[INFO] fetching crate puteketeke 0.0.5... [INFO] testing puteketeke-0.0.5 against master#8c0ec05f7dc9ef185eea9f36c5638e74e43935bf for pr-130285 [INFO] extracting crate puteketeke 0.0.5 into /workspace/builds/worker-4-tc1/source [INFO] validating manifest of crates.io crate puteketeke 0.0.5 on toolchain 8c0ec05f7dc9ef185eea9f36c5638e74e43935bf [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+8c0ec05f7dc9ef185eea9f36c5638e74e43935bf" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate puteketeke 0.0.5 [INFO] finished tweaking crates.io crate puteketeke 0.0.5 [INFO] tweaked toml for crates.io crate puteketeke 0.0.5 written to /workspace/builds/worker-4-tc1/source/Cargo.toml [INFO] crate crates.io crate puteketeke 0.0.5 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" "+8c0ec05f7dc9ef185eea9f36c5638e74e43935bf" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded async-io v2.2.0 [INFO] [stderr] Downloaded polling v3.3.0 [INFO] [stderr] Downloaded async-lock v3.1.1 [INFO] [stderr] Downloaded async-executor v1.7.2 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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:96c0aa5895ecf336b730ef27bcdde6e21027415df19888aad3cf1448e5d65d90" "/opt/rustwide/cargo-home/bin/cargo" "+8c0ec05f7dc9ef185eea9f36c5638e74e43935bf" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] a5b45812901870d82d3542256226ec66685810e1985cb2d2a0e4c87f0bf8fd3f [INFO] running `Command { std: "docker" "start" "-a" "a5b45812901870d82d3542256226ec66685810e1985cb2d2a0e4c87f0bf8fd3f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "a5b45812901870d82d3542256226ec66685810e1985cb2d2a0e4c87f0bf8fd3f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "a5b45812901870d82d3542256226ec66685810e1985cb2d2a0e4c87f0bf8fd3f", kill_on_drop: false }` [INFO] [stdout] a5b45812901870d82d3542256226ec66685810e1985cb2d2a0e4c87f0bf8fd3f [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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:96c0aa5895ecf336b730ef27bcdde6e21027415df19888aad3cf1448e5d65d90" "/opt/rustwide/cargo-home/bin/cargo" "+8c0ec05f7dc9ef185eea9f36c5638e74e43935bf" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 4438f47dcc0fa221228c073696796be76602bc3c96a4e08a029f5d5cd462f9e1 [INFO] running `Command { std: "docker" "start" "-a" "4438f47dcc0fa221228c073696796be76602bc3c96a4e08a029f5d5cd462f9e1", kill_on_drop: false }` [INFO] [stderr] Compiling crossbeam-utils v0.8.16 [INFO] [stderr] Compiling libc v0.2.150 [INFO] [stderr] Compiling parking v2.2.0 [INFO] [stderr] Compiling memoffset v0.9.0 [INFO] [stderr] Compiling futures-core v0.3.29 [INFO] [stderr] Compiling slab v0.4.9 [INFO] [stderr] Compiling crossbeam-epoch v0.9.15 [INFO] [stderr] Compiling syn v2.0.39 [INFO] [stderr] Compiling rustix v0.38.25 [INFO] [stderr] Compiling tracing-core v0.1.32 [INFO] [stderr] Compiling lock_api v0.4.11 [INFO] [stderr] Compiling crossbeam-queue v0.3.8 [INFO] [stderr] Compiling linux-raw-sys v0.4.11 [INFO] [stderr] Compiling parking_lot_core v0.9.9 [INFO] [stderr] Compiling futures-io v0.3.29 [INFO] [stderr] Compiling fastrand v2.0.1 [INFO] [stderr] Compiling concurrent-queue v2.3.0 [INFO] [stderr] Compiling bitflags v2.4.1 [INFO] [stderr] Compiling event-listener v3.1.0 [INFO] [stderr] Compiling futures-lite v2.0.1 [INFO] [stderr] Compiling smallvec v1.11.2 [INFO] [stderr] Compiling event-listener-strategy v0.3.0 [INFO] [stderr] Compiling async-lock v3.1.1 [INFO] [stderr] Compiling num_cpus v1.16.0 [INFO] [stderr] Compiling crossbeam-deque v0.8.3 [INFO] [stderr] Compiling crossbeam-channel v0.5.8 [INFO] [stderr] Compiling async-task v4.5.0 [INFO] [stderr] Compiling waker-fn v1.1.1 [INFO] [stderr] Compiling parking_lot v0.12.1 [INFO] [stderr] Compiling async-executor v1.7.2 [INFO] [stderr] Compiling threadpool v1.8.1 [INFO] [stderr] Compiling crossbeam v0.8.2 [INFO] [stderr] Compiling tracing-attributes v0.1.27 [INFO] [stderr] Compiling tracing v0.1.40 [INFO] [stderr] Compiling polling v3.3.0 [INFO] [stderr] Compiling async-io v2.2.0 [INFO] [stderr] Compiling puteketeke v0.0.5 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 5.06s [INFO] running `Command { std: "docker" "inspect" "4438f47dcc0fa221228c073696796be76602bc3c96a4e08a029f5d5cd462f9e1", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "4438f47dcc0fa221228c073696796be76602bc3c96a4e08a029f5d5cd462f9e1", kill_on_drop: false }` [INFO] [stdout] 4438f47dcc0fa221228c073696796be76602bc3c96a4e08a029f5d5cd462f9e1 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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:96c0aa5895ecf336b730ef27bcdde6e21027415df19888aad3cf1448e5d65d90" "/opt/rustwide/cargo-home/bin/cargo" "+8c0ec05f7dc9ef185eea9f36c5638e74e43935bf" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] f80f61fda78f9fbe2dfb08b5eeb5db80e78c8b76b8411196d61ad715be61a7c7 [INFO] running `Command { std: "docker" "start" "-a" "f80f61fda78f9fbe2dfb08b5eeb5db80e78c8b76b8411196d61ad715be61a7c7", kill_on_drop: false }` [INFO] [stderr] Compiling futures-core v0.3.29 [INFO] [stderr] Compiling libc v0.2.150 [INFO] [stderr] Compiling log v0.4.20 [INFO] [stderr] Compiling tracing-core v0.1.32 [INFO] [stderr] Compiling futures-channel v0.3.29 [INFO] [stderr] Compiling syn v2.0.39 [INFO] [stderr] Compiling futures-task v0.3.29 [INFO] [stderr] Compiling futures-sink v0.3.29 [INFO] [stderr] Compiling fnv v1.0.7 [INFO] [stderr] Compiling socket2 v0.5.5 [INFO] [stderr] Compiling mio v0.8.9 [INFO] [stderr] Compiling http v0.2.11 [INFO] [stderr] Compiling futures-util v0.3.29 [INFO] [stderr] Compiling typenum v1.17.0 [INFO] [stderr] Compiling tokio v1.34.0 [INFO] [stderr] Compiling generic-array v0.14.7 [INFO] [stderr] Compiling cc v1.0.83 [INFO] [stderr] Compiling httparse v1.8.0 [INFO] [stderr] Compiling getrandom v0.2.11 [INFO] [stderr] Compiling unicase v2.7.0 [INFO] [stderr] Compiling foreign-types-shared v0.1.1 [INFO] [stderr] Compiling equivalent v1.0.1 [INFO] [stderr] Compiling serde v1.0.192 [INFO] [stderr] Compiling hashbrown v0.14.2 [INFO] [stderr] Compiling openssl v0.10.59 [INFO] [stderr] Compiling foreign-types v0.3.2 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling try-lock v0.2.4 [INFO] [stderr] Compiling httpdate v1.0.3 [INFO] [stderr] Compiling mime v0.3.17 [INFO] [stderr] Compiling native-tls v0.2.11 [INFO] [stderr] Compiling openssl-sys v0.9.95 [INFO] [stderr] Compiling block-buffer v0.10.4 [INFO] [stderr] Compiling crypto-common v0.1.6 [INFO] [stderr] Compiling indexmap v2.1.0 [INFO] [stderr] Compiling cpufeatures v0.2.11 [INFO] [stderr] Compiling thiserror v1.0.50 [INFO] [stderr] Compiling digest v0.10.7 [INFO] [stderr] Compiling ppv-lite86 v0.2.17 [INFO] [stderr] Compiling want v0.3.1 [INFO] [stderr] Compiling sha1 v0.10.6 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling http-body v0.4.5 [INFO] [stderr] Compiling socket2 v0.4.10 [INFO] [stderr] Compiling tower-service v0.3.2 [INFO] [stderr] Compiling base64 v0.21.5 [INFO] [stderr] Compiling ryu v1.0.15 [INFO] [stderr] Compiling openssl-probe v0.1.5 [INFO] [stderr] Compiling futures-lite v2.0.1 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling mime_guess v2.0.4 [INFO] [stderr] Compiling backtrace v0.3.69 [INFO] [stderr] Compiling multer v2.1.0 [INFO] [stderr] Compiling encoding_rs v0.8.33 [INFO] [stderr] Compiling regex-syntax v0.8.2 [INFO] [stderr] Compiling data-encoding v2.4.0 [INFO] [stderr] Compiling regex-syntax v0.6.29 [INFO] [stderr] Compiling utf-8 v0.7.6 [INFO] [stderr] Compiling serde_json v1.0.108 [INFO] [stderr] Compiling byteorder v1.5.0 [INFO] [stderr] Compiling gimli v0.28.0 [INFO] [stderr] Compiling tracing-attributes v0.1.27 [INFO] [stderr] Compiling futures-macro v0.3.29 [INFO] [stderr] Compiling openssl-macros v0.1.1 [INFO] [stderr] Compiling tracing v0.1.40 [INFO] [stderr] Compiling thiserror-impl v1.0.50 [INFO] [stderr] Compiling tokio-util v0.7.10 [INFO] [stderr] Compiling adler v1.0.2 [INFO] [stderr] Compiling miniz_oxide v0.7.1 [INFO] [stderr] Compiling addr2line v0.21.0 [INFO] [stderr] Compiling polling v3.3.0 [INFO] [stderr] Compiling regex-automata v0.1.10 [INFO] [stderr] Compiling regex-automata v0.4.3 [INFO] [stderr] Compiling serde_urlencoded v0.7.1 [INFO] [stderr] Compiling pin-project-internal v1.1.3 [INFO] [stderr] Compiling tungstenite v0.20.1 [INFO] [stderr] Compiling headers-core v0.2.0 [INFO] [stderr] Compiling object v0.32.1 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling rustc-demangle v0.1.23 [INFO] [stderr] Compiling overload v0.1.1 [INFO] [stderr] Compiling spin v0.9.8 [INFO] [stderr] Compiling async-io v2.2.0 [INFO] [stderr] Compiling h2 v0.3.22 [INFO] [stderr] Compiling pin-project v1.1.3 [INFO] [stderr] Compiling regex v1.10.2 [INFO] [stderr] Compiling tokio-native-tls v0.3.1 [INFO] [stderr] Compiling tokio-tungstenite v0.20.1 [INFO] [stderr] Compiling futures-executor v0.3.29 [INFO] [stderr] Compiling nu-ansi-term v0.46.0 [INFO] [stderr] Compiling matchers v0.1.0 [INFO] [stderr] Compiling sharded-slab v0.1.7 [INFO] [stderr] Compiling async-executor v1.7.2 [INFO] [stderr] Compiling headers v0.3.9 [INFO] [stderr] Compiling tokio-stream v0.1.14 [INFO] [stderr] Compiling rustls-pemfile v1.0.4 [INFO] [stderr] Compiling tracing-log v0.2.0 [INFO] [stderr] Compiling thread_local v1.1.7 [INFO] [stderr] Compiling scoped-tls v1.0.1 [INFO] [stderr] Compiling termcolor v1.4.0 [INFO] [stderr] Compiling ipnet v2.9.0 [INFO] [stderr] Compiling tracing-subscriber v0.3.18 [INFO] [stderr] Compiling futures v0.3.29 [INFO] [stderr] Compiling async-compat v0.2.3 [INFO] [stderr] Compiling puteketeke v0.0.5 (/opt/rustwide/workdir) [INFO] [stderr] Compiling color-backtrace v0.6.1 [INFO] [stderr] Compiling hyper v0.14.27 [INFO] [stderr] Compiling hyper-tls v0.5.0 [INFO] [stderr] Compiling warp v0.3.6 [INFO] [stderr] Compiling reqwest v0.11.22 [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 26.77s [INFO] running `Command { std: "docker" "inspect" "f80f61fda78f9fbe2dfb08b5eeb5db80e78c8b76b8411196d61ad715be61a7c7", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f80f61fda78f9fbe2dfb08b5eeb5db80e78c8b76b8411196d61ad715be61a7c7", kill_on_drop: false }` [INFO] [stdout] f80f61fda78f9fbe2dfb08b5eeb5db80e78c8b76b8411196d61ad715be61a7c7 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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:96c0aa5895ecf336b730ef27bcdde6e21027415df19888aad3cf1448e5d65d90" "/opt/rustwide/cargo-home/bin/cargo" "+8c0ec05f7dc9ef185eea9f36c5638e74e43935bf" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] d464a90c86814b71356e42a90596bbaaf8d24db2dc3baa2330bfb1ac5ee314e2 [INFO] running `Command { std: "docker" "start" "-a" "d464a90c86814b71356e42a90596bbaaf8d24db2dc3baa2330bfb1ac5ee314e2", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.13s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/puteketeke-f8304ff01882da9d) [INFO] [stdout] [INFO] [stdout] running 8 tests [INFO] [stdout] test tests::remove_root_worker ... ok [INFO] [stdout] test tests::test_executor ... ok [INFO] [stdout] test tests::worker ... ok [INFO] [stdout] test tests::reqwest ... ok [INFO] [stdout] test tests::test_timer ... ok [INFO] [stdout] test tests::race ... ok [INFO] [stderr] The application panicked (crashed). [INFO] [stderr] Message: assertion failed: delta.subsec_millis() < 3 [INFO] [stderr] Location: src/lib.rs:1054 [INFO] [stderr] [INFO] [stderr] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering. [INFO] [stderr] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stderr] ⋮ 8 frames hidden ⋮ [INFO] [stderr] 9: core::panicking::panic::h690a73cd45bac749 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/panicking.rs:148 [INFO] [stderr] 10: puteketeke::tests::stress_test_timer_workers::{{closure}}::heb93bd5b59fc608d [INFO] [stderr] at /opt/rustwide/workdir/src/lib.rs:1054 [INFO] [stderr] 1052 │ println!("delta: {delta:?}"); [INFO] [stderr] 1053 │ [INFO] [stderr] 1054 > assert!(delta.subsec_millis() < 3); [INFO] [stderr] 1055 │ inner_worker.destroy(); [INFO] [stderr] 1056 │ }) [INFO] [stderr] 11: async_executor::Executor::spawn::{{closure}}::h5eed20ae19ab3f44 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/async-executor-1.7.2/src/lib.rs:158 [INFO] [stderr] 156 │ let future = async move { [INFO] [stderr] 157 │ let _guard = CallOnDrop(move || drop(state.active.lock().unwrap().try_remove(index))); [INFO] [stderr] 158 > future.await [INFO] [stderr] 159 │ }; [INFO] [stderr] 160 │ [INFO] [stderr] 12: async_task::raw::RawTask::run::ha42b926127c2cc44 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/async-task-4.5.0/src/raw.rs:568 [INFO] [stderr] 566 │ } [INFO] [stderr] 567 │ } else { [INFO] [stderr] 568 > ::poll(Pin::new_unchecked(&mut *raw.future), cx).map(Ok) [INFO] [stderr] 569 │ } [INFO] [stderr] 570 │ }; [INFO] [stderr] 13: async_task::runnable::Runnable::run::h5f8e6aff99b1f1c3 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/async-task-4.5.0/src/runnable.rs:784 [INFO] [stderr] 782 │ mem::forget(self); [INFO] [stderr] 783 │ [INFO] [stderr] 784 > unsafe { ((*header).vtable.run)(ptr) } [INFO] [stderr] 785 │ } [INFO] [stderr] 786 │ [INFO] [stderr] 14: async_executor::Executor::try_tick::h0d4f827270228bf1 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/async-executor-1.7.2/src/lib.rs:195 [INFO] [stderr] 193 │ [INFO] [stderr] 194 │ // Run the task. [INFO] [stderr] 195 > runnable.run(); [INFO] [stderr] 196 │ true [INFO] [stderr] 197 │ } [INFO] [stderr] 15: puteketeke::AsyncWorker::try_tick::hee006af97a2ff821 [INFO] [stderr] at /opt/rustwide/workdir/src/lib.rs:755 [INFO] [stderr] 753 │ fn try_tick(&self) -> bool { [INFO] [stderr] 754 │ tracing::debug!(target: "async", "AsyncWorker {} try_tick", self.id); [INFO] [stderr] 755 > self.ex.try_tick() [INFO] [stderr] 756 │ } [INFO] [stderr] 757 │ } [INFO] [stderr] 16: puteketeke::UberExecutor::start::{{closure}}::he7470bd778d9a88c [INFO] [stderr] at /opt/rustwide/workdir/src/lib.rs:671 [INFO] [stderr] 669 │ loop { [INFO] [stderr] 670 │ tracing::trace!(target: "async", "tick {:?}", thread::current().id()); [INFO] [stderr] 671 > match worker.try_tick() { [INFO] [stderr] 672 │ true => { [INFO] [stderr] 673 │ tracing::trace!(target: "async", "tock {:?}", thread::current().id()); [INFO] [stderr] 17: ::call_box::hff26eaa7af925b6c [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/threadpool-1.8.1/src/lib.rs:95 [INFO] [stderr] 93 │ impl FnBox for F { [INFO] [stderr] 94 │ fn call_box(self: Box) { [INFO] [stderr] 95 > (*self)() [INFO] [stderr] 96 │ } [INFO] [stderr] 97 │ } [INFO] [stderr] 18: threadpool::spawn_in_pool::{{closure}}::h04fcbe41d15a286f [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/threadpool-1.8.1/src/lib.rs:769 [INFO] [stderr] 767 │ shared_data.queued_count.fetch_sub(1, Ordering::SeqCst); [INFO] [stderr] 768 │ [INFO] [stderr] 769 > job.call_box(); [INFO] [stderr] 770 │ [INFO] [stderr] 771 │ shared_data.active_count.fetch_sub(1, Ordering::SeqCst); [INFO] [stderr] 19: std::sys::backtrace::__rust_begin_short_backtrace::he578c30e1998ea8d [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/sys/backtrace.rs:154 [INFO] [stderr] 20: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::he3c341b9dd562511 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/thread/mod.rs:522 [INFO] [stderr] 21: as core::ops::function::FnOnce<()>>::call_once::hdc03c339af64e011 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/panic/unwind_safe.rs:272 [INFO] [stderr] 22: std::panicking::try::do_call::ha59bae684baba620 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/panicking.rs:557 [INFO] [stderr] 23: __rust_try [INFO] [stderr] at [INFO] [stderr] 24: std::panicking::try::h5374678ef39d89c5 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/panicking.rs:520 [INFO] [stderr] 25: std::panic::catch_unwind::hcac8fa0d9f20af79 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/panic.rs:345 [INFO] [stderr] 26: std::thread::Builder::spawn_unchecked_::{{closure}}::h8e4a4c0dddfda246 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/thread/mod.rs:521 [INFO] [stderr] 27: core::ops::function::FnOnce::call_once{{vtable.shim}}::h1a35013b73f92a2d [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/ops/function.rs:250 [INFO] [stderr] 28: as core::ops::function::FnOnce>::call_once::hed0705102829bfb8 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/alloc/src/boxed.rs:2229 [INFO] [stderr] 29: as core::ops::function::FnOnce>::call_once::h88dbb0360840e1a3 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/alloc/src/boxed.rs:2229 [INFO] [stderr] 30: std::sys::pal::unix::thread::Thread::new::thread_start::hed3d760d9bfb1ad1 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/sys/pal/unix/thread.rs:105 [INFO] [stderr] 31: clone [INFO] [stderr] at [INFO] [stderr] The application panicked (crashed). [INFO] [stderr] Message: assertion failed: delta.subsec_millis() < 3 [INFO] [stderr] Location: src/lib.rs:1054 [INFO] [stderr] [INFO] [stderr] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering. [INFO] [stderr] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stderr] ⋮ 8 frames hidden ⋮ [INFO] [stderr] 9: core::panicking::panic::h690a73cd45bac749 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/panicking.rs:148 [INFO] [stderr] 10: puteketeke::tests::stress_test_timer_workers::{{closure}}::heb93bd5b59fc608d [INFO] [stderr] at /opt/rustwide/workdir/src/lib.rs:1054 [INFO] [stderr] 1052 │ println!("delta: {delta:?}"); [INFO] [stderr] 1053 │ [INFO] [stderr] 1054 > assert!(delta.subsec_millis() < 3); [INFO] [stderr] 1055 │ inner_worker.destroy(); [INFO] [stderr] 1056 │ }) [INFO] [stderr] 11: async_executor::Executor::spawn::{{closure}}::h5eed20ae19ab3f44 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/async-executor-1.7.2/src/lib.rs:158 [INFO] [stderr] 156 │ let future = async move { [INFO] [stderr] 157 │ let _guard = CallOnDrop(move || drop(state.active.lock().unwrap().try_remove(index))); [INFO] [stderr] 158 > future.await [INFO] [stderr] 159 │ }; [INFO] [stderr] 160 │ [INFO] [stderr] 12: async_task::raw::RawTask::run::ha42b926127c2cc44 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/async-task-4.5.0/src/raw.rs:568 [INFO] [stderr] 566 │ } [INFO] [stderr] 567 │ } else { [INFO] [stderr] 568 > ::poll(Pin::new_unchecked(&mut *raw.future), cx).map(Ok) [INFO] [stderr] 569 │ } [INFO] [stderr] 570 │ }; [INFO] [stderr] 13: async_task::runnable::Runnable::run::h5f8e6aff99b1f1c3 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/async-task-4.5.0/src/runnable.rs:784 [INFO] [stderr] 782 │ mem::forget(self); [INFO] [stderr] 783 │ [INFO] [stderr] 784 > unsafe { ((*header).vtable.run)(ptr) } [INFO] [stderr] 785 │ } [INFO] [stderr] 786 │ [INFO] [stderr] 14: async_executor::Executor::try_tick::h0d4f827270228bf1 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/async-executor-1.7.2/src/lib.rs:195 [INFO] [stderr] 193 │ [INFO] [stderr] 194 │ // Run the task. [INFO] [stderr] 195 > runnable.run(); [INFO] [stderr] 196 │ true [INFO] [stderr] 197 │ } [INFO] [stderr] 15: puteketeke::AsyncWorker::try_tick::hee006af97a2ff821 [INFO] [stderr] at /opt/rustwide/workdir/src/lib.rs:755 [INFO] [stderr] 753 │ fn try_tick(&self) -> bool { [INFO] [stderr] 754 │ tracing::debug!(target: "async", "AsyncWorker {} try_tick", self.id); [INFO] [stderr] 755 > self.ex.try_tick() [INFO] [stderr] 756 │ } [INFO] [stderr] 757 │ } [INFO] [stderr] 16: puteketeke::UberExecutor::start::{{closure}}::he7470bd778d9a88c [INFO] [stderr] at /opt/rustwide/workdir/src/lib.rs:671 [INFO] [stderr] 669 │ loop { [INFO] [stderr] 670 │ tracing::trace!(target: "async", "tick {:?}", thread::current().id()); [INFO] [stderr] 671 > match worker.try_tick() { [INFO] [stderr] 672 │ true => { [INFO] [stderr] 673 │ tracing::trace!(target: "async", "tock {:?}", thread::current().id()); [INFO] [stderr] 17: ::call_box::hff26eaa7af925b6c [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/threadpool-1.8.1/src/lib.rs:95 [INFO] [stderr] 93 │ impl FnBox for F { [INFO] [stderr] 94 │ fn call_box(self: Box) { [INFO] [stderr] 95 > (*self)() [INFO] [stderr] 96 │ } [INFO] [stderr] 97 │ } [INFO] [stderr] 18: threadpool::spawn_in_pool::{{closure}}::h04fcbe41d15a286f [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/threadpool-1.8.1/src/lib.rs:769 [INFO] [stderr] 767 │ shared_data.queued_count.fetch_sub(1, Ordering::SeqCst); [INFO] [stderr] 768 │ [INFO] [stderr] 769 > job.call_box(); [INFO] [stderr] 770 │ [INFO] [stderr] 771 │ shared_data.active_count.fetch_sub(1, Ordering::SeqCst); [INFO] [stderr] 19: std::sys::backtrace::__rust_begin_short_backtrace::he578c30e1998ea8d [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/sys/backtrace.rs:154 [INFO] [stderr] 20: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::he3c341b9dd562511 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/thread/mod.rs:522 [INFO] [stderr] 21: as core::ops::function::FnOnce<()>>::call_once::hdc03c339af64e011 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/panic/unwind_safe.rs:272 [INFO] [stderr] 22: std::panicking::try::do_call::ha59bae684baba620 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/panicking.rs:557 [INFO] [stderr] 23: __rust_try [INFO] [stderr] at [INFO] [stderr] 24: std::panicking::try::h5374678ef39d89c5 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/panicking.rs:520 [INFO] [stderr] 25: std::panic::catch_unwind::hcac8fa0d9f20af79 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/panic.rs:345 [INFO] [stderr] 26: std::thread::Builder::spawn_unchecked_::{{closure}}::h8e4a4c0dddfda246 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/thread/mod.rs:521 [INFO] [stderr] 27: core::ops::function::FnOnce::call_once{{vtable.shim}}::h1a35013b73f92a2d [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/ops/function.rs:250 [INFO] [stderr] 28: as core::ops::function::FnOnce>::call_once::hed0705102829bfb8 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/alloc/src/boxed.rs:2229 [INFO] [stderr] 29: as core::ops::function::FnOnce>::call_once::h88dbb0360840e1a3 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/alloc/src/boxed.rs:2229 [INFO] [stderr] 30: std::sys::pal::unix::thread::Thread::new::thread_start::hed3d760d9bfb1ad1 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/sys/pal/unix/thread.rs:105 [INFO] [stderr] 31: clone [INFO] [stderr] at [INFO] [stderr] The application panicked (crashed). [INFO] [stderr] Message: task has failed [INFO] [stderr] Location: /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/async-task-4.5.0/src/task.rs:452 [INFO] [stderr] [INFO] [stderr] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering. [INFO] [stderr] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stderr] ⋮ 8 frames hidden ⋮ [INFO] [stderr] 9: core::panicking::panic_display::haabd6b6701022131 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/panicking.rs:264 [INFO] [stderr] 10: core::option::expect_failed::h7adb6a1f0ac66dec [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/option.rs:2024 [INFO] [stderr] 11: core::option::Option::expect::hcbd48d64d29fdeed [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/option.rs:928 [INFO] [stderr] 12: as core::future::future::Future>::poll::h8c510776c9d37835 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/async-task-4.5.0/src/task.rs:452 [INFO] [stderr] 450 │ fn poll(mut self: Pin<&mut Self>, cx: &mut Context<'_>) -> Poll { [INFO] [stderr] 451 │ match self.poll_task(cx) { [INFO] [stderr] 452 > Poll::Ready(t) => Poll::Ready(t.expect("task has failed")), [INFO] [stderr] 453 │ Poll::Pending => Poll::Pending, [INFO] [stderr] 454 │ } [INFO] [stderr] 13: puteketeke::AsyncTask::new::{{closure}}::hac8b2bbdf4b19147 [INFO] [stderr] at /opt/rustwide/workdir/src/lib.rs:802 [INFO] [stderr] 800 │ let future = async move { [INFO] [stderr] 801 │ tracing::trace!(target: "async", "AsyncTask spawning inner task {id} on worker {}", inner.id); [INFO] [stderr] 802 > let result = inner.spawn(future).await; [INFO] [stderr] 803 │ tracing::trace!(target: "async", "AsyncTask finished inner task {id} on worker {}", inner.id); [INFO] [stderr] 804 │ result [INFO] [stderr] 14: async_executor::Executor::spawn::{{closure}}::h5c8c738dc673c093 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/async-executor-1.7.2/src/lib.rs:158 [INFO] [stderr] 156 │ let future = async move { [INFO] [stderr] 157 │ let _guard = CallOnDrop(move || drop(state.active.lock().unwrap().try_remove(index))); [INFO] [stderr] 158 > future.await [INFO] [stderr] 159 │ }; [INFO] [stderr] 160 │ [INFO] [stderr] 15: async_task::raw::RawTask::run::hebecbf28e9128a14 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/async-task-4.5.0/src/raw.rs:568 [INFO] [stderr] 566 │ } [INFO] [stderr] 567 │ } else { [INFO] [stderr] 568 > ::poll(Pin::new_unchecked(&mut *raw.future), cx).map(Ok) [INFO] [stderr] 569 │ } [INFO] [stderr] 570 │ }; [INFO] [stderr] 16: async_task::runnable::Runnable::run::h5f8e6aff99b1f1c3 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/async-task-4.5.0/src/runnable.rs:784 [INFO] [stderr] 782 │ mem::forget(self); [INFO] [stderr] 783 │ [INFO] [stderr] 784 > unsafe { ((*header).vtable.run)(ptr) } [INFO] [stderr] 785 │ } [INFO] [stderr] 786 │ [INFO] [stderr] 17: async_executor::Executor::run::{{closure}}::{{closure}}::heed401a1bfa43cca [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/async-executor-1.7.2/src/lib.rs:250 [INFO] [stderr] 248 │ for _ in 0..200 { [INFO] [stderr] 249 │ let runnable = runner.runnable(&mut rng).await; [INFO] [stderr] 250 > runnable.run(); [INFO] [stderr] 251 │ } [INFO] [stderr] 252 │ future::yield_now().await; [INFO] [stderr] 18: as core::future::future::Future>::poll::h15fe0b7529a52a8b [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/futures-lite-2.0.1/src/future.rs:449 [INFO] [stderr] 447 │ return Poll::Ready(t); [INFO] [stderr] 448 │ } [INFO] [stderr] 449 > if let Poll::Ready(t) = this.future2.poll(cx) { [INFO] [stderr] 450 │ return Poll::Ready(t); [INFO] [stderr] 451 │ } [INFO] [stderr] 19: async_executor::Executor::run::{{closure}}::h69b148b058b1eaff [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/async-executor-1.7.2/src/lib.rs:257 [INFO] [stderr] 255 │ [INFO] [stderr] 256 │ // Run `future` and `run_forever` concurrently until `future` completes. [INFO] [stderr] 257 > future.or(run_forever).await [INFO] [stderr] 258 │ } [INFO] [stderr] 259 │ [INFO] [stderr] 20: puteketeke::AsyncWorker::resolve_task::{{closure}}::hdb444f7b4954655c [INFO] [stderr] at /opt/rustwide/workdir/src/lib.rs:748 [INFO] [stderr] 746 │ T: std::fmt::Debug, [INFO] [stderr] 747 │ { [INFO] [stderr] 748 > let result = self.ex.run(task).await; [INFO] [stderr] 749 │ tracing::debug!(target: "async", "AsyncWorker {} resolve_task: RESOLVED: {result:?}", self.id); [INFO] [stderr] 750 │ result [INFO] [stderr] 21: futures_lite::future::block_on::{{closure}}::hd0d811573f7a3d06 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/futures-lite-2.0.1/src/future.rs:99 [INFO] [stderr] 97 │ // Keep polling until the future is ready. [INFO] [stderr] 98 │ loop { [INFO] [stderr] 99 > match future.as_mut().poll(cx) { [INFO] [stderr] 100 │ Poll::Ready(output) => return output, [INFO] [stderr] 101 │ Poll::Pending => parker.park(), [INFO] [stderr] 22: std::thread::local::LocalKey::try_with::h5bcf92ff4a217d23 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/thread/local.rs:283 [INFO] [stderr] 23: std::thread::local::LocalKey::with::h1aba51917754f4cf [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/thread/local.rs:260 [INFO] [stderr] 24: futures_lite::future::block_on::h843183121e2245e1 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/futures-lite-2.0.1/src/future.rs:78 [INFO] [stderr] 76 │ } [INFO] [stderr] 77 │ [INFO] [stderr] 78 > CACHE.with(|cache| { [INFO] [stderr] 79 │ // Try grabbing the cached parker and waker. [INFO] [stderr] 80 │ let tmp_cached; [INFO] [stderr] 25: as core::future::future::Future>::poll::h839128f1a8718ae0 [INFO] [stderr] at /opt/rustwide/workdir/src/lib.rs:847 [INFO] [stderr] 845 │ tracing::trace!(target: "async", "AsyncTask::poll: ready: {}", this.id,); [INFO] [stderr] 846 │ let task = this.inner.take().unwrap(); [INFO] [stderr] 847 > Poll::Ready(future::block_on(this.worker.resolve_task(task))) [INFO] [stderr] 848 │ } else { [INFO] [stderr] 849 │ tracing::trace!(target: "async", "AsyncTask::poll: pending: {}", this.id,); [INFO] [stderr] 26: as core::future::future::Future>::poll::he982823301ed7741 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/futures-util-0.3.29/src/stream/futures_ordered.rs:55 [INFO] [stderr] 53 │ fn poll(self: Pin<&mut Self>, cx: &mut Context<'_>) -> Poll { [INFO] [stderr] 54 │ let index = self.index; [INFO] [stderr] 55 > self.project().data.poll(cx).map(|output| OrderWrapper { data: output, index }) [INFO] [stderr] 56 │ } [INFO] [stderr] 57 │ } [INFO] [stderr] 27: as futures_core::stream::Stream>::poll_next::h747334ee88d95d64 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/futures-util-0.3.29/src/stream/futures_unordered/mod.rs:518 [INFO] [stderr] 516 │ let future = unsafe { Pin::new_unchecked(future) }; [INFO] [stderr] 517 │ [INFO] [stderr] 518 > future.poll(&mut cx) [INFO] [stderr] 519 │ }; [INFO] [stderr] 520 │ polled += 1; [INFO] [stderr] 28: futures_util::stream::stream::StreamExt::poll_next_unpin::hed6da5f0cecd73c7 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/futures-util-0.3.29/src/stream/stream/mod.rs:1638 [INFO] [stderr] 1636 │ Self: Unpin, [INFO] [stderr] 1637 │ { [INFO] [stderr] 1638 > Pin::new(self).poll_next(cx) [INFO] [stderr] 1639 │ } [INFO] [stderr] 1640 │ [INFO] [stderr] 29: as futures_core::stream::Stream>::poll_next::hd0286ec8228c77f0 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/futures-util-0.3.29/src/stream/futures_ordered.rs:194 [INFO] [stderr] 192 │ [INFO] [stderr] 193 │ loop { [INFO] [stderr] 194 > match ready!(this.in_progress_queue.poll_next_unpin(cx)) { [INFO] [stderr] 195 │ Some(output) => { [INFO] [stderr] 196 │ if output.index == this.next_outgoing_index { [INFO] [stderr] 30: as core::future::future::Future>::poll::h0b40d33e256a1d08 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/futures-util-0.3.29/src/stream/stream/collect.rs:50 [INFO] [stderr] 48 │ let mut this = self.as_mut().project(); [INFO] [stderr] 49 │ loop { [INFO] [stderr] 50 > match ready!(this.stream.as_mut().poll_next(cx)) { [INFO] [stderr] 51 │ Some(e) => this.collection.extend(Some(e)), [INFO] [stderr] 52 │ None => return Poll::Ready(self.finish()), [INFO] [stderr] 31: as core::future::future::Future>::poll::h81985b46071dfbba [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/futures-util-0.3.29/src/future/join_all.rs:157 [INFO] [stderr] 155 │ } [INFO] [stderr] 156 │ #[cfg(not(futures_no_atomic_cas))] [INFO] [stderr] 157 > JoinAllKind::Big { fut } => Pin::new(fut).poll(cx), [INFO] [stderr] 158 │ } [INFO] [stderr] 159 │ } [INFO] [stderr] 32: futures_lite::future::block_on::{{closure}}::h2162a4a5568d7ab0 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/futures-lite-2.0.1/src/future.rs:99 [INFO] [stderr] 97 │ // Keep polling until the future is ready. [INFO] [stderr] 98 │ loop { [INFO] [stderr] 99 > match future.as_mut().poll(cx) { [INFO] [stderr] 100 │ Poll::Ready(output) => return output, [INFO] [stderr] 101 │ Poll::Pending => parker.park(), [INFO] [stderr] 33: std::thread::local::LocalKey::try_with::h70b9700d48a1fd6f [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/thread/local.rs:283 [INFO] [stderr] 34: std::thread::local::LocalKey::with::he543483fa39bee41 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/thread/local.rs:260 [INFO] [stderr] 35: futures_lite::future::block_on::h1f9294e90ac354b6 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/futures-lite-2.0.1/src/future.rs:78 [INFO] [stderr] 76 │ } [INFO] [stderr] 77 │ [INFO] [stderr] 78 > CACHE.with(|cache| { [INFO] [stderr] 79 │ // Try grabbing the cached parker and waker. [INFO] [stderr] 80 │ let tmp_cached; [INFO] [stderr] 36: puteketeke::tests::stress_test_timer_workers::he7f2f9a229fc8f5d [INFO] [stderr] at /opt/rustwide/workdir/src/lib.rs:1069 [INFO] [stderr] 1067 │ } [INFO] [stderr] 1068 │ [INFO] [stderr] 1069 > future::block_on(futures::future::join_all(tasks)); [INFO] [stderr] 1070 │ let duration = now.elapsed(); [INFO] [stderr] 1071 │ println!("duration: {duration:?}, sum: {sum}"); [INFO] [stderr] 37: puteketeke::tests::stress_test_timer_workers::{{closure}}::h99b205e79237d0d8 [INFO] [stderr] at /opt/rustwide/workdir/src/lib.rs:1025 [INFO] [stderr] 1023 │ [INFO] [stderr] 1024 │ #[test] [INFO] [stderr] 1025 > fn stress_test_timer_workers() { [INFO] [stderr] 1026 │ start_logger(); [INFO] [stderr] 1027 │ color_backtrace::install(); [INFO] [stderr] 38: core::ops::function::FnOnce::call_once::h524def3d60bd3322 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/ops/function.rs:250 [INFO] [stderr] 39: core::ops::function::FnOnce::call_once::he9dcba90b82d8683 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/ops/function.rs:250 [INFO] [stderr] 40: test::__rust_begin_short_backtrace::hd3c7d260f12c8c4c [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/test/src/lib.rs:620 [INFO] [stderr] 41: test::run_test_in_process::{{closure}}::h2a83a0a65ed3c222 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/test/src/lib.rs:643 [INFO] [stderr] 42: as core::ops::function::FnOnce<()>>::call_once::h86af6a43b3d339fd [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/panic/unwind_safe.rs:272 [INFO] [stderr] 43: std::panicking::try::do_call::h06bb3a6ef41e33d4 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/panicking.rs:557 [INFO] [stderr] 44: std::panicking::try::hbe4ce21e81168e19 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/panicking.rs:520 [INFO] [stderr] 45: std::panic::catch_unwind::h9216a5b93914ba30 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/panic.rs:345 [INFO] [stderr] 46: test::run_test_in_process::hd5f710dbb42ac3ac [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/test/src/lib.rs:643 [INFO] [stderr] 47: test::run_test::{{closure}}::ha3c5aa83f018eb90 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/test/src/lib.rs:564 [INFO] [stderr] 48: test::run_test::{{closure}}::h106c33754fb7ad93 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/test/src/lib.rs:594 [INFO] [stderr] 49: std::sys::backtrace::__rust_begin_short_backtrace::h72b607f200d489e1 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/sys/backtrace.rs:154 [INFO] [stderr] 50: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h4c13dee51575b1d5 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/thread/mod.rs:522 [INFO] [stderr] 51: as core::ops::function::FnOnce<()>>::call_once::hc15ba15cced82411 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/panic/unwind_safe.rs:272 [INFO] [stderr] 52: std::panicking::try::do_call::ha2b96a5442f2b7a6 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/panicking.rs:557 [INFO] [stderr] 53: std::panicking::try::h3249b845d1609c7f [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/panicking.rs:520 [INFO] [stderr] 54: std::panic::catch_unwind::hb1234c1714481772 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/panic.rs:345 [INFO] [stderr] 55: std::thread::Builder::spawn_unchecked_::{{closure}}::hf2ecca2087a712d7 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/thread/mod.rs:521 [INFO] [stderr] 56: core::ops::function::FnOnce::call_once{{vtable.shim}}::h33604033fff0c135 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/ops/function.rs:250 [INFO] [stderr] 57: as core::ops::function::FnOnce>::call_once::hed0705102829bfb8 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/alloc/src/boxed.rs:2229 [INFO] [stderr] 58: as core::ops::function::FnOnce>::call_once::h88dbb0360840e1a3 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/alloc/src/boxed.rs:2229 [INFO] [stderr] 59: std::sys::pal::unix::thread::Thread::new::thread_start::hed3d760d9bfb1ad1 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/sys/pal/unix/thread.rs:105 [INFO] [stderr] 60: clone [INFO] [stderr] at [INFO] [stdout] test tests::stress_test_timer_workers ... FAILED [INFO] [stderr] The application panicked (crashed). [INFO] [stderr] Message: assertion failed: c < d [INFO] [stderr] Location: src/lib.rs:988 [INFO] [stderr] [INFO] [stderr] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering. [INFO] [stderr] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stderr] ⋮ 8 frames hidden ⋮ [INFO] [stderr] 9: core::panicking::panic::h690a73cd45bac749 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/panicking.rs:148 [INFO] [stderr] 10: puteketeke::tests::test_two_timer::ha4bb11954128eccb [INFO] [stderr] at /opt/rustwide/workdir/src/lib.rs:988 [INFO] [stderr] 986 │ [INFO] [stderr] 987 │ let (c, d) = future::block_on(future::zip(task_2, task_3)); [INFO] [stderr] 988 > assert!(c < d); [INFO] [stderr] 989 │ } [INFO] [stderr] 990 │ [INFO] [stderr] 11: puteketeke::tests::test_two_timer::{{closure}}::hd522177d70b0a784 [INFO] [stderr] at /opt/rustwide/workdir/src/lib.rs:938 [INFO] [stderr] 936 │ [INFO] [stderr] 937 │ #[test] [INFO] [stderr] 938 > fn test_two_timer() { [INFO] [stderr] 939 │ color_backtrace::install(); [INFO] [stderr] 940 │ let executor = Executor::new(1); [INFO] [stderr] 12: core::ops::function::FnOnce::call_once::ha9c0499d8e700271 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/ops/function.rs:250 [INFO] [stderr] 13: core::ops::function::FnOnce::call_once::he9dcba90b82d8683 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/ops/function.rs:250 [INFO] [stderr] 14: test::__rust_begin_short_backtrace::hd3c7d260f12c8c4c [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/test/src/lib.rs:620 [INFO] [stderr] 15: test::run_test_in_process::{{closure}}::h2a83a0a65ed3c222 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/test/src/lib.rs:643 [INFO] [stderr] 16: as core::ops::function::FnOnce<()>>::call_once::h86af6a43b3d339fd [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/panic/unwind_safe.rs:272 [INFO] [stderr] 17: std::panicking::try::do_call::h06bb3a6ef41e33d4 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/panicking.rs:557 [INFO] [stderr] 18: std::panicking::try::hbe4ce21e81168e19 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/panicking.rs:520 [INFO] [stderr] 19: std::panic::catch_unwind::h9216a5b93914ba30 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/panic.rs:345 [INFO] [stderr] 20: test::run_test_in_process::hd5f710dbb42ac3ac [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/test/src/lib.rs:643 [INFO] [stderr] 21: test::run_test::{{closure}}::ha3c5aa83f018eb90 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/test/src/lib.rs:564 [INFO] [stderr] 22: test::run_test::{{closure}}::h106c33754fb7ad93 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/test/src/lib.rs:594 [INFO] [stderr] 23: std::sys::backtrace::__rust_begin_short_backtrace::h72b607f200d489e1 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/sys/backtrace.rs:154 [INFO] [stderr] 24: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h4c13dee51575b1d5 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/thread/mod.rs:522 [INFO] [stderr] 25: as core::ops::function::FnOnce<()>>::call_once::hc15ba15cced82411 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/panic/unwind_safe.rs:272 [INFO] [stderr] 26: std::panicking::try::do_call::ha2b96a5442f2b7a6 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/panicking.rs:557 [INFO] [stderr] 27: std::panicking::try::h3249b845d1609c7f [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/panicking.rs:520 [INFO] [stderr] 28: std::panic::catch_unwind::hb1234c1714481772 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/panic.rs:345 [INFO] [stderr] 29: std::thread::Builder::spawn_unchecked_::{{closure}}::hf2ecca2087a712d7 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/thread/mod.rs:521 [INFO] [stderr] 30: core::ops::function::FnOnce::call_once{{vtable.shim}}::h33604033fff0c135 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/core/src/ops/function.rs:250 [INFO] [stderr] 31: as core::ops::function::FnOnce>::call_once::hed0705102829bfb8 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/alloc/src/boxed.rs:2229 [INFO] [stderr] 32: as core::ops::function::FnOnce>::call_once::h88dbb0360840e1a3 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/alloc/src/boxed.rs:2229 [INFO] [stderr] 33: std::sys::pal::unix::thread::Thread::new::thread_start::hed3d760d9bfb1ad1 [INFO] [stderr] at /rustc/8c0ec05f7dc9ef185eea9f36c5638e74e43935bf/library/std/src/sys/pal/unix/thread.rs:105 [INFO] [stderr] 34: clone [INFO] [stderr] at [INFO] [stdout] test tests::test_two_timer ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- tests::stress_test_timer_workers stdout ---- [INFO] [stdout] sleep: 2 [INFO] [stdout] elapsed: 2.1977ms [INFO] [stdout] delta: 197.7µs [INFO] [stdout] sleep: 2 [INFO] [stdout] elapsed: 2.30164ms [INFO] [stdout] delta: 301.64µs [INFO] [stdout] sleep: 5 [INFO] [stdout] elapsed: 5.1948ms [INFO] [stdout] delta: 194.8µs [INFO] [stdout] sleep: 1 [INFO] [stdout] elapsed: 1.09309ms [INFO] [stdout] delta: 93.09µs [INFO] [stdout] sleep: 13 [INFO] [stdout] elapsed: 13.20477ms [INFO] [stdout] delta: 204.77µs [INFO] [stdout] sleep: 19 [INFO] [stdout] elapsed: 19.15754ms [INFO] [stdout] delta: 157.54µs [INFO] [stdout] sleep: 21 [INFO] [stdout] elapsed: 21.18036ms [INFO] [stdout] delta: 180.36µs [INFO] [stdout] sleep: 22 [INFO] [stdout] elapsed: 22.08579ms [INFO] [stdout] delta: 85.79µs [INFO] [stdout] sleep: 25 [INFO] [stdout] elapsed: 25.19409ms [INFO] [stdout] delta: 194.09µs [INFO] [stdout] sleep: 3 [INFO] [stdout] elapsed: 3.07219ms [INFO] [stdout] delta: 72.19µs [INFO] [stdout] sleep: 31 [INFO] [stdout] elapsed: 31.21356ms [INFO] [stdout] delta: 213.56µs [INFO] [stdout] sleep: 33 [INFO] [stdout] elapsed: 33.21958ms [INFO] [stdout] delta: 219.58µs [INFO] [stdout] sleep: 34 [INFO] [stdout] elapsed: 34.11549ms [INFO] [stdout] delta: 115.49µs [INFO] [stdout] sleep: 15 [INFO] [stdout] elapsed: 15.0821ms [INFO] [stdout] delta: 82.1µs [INFO] [stdout] sleep: 35 [INFO] [stdout] elapsed: 35.07665ms [INFO] [stdout] delta: 76.65µs [INFO] [stdout] sleep: 41 [INFO] [stdout] elapsed: 41.19045ms [INFO] [stdout] delta: 190.45µs [INFO] [stdout] sleep: 42 [INFO] [stdout] elapsed: 42.19079ms [INFO] [stdout] delta: 190.79µs [INFO] [stdout] sleep: 43 [INFO] [stdout] elapsed: 43.14375ms [INFO] [stdout] delta: 143.75µs [INFO] [stdout] sleep: 43 [INFO] [stdout] elapsed: 43.1828ms [INFO] [stdout] delta: 182.8µs [INFO] [stdout] sleep: 47 [INFO] [stdout] elapsed: 47.21348ms [INFO] [stdout] delta: 213.48µs [INFO] [stdout] sleep: 4 [INFO] [stdout] elapsed: 4.11079ms [INFO] [stdout] delta: 110.79µs [INFO] [stdout] sleep: 49 [INFO] [stdout] elapsed: 49.18217ms [INFO] [stdout] delta: 182.17µs [INFO] [stdout] sleep: 51 [INFO] [stdout] elapsed: 51.16271ms [INFO] [stdout] delta: 162.71µs [INFO] [stdout] sleep: 52 [INFO] [stdout] elapsed: 52.1578ms [INFO] [stdout] delta: 157.8µs [INFO] [stdout] sleep: 19 [INFO] [stdout] elapsed: 19.13217ms [INFO] [stdout] delta: 132.17µs [INFO] [stdout] sleep: 2 [INFO] [stdout] elapsed: 2.13111ms [INFO] [stdout] delta: 131.11µs [INFO] [stdout] sleep: 55 [INFO] [stdout] elapsed: 55.14809ms [INFO] [stdout] delta: 148.09µs [INFO] [stdout] sleep: 57 [INFO] [stdout] elapsed: 57.12196ms [INFO] [stdout] delta: 121.96µs [INFO] [stdout] sleep: 32 [INFO] [stdout] elapsed: 32.12048ms [INFO] [stdout] delta: 120.48µs [INFO] [stdout] sleep: 15 [INFO] [stdout] elapsed: 15.11557ms [INFO] [stdout] delta: 115.57µs [INFO] [stdout] sleep: 70 [INFO] [stdout] elapsed: 70.15797ms [INFO] [stdout] delta: 157.97µs [INFO] [stdout] sleep: 36 [INFO] [stdout] elapsed: 36.13404ms [INFO] [stdout] delta: 134.04µs [INFO] [stdout] sleep: 30 [INFO] [stdout] elapsed: 30.09425ms [INFO] [stdout] delta: 94.25µs [INFO] [stdout] sleep: 26 [INFO] [stdout] elapsed: 26.09995ms [INFO] [stdout] delta: 99.95µs [INFO] [stdout] sleep: 72 [INFO] [stdout] elapsed: 72.10192ms [INFO] [stdout] delta: 101.92µs [INFO] [stdout] sleep: 48 [INFO] [stdout] elapsed: 48.13694ms [INFO] [stdout] delta: 136.94µs [INFO] [stdout] sleep: 80 [INFO] [stdout] elapsed: 80.12474ms [INFO] [stdout] delta: 124.74µs [INFO] [stdout] sleep: 82 [INFO] [stdout] elapsed: 82.11171ms [INFO] [stdout] delta: 111.71µs [INFO] [stdout] sleep: 68 [INFO] [stdout] elapsed: 68.10415ms [INFO] [stdout] delta: 104.15µs [INFO] [stdout] sleep: 84 [INFO] [stdout] elapsed: 84.23766ms [INFO] [stdout] delta: 237.66µs [INFO] [stdout] sleep: 43 [INFO] [stdout] elapsed: 43.118871ms [INFO] [stdout] delta: 118.871µs [INFO] [stdout] sleep: 37 [INFO] [stdout] elapsed: 37.09365ms [INFO] [stdout] delta: 93.65µs [INFO] [stdout] sleep: 22 [INFO] [stdout] elapsed: 22.08059ms [INFO] [stdout] delta: 80.59µs [INFO] [stdout] sleep: 46 [INFO] [stdout] elapsed: 46.09668ms [INFO] [stdout] delta: 96.68µs [INFO] [stdout] sleep: 19 [INFO] [stdout] elapsed: 19.0944ms [INFO] [stdout] delta: 94.4µs [INFO] [stdout] sleep: 8 [INFO] [stdout] elapsed: 8.11802ms [INFO] [stdout] delta: 118.02µs [INFO] [stdout] sleep: 97 [INFO] [stdout] elapsed: 97.1148ms [INFO] [stdout] delta: 114.8µs [INFO] [stdout] sleep: 68 [INFO] [stdout] elapsed: 68.14122ms [INFO] [stdout] delta: 141.22µs [INFO] [stdout] sleep: 76 [INFO] [stdout] elapsed: 76.100001ms [INFO] [stdout] delta: 100.001µs [INFO] [stdout] sleep: 31 [INFO] [stdout] elapsed: 31.110841ms [INFO] [stdout] delta: 110.841µs [INFO] [stdout] sleep: 21 [INFO] [stdout] elapsed: 21.09598ms [INFO] [stdout] delta: 95.98µs [INFO] [stdout] sleep: 92 [INFO] [stdout] elapsed: 92.103181ms [INFO] [stdout] delta: 103.181µs [INFO] [stdout] sleep: 32 [INFO] [stdout] elapsed: 32.06608ms [INFO] [stdout] delta: 66.08µs [INFO] [stdout] sleep: 65 [INFO] [stdout] elapsed: 65.125551ms [INFO] [stdout] delta: 125.551µs [INFO] [stdout] sleep: 40 [INFO] [stdout] elapsed: 40.100991ms [INFO] [stdout] delta: 100.991µs [INFO] [stdout] sleep: 3 [INFO] [stdout] elapsed: 3.112779ms [INFO] [stdout] delta: 112.779µs [INFO] [stdout] sleep: 58 [INFO] [stdout] elapsed: 58.09696ms [INFO] [stdout] delta: 96.96µs [INFO] [stdout] sleep: 47 [INFO] [stdout] elapsed: 47.10702ms [INFO] [stdout] delta: 107.02µs [INFO] [stdout] sleep: 27 [INFO] [stdout] elapsed: 27.13778ms [INFO] [stdout] delta: 137.78µs [INFO] [stdout] sleep: 9 [INFO] [stdout] elapsed: 9.108691ms [INFO] [stdout] delta: 108.691µs [INFO] [stdout] sleep: 93 [INFO] [stdout] elapsed: 93.10848ms [INFO] [stdout] delta: 108.48µs [INFO] [stdout] sleep: 47 [INFO] [stdout] elapsed: 47.111151ms [INFO] [stdout] delta: 111.151µs [INFO] [stdout] sleep: 80 [INFO] [stdout] elapsed: 80.096481ms [INFO] [stdout] delta: 96.481µs [INFO] [stdout] sleep: 21 [INFO] [stdout] elapsed: 21.1203ms [INFO] [stdout] delta: 120.3µs [INFO] [stdout] sleep: 45 [INFO] [stdout] elapsed: 45.094031ms [INFO] [stdout] delta: 94.031µs [INFO] [stdout] sleep: 51 [INFO] [stdout] elapsed: 51.10227ms [INFO] [stdout] delta: 102.27µs [INFO] [stdout] sleep: 43 [INFO] [stdout] elapsed: 43.116311ms [INFO] [stdout] delta: 116.311µs [INFO] [stdout] sleep: 7 [INFO] [stdout] elapsed: 7.11766ms [INFO] [stdout] delta: 117.66µs [INFO] [stdout] sleep: 87 [INFO] [stdout] elapsed: 87.106601ms [INFO] [stdout] delta: 106.601µs [INFO] [stdout] sleep: 12 [INFO] [stdout] elapsed: 12.126129ms [INFO] [stdout] delta: 126.129µs [INFO] [stdout] sleep: 45 [INFO] [stdout] elapsed: 45.09447ms [INFO] [stdout] delta: 94.47µs [INFO] [stdout] sleep: 63 [INFO] [stdout] elapsed: 63.116951ms [INFO] [stdout] delta: 116.951µs [INFO] [stdout] sleep: 99 [INFO] [stdout] elapsed: 99.129021ms [INFO] [stdout] delta: 129.021µs [INFO] [stdout] sleep: 34 [INFO] [stdout] elapsed: 34.07593ms [INFO] [stdout] delta: 75.93µs [INFO] [stdout] sleep: 72 [INFO] [stdout] elapsed: 72.13317ms [INFO] [stdout] delta: 133.17µs [INFO] [stdout] sleep: 41 [INFO] [stdout] elapsed: 41.09354ms [INFO] [stdout] delta: 93.54µs [INFO] [stdout] sleep: 7 [INFO] [stdout] elapsed: 7.11885ms [INFO] [stdout] delta: 118.85µs [INFO] [stdout] sleep: 85 [INFO] [stdout] elapsed: 85.10114ms [INFO] [stdout] delta: 101.14µs [INFO] [stdout] sleep: 44 [INFO] [stdout] elapsed: 44.10074ms [INFO] [stdout] delta: 100.74µs [INFO] [stdout] sleep: 80 [INFO] [stdout] elapsed: 80.09364ms [INFO] [stdout] delta: 93.64µs [INFO] [stdout] sleep: 23 [INFO] [stdout] elapsed: 23.128361ms [INFO] [stdout] delta: 128.361µs [INFO] [stdout] sleep: 49 [INFO] [stdout] elapsed: 49.115911ms [INFO] [stdout] delta: 115.911µs [INFO] [stdout] sleep: 96 [INFO] [stdout] elapsed: 96.09717ms [INFO] [stdout] delta: 97.17µs [INFO] [stdout] sleep: 43 [INFO] [stdout] elapsed: 43.103581ms [INFO] [stdout] delta: 103.581µs [INFO] [stdout] sleep: 52 [INFO] [stdout] elapsed: 52.075651ms [INFO] [stdout] delta: 75.651µs [INFO] [stdout] sleep: 16 [INFO] [stdout] elapsed: 16.075341ms [INFO] [stdout] delta: 75.341µs [INFO] [stdout] sleep: 71 [INFO] [stdout] elapsed: 71.09464ms [INFO] [stdout] delta: 94.64µs [INFO] [stdout] sleep: 95 [INFO] [stdout] elapsed: 95.09232ms [INFO] [stdout] delta: 92.32µs [INFO] [stdout] sleep: 80 [INFO] [stdout] elapsed: 80.139849ms [INFO] [stdout] delta: 139.849µs [INFO] [stdout] sleep: 38 [INFO] [stdout] elapsed: 38.12167ms [INFO] [stdout] delta: 121.67µs [INFO] [stdout] sleep: 32 [INFO] [stdout] elapsed: 32.07958ms [INFO] [stdout] delta: 79.58µs [INFO] [stdout] sleep: 71 [INFO] [stdout] elapsed: 71.093921ms [INFO] [stdout] delta: 93.921µs [INFO] [stdout] sleep: 44 [INFO] [stdout] elapsed: 44.120331ms [INFO] [stdout] delta: 120.331µs [INFO] [stdout] sleep: 56 [INFO] [stdout] elapsed: 56.1116ms [INFO] [stdout] delta: 111.6µs [INFO] [stdout] sleep: 66 [INFO] [stdout] elapsed: 66.13305ms [INFO] [stdout] delta: 133.05µs [INFO] [stdout] sleep: 90 [INFO] [stdout] elapsed: 90.17206ms [INFO] [stdout] delta: 172.06µs [INFO] [stdout] sleep: 71 [INFO] [stdout] elapsed: 75.63075ms [INFO] [stdout] delta: 4.63075ms [INFO] [stdout] sleep: 77 [INFO] [stdout] elapsed: 83.12029ms [INFO] [stdout] delta: 6.12029ms [INFO] [stdout] sleep: 80 [INFO] [stdout] elapsed: 80.126291ms [INFO] [stdout] delta: 126.291µs [INFO] [stdout] sleep: 98 [INFO] [stdout] elapsed: 98.092089ms [INFO] [stdout] delta: 92.089µs [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] tests::stress_test_timer_workers [INFO] [stdout] tests::test_two_timer [INFO] [stdout] [INFO] [stdout] test result: FAILED. 6 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.01s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "d464a90c86814b71356e42a90596bbaaf8d24db2dc3baa2330bfb1ac5ee314e2", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "d464a90c86814b71356e42a90596bbaaf8d24db2dc3baa2330bfb1ac5ee314e2", kill_on_drop: false }` [INFO] [stdout] d464a90c86814b71356e42a90596bbaaf8d24db2dc3baa2330bfb1ac5ee314e2