[INFO] cloning repository https://github.com/wvhulle/forked_stream [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/wvhulle/forked_stream" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fwvhulle%2Fforked_stream", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fwvhulle%2Fforked_stream'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 8b3fbaba9d38b078ea024c538ad0cbb1634065bc [INFO] testing wvhulle/forked_stream against try#16fbf98f22730e073c09e7b03f0eafb87a295545 for pr-145628 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fwvhulle%2Fforked_stream" "/workspace/builds/worker-7-tc2/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc2/source'... [INFO] [stderr] done. [INFO] removed /workspace/builds/worker-7-tc2/source/rust-toolchain.toml [INFO] started tweaking git repo https://github.com/wvhulle/forked_stream [INFO] finished tweaking git repo https://github.com/wvhulle/forked_stream [INFO] tweaked toml for git repo https://github.com/wvhulle/forked_stream written to /workspace/builds/worker-7-tc2/source/Cargo.toml [INFO] validating manifest of git repo https://github.com/wvhulle/forked_stream on toolchain 16fbf98f22730e073c09e7b03f0eafb87a295545 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+16fbf98f22730e073c09e7b03f0eafb87a295545" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+16fbf98f22730e073c09e7b03f0eafb87a295545" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Locking 102 packages to latest Rust 1.91.0-nightly compatible versions [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+16fbf98f22730e073c09e7b03f0eafb87a295545" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+16fbf98f22730e073c09e7b03f0eafb87a295545" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 275d64e5068a4c8f6f0ab2116014c4b18a83d5ef6604bd41edf4f5939f816f39 [INFO] running `Command { std: "docker" "start" "-a" "275d64e5068a4c8f6f0ab2116014c4b18a83d5ef6604bd41edf4f5939f816f39", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "275d64e5068a4c8f6f0ab2116014c4b18a83d5ef6604bd41edf4f5939f816f39", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "275d64e5068a4c8f6f0ab2116014c4b18a83d5ef6604bd41edf4f5939f816f39", kill_on_drop: false }` [INFO] [stdout] 275d64e5068a4c8f6f0ab2116014c4b18a83d5ef6604bd41edf4f5939f816f39 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+16fbf98f22730e073c09e7b03f0eafb87a295545" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] cb6d66a63dc66a817b8c9913eada3228df668e980794a5d0007f54be8a29a7a1 [INFO] running `Command { std: "docker" "start" "-a" "cb6d66a63dc66a817b8c9913eada3228df668e980794a5d0007f54be8a29a7a1", kill_on_drop: false }` [INFO] [stderr] Compiling memchr v2.7.5 [INFO] [stderr] Compiling slab v0.4.11 [INFO] [stderr] Compiling num_cpus v1.17.0 [INFO] [stderr] Compiling futures-macro v0.3.31 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling clone-stream v0.2.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 6.13s [INFO] running `Command { std: "docker" "inspect" "cb6d66a63dc66a817b8c9913eada3228df668e980794a5d0007f54be8a29a7a1", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "cb6d66a63dc66a817b8c9913eada3228df668e980794a5d0007f54be8a29a7a1", kill_on_drop: false }` [INFO] [stdout] cb6d66a63dc66a817b8c9913eada3228df668e980794a5d0007f54be8a29a7a1 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+16fbf98f22730e073c09e7b03f0eafb87a295545" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] f946ac56f4e486a70fcdeb4d3e840d45d0276429959788569aeb32014e2b713d [INFO] running `Command { std: "docker" "start" "-a" "f946ac56f4e486a70fcdeb4d3e840d45d0276429959788569aeb32014e2b713d", kill_on_drop: false }` [INFO] [stderr] Compiling aho-corasick v1.1.3 [INFO] [stderr] Compiling lock_api v0.4.13 [INFO] [stderr] Compiling regex-syntax v0.8.6 [INFO] [stderr] Compiling parking_lot_core v0.9.11 [INFO] [stderr] Compiling log v0.4.27 [INFO] [stderr] Compiling cfg-if v1.0.3 [INFO] [stderr] Compiling futures-channel v0.3.31 [INFO] [stderr] Compiling smallvec v1.15.1 [INFO] [stderr] Compiling socket2 v0.6.0 [INFO] [stderr] Compiling signal-hook-registry v1.4.6 [INFO] [stderr] Compiling anstream v0.6.20 [INFO] [stderr] Compiling mio v1.0.4 [INFO] [stderr] Compiling jiff v0.2.15 [INFO] [stderr] Compiling tokio-macros v2.5.0 [INFO] [stderr] Compiling test-log-macros v0.2.18 [INFO] [stderr] Compiling parking_lot v0.12.4 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling tokio v1.47.1 [INFO] [stderr] Compiling regex-automata v0.4.10 [INFO] [stderr] Compiling regex v1.11.2 [INFO] [stderr] Compiling env_filter v0.1.3 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling env_logger v0.11.8 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling clone-stream v0.2.1 (/opt/rustwide/workdir) [INFO] [stderr] Compiling test-log v0.2.18 [INFO] [stderr] Compiling tokio-stream v0.1.17 [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 30.28s [INFO] running `Command { std: "docker" "inspect" "f946ac56f4e486a70fcdeb4d3e840d45d0276429959788569aeb32014e2b713d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f946ac56f4e486a70fcdeb4d3e840d45d0276429959788569aeb32014e2b713d", kill_on_drop: false }` [INFO] [stdout] f946ac56f4e486a70fcdeb4d3e840d45d0276429959788569aeb32014e2b713d [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+16fbf98f22730e073c09e7b03f0eafb87a295545" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 74189898aaf0fecc635a81709a5d8b2cb59a7dd9bfebc5b457abede5e8130224 [INFO] running `Command { std: "docker" "start" "-a" "74189898aaf0fecc635a81709a5d8b2cb59a7dd9bfebc5b457abede5e8130224", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.14s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/clone_stream-3e9ff6027b3a9363) [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/clone_receive.rs (/opt/rustwide/target/debug/deps/clone_receive-994bc61f5c2983c6) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stderr] [2025-08-31T18:48:22.537181Z /opt/rustwide/workdir/src/fork.rs:74] Registering clone 0. [INFO] [stderr] [2025-08-31T18:48:22.537392Z /opt/rustwide/workdir/src/fork.rs:74] Registering clone 1. [INFO] [stderr] [2025-08-31T18:48:22.568808Z /opt/rustwide/workdir/src/fork.rs:44] Clone 0 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.569025Z /opt/rustwide/workdir/src/states/cold_queue/never_polled.rs:30] Currently in state 'NeverPolled' [INFO] [stderr] [2025-08-31T18:48:22.569124Z /opt/rustwide/workdir/src/fork.rs:66] Found 1 wakers. [INFO] [stderr] [2025-08-31T18:48:22.569205Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 0 back into the fork with state: QueueEmptyThenBasePending(QueueEmptyThenBasePending { waker: Waker { data: 0x78609800c680, vtable: 0x61750ff17dd8 } }). [INFO] [stderr] [2025-08-31T18:48:22.569381Z /opt/rustwide/workdir/src/fork.rs:44] Clone 1 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.569475Z /opt/rustwide/workdir/src/states/cold_queue/never_polled.rs:30] Currently in state 'NeverPolled' [INFO] [stderr] [2025-08-31T18:48:22.569553Z /opt/rustwide/workdir/src/fork.rs:66] Found 2 wakers. [INFO] [stderr] [2025-08-31T18:48:22.569629Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 1 back into the fork with state: QueueEmptyThenBasePending(QueueEmptyThenBasePending { waker: Waker { data: 0x78609800c800, vtable: 0x61750ff17dd8 } }). [INFO] [stderr] [2025-08-31T18:48:22.578987Z src/fork.rs:122] Waking up all sleeping clones. [INFO] [stderr] [2025-08-31T18:48:22.579216Z /opt/rustwide/workdir/src/fork.rs:44] Clone 0 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.579308Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:29] Currently in state 'QueueEmptyThenBasePending [INFO] [stderr] [2025-08-31T18:48:22.579388Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:31] Queue is empty [INFO] [stderr] [2025-08-31T18:48:22.579476Z /opt/rustwide/workdir/src/fork.rs:66] Found 2 wakers. [INFO] [stderr] [2025-08-31T18:48:22.579541Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:42] At least one clone is interested in the new item. [INFO] [stderr] [2025-08-31T18:48:22.579630Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 0 back into the fork with state: QueueEmptyThenBaseReady(QueueEmptyThenBaseReady). [INFO] [stderr] [2025-08-31T18:48:22.579828Z /opt/rustwide/workdir/src/fork.rs:82] Unregistering clone 0. [INFO] [stderr] [2025-08-31T18:48:22.580041Z src/states/mod.rs:58] Checking if state QueueEmptyThenBasePending(QueueEmptyThenBasePending { waker: Waker { data: 0x78609800c800, vtable: 0x61750ff17dd8 } }) should still see queue item with index 0 [INFO] [stderr] [2025-08-31T18:48:22.580332Z /opt/rustwide/workdir/src/fork.rs:44] Clone 1 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.580512Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:29] Currently in state 'QueueEmptyThenBasePending [INFO] [stderr] [2025-08-31T18:48:22.580618Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:76] No other clone is waiting for the first item in the queue. [INFO] [stderr] [2025-08-31T18:48:22.580707Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 1 back into the fork with state: UnseenQueuedItemReady(UnseenQueuedItemReady { unseen_ready_queue_item_index: 0 }). [INFO] [stderr] [2025-08-31T18:48:22.580858Z /opt/rustwide/workdir/src/fork.rs:82] Unregistering clone 1. [INFO] [stdout] test clone_pair_receives ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s [INFO] [stdout] [INFO] [stderr] Running tests/is_terminated.rs (/opt/rustwide/target/debug/deps/is_terminated-246de3e8f2d83d8c) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] test two_clones_terminated ... ok [INFO] [stdout] test ready_into_stream ... ok [INFO] [stderr] Running tests/late_clone.rs (/opt/rustwide/target/debug/deps/late_clone-45c718b40940ddf5) [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stderr] [2025-08-31T18:48:22.597708Z /opt/rustwide/workdir/src/fork.rs:74] Registering clone 0. [INFO] [stderr] [2025-08-31T18:48:22.597817Z /opt/rustwide/workdir/src/fork.rs:74] Registering clone 1. [INFO] [stderr] [2025-08-31T18:48:22.629205Z /opt/rustwide/workdir/src/fork.rs:44] Clone 0 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.629325Z /opt/rustwide/workdir/src/states/cold_queue/never_polled.rs:30] Currently in state 'NeverPolled' [INFO] [stderr] [2025-08-31T18:48:22.629378Z /opt/rustwide/workdir/src/fork.rs:66] Found 1 wakers. [INFO] [stderr] [2025-08-31T18:48:22.629431Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 0 back into the fork with state: QueueEmptyThenBasePending(QueueEmptyThenBasePending { waker: Waker { data: 0x7312e400c680, vtable: 0x561a481eb5f8 } }). [INFO] [stderr] [2025-08-31T18:48:22.629554Z /opt/rustwide/workdir/src/fork.rs:44] Clone 1 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.629604Z /opt/rustwide/workdir/src/states/cold_queue/never_polled.rs:30] Currently in state 'NeverPolled' [INFO] [stderr] [2025-08-31T18:48:22.629658Z /opt/rustwide/workdir/src/fork.rs:66] Found 2 wakers. [INFO] [stderr] [2025-08-31T18:48:22.629705Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 1 back into the fork with state: QueueEmptyThenBasePending(QueueEmptyThenBasePending { waker: Waker { data: 0x7312e400c800, vtable: 0x561a481eb5f8 } }). [INFO] [stderr] [2025-08-31T18:48:22.639032Z src/fork.rs:122] Waking up all sleeping clones. [INFO] [stderr] [2025-08-31T18:48:22.639166Z /opt/rustwide/workdir/src/fork.rs:44] Clone 0 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.639224Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:29] Currently in state 'QueueEmptyThenBasePending [INFO] [stderr] [2025-08-31T18:48:22.639291Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:31] Queue is empty [INFO] [stderr] [2025-08-31T18:48:22.639352Z /opt/rustwide/workdir/src/fork.rs:66] Found 2 wakers. [INFO] [stderr] [2025-08-31T18:48:22.639401Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:42] At least one clone is interested in the new item. [INFO] [stderr] [2025-08-31T18:48:22.639466Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 0 back into the fork with state: QueueEmptyThenBaseReady(QueueEmptyThenBaseReady). [INFO] [stderr] [2025-08-31T18:48:22.639546Z /opt/rustwide/workdir/src/fork.rs:82] Unregistering clone 0. [INFO] [stderr] [2025-08-31T18:48:22.639595Z src/states/mod.rs:58] Checking if state QueueEmptyThenBasePending(QueueEmptyThenBasePending { waker: Waker { data: 0x7312e400c800, vtable: 0x561a481eb5f8 } }) should still see queue item with index 0 [INFO] [stderr] [2025-08-31T18:48:22.639710Z /opt/rustwide/workdir/src/fork.rs:44] Clone 1 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.639765Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:29] Currently in state 'QueueEmptyThenBasePending [INFO] [stderr] [2025-08-31T18:48:22.639828Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:76] No other clone is waiting for the first item in the queue. [INFO] [stderr] [2025-08-31T18:48:22.639896Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 1 back into the fork with state: UnseenQueuedItemReady(UnseenQueuedItemReady { unseen_ready_queue_item_index: 0 }). [INFO] [stderr] [2025-08-31T18:48:22.639991Z /opt/rustwide/workdir/src/fork.rs:74] Registering clone 2. [INFO] [stderr] [2025-08-31T18:48:22.649284Z /opt/rustwide/workdir/src/fork.rs:44] Clone 2 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.649393Z /opt/rustwide/workdir/src/states/cold_queue/never_polled.rs:30] Currently in state 'NeverPolled' [INFO] [stderr] [2025-08-31T18:48:22.649451Z /opt/rustwide/workdir/src/fork.rs:66] Found 1 wakers. [INFO] [stderr] [2025-08-31T18:48:22.649497Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 2 back into the fork with state: QueueEmptyThenBasePending(QueueEmptyThenBasePending { waker: Waker { data: 0x7312e400c800, vtable: 0x561a481eb5f8 } }). [INFO] [stderr] [2025-08-31T18:48:22.658788Z src/fork.rs:122] Waking up all sleeping clones. [INFO] [stderr] [2025-08-31T18:48:22.659016Z /opt/rustwide/workdir/src/fork.rs:44] Clone 2 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.662992Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:29] Currently in state 'QueueEmptyThenBasePending [INFO] [stderr] [2025-08-31T18:48:22.663155Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:31] Queue is empty [INFO] [stdout] test clone_after_send ... ok [INFO] [stderr] [2025-08-31T18:48:22.663243Z /opt/rustwide/workdir/src/fork.rs:66] Found 1 wakers. [INFO] [stdout] [INFO] [stderr] [2025-08-31T18:48:22.663319Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:46] No other clone is interested in the new item. [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s [INFO] [stderr] [2025-08-31T18:48:22.663408Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 2 back into the fork with state: QueueEmptyThenBaseReady(QueueEmptyThenBaseReady). [INFO] [stdout] [INFO] [stderr] [2025-08-31T18:48:22.663497Z /opt/rustwide/workdir/src/fork.rs:82] Unregistering clone 2. [INFO] [stderr] [2025-08-31T18:48:22.663581Z /opt/rustwide/workdir/src/fork.rs:82] Unregistering clone 1. [INFO] [stderr] Running tests/late_poll.rs (/opt/rustwide/target/debug/deps/late_poll-75a960eb727c11bf) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stderr] [2025-08-31T18:48:22.671288Z /opt/rustwide/workdir/src/fork.rs:74] Registering clone 0. [INFO] [stderr] [2025-08-31T18:48:22.671461Z /opt/rustwide/workdir/src/fork.rs:74] Registering clone 1. [INFO] [stderr] [2025-08-31T18:48:22.700235Z /opt/rustwide/workdir/src/fork.rs:44] Clone 0 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.700437Z /opt/rustwide/workdir/src/states/cold_queue/never_polled.rs:30] Currently in state 'NeverPolled' [INFO] [stderr] [2025-08-31T18:48:22.700525Z /opt/rustwide/workdir/src/fork.rs:66] Found 1 wakers. [INFO] [stderr] [2025-08-31T18:48:22.700602Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 0 back into the fork with state: QueueEmptyThenBasePending(QueueEmptyThenBasePending { waker: Waker { data: 0x7b18e400c480, vtable: 0x606bebed3ac8 } }). [INFO] [stderr] [2025-08-31T18:48:22.700756Z /opt/rustwide/workdir/src/fork.rs:44] Clone 1 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.700945Z /opt/rustwide/workdir/src/states/cold_queue/never_polled.rs:30] Currently in state 'NeverPolled' [INFO] [stderr] [2025-08-31T18:48:22.701007Z /opt/rustwide/workdir/src/fork.rs:66] Found 2 wakers. [INFO] [stderr] [2025-08-31T18:48:22.701050Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 1 back into the fork with state: QueueEmptyThenBasePending(QueueEmptyThenBasePending { waker: Waker { data: 0x7b18e400c680, vtable: 0x606bebed3ac8 } }). [INFO] [stderr] [2025-08-31T18:48:22.702763Z /opt/rustwide/workdir/src/fork.rs:44] Clone 0 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.702889Z /opt/rustwide/workdir/src/states/cold_queue/never_polled.rs:30] Currently in state 'NeverPolled' [INFO] [stderr] [2025-08-31T18:48:22.703002Z /opt/rustwide/workdir/src/fork.rs:66] Found 1 wakers. [INFO] [stderr] [2025-08-31T18:48:22.703084Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 0 back into the fork with state: QueueEmptyThenBasePending(QueueEmptyThenBasePending { waker: Waker { data: 0x7b18dc006700, vtable: 0x606bebed3ac8 } }). [INFO] [stderr] [2025-08-31T18:48:22.710353Z src/fork.rs:122] Waking up all sleeping clones. [INFO] [stderr] [2025-08-31T18:48:22.710566Z /opt/rustwide/workdir/src/fork.rs:44] Clone 0 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.710666Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:29] Currently in state 'QueueEmptyThenBasePending [INFO] [stderr] [2025-08-31T18:48:22.710759Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:31] Queue is empty [INFO] [stderr] [2025-08-31T18:48:22.710847Z /opt/rustwide/workdir/src/fork.rs:66] Found 2 wakers. [INFO] [stderr] [2025-08-31T18:48:22.710949Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:42] At least one clone is interested in the new item. [INFO] [stderr] [2025-08-31T18:48:22.711062Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 0 back into the fork with state: QueueEmptyThenBaseReady(QueueEmptyThenBaseReady). [INFO] [stderr] [2025-08-31T18:48:22.711203Z /opt/rustwide/workdir/src/fork.rs:44] Clone 1 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.711306Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:29] Currently in state 'QueueEmptyThenBasePending [INFO] [stderr] [2025-08-31T18:48:22.711414Z src/states/mod.rs:58] Checking if state QueueEmptyThenBaseReady(QueueEmptyThenBaseReady) should still see queue item with index 0 [INFO] [stderr] [2025-08-31T18:48:22.711513Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:76] No other clone is waiting for the first item in the queue. [INFO] [stderr] [2025-08-31T18:48:22.711607Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 1 back into the fork with state: UnseenQueuedItemReady(UnseenQueuedItemReady { unseen_ready_queue_item_index: 0 }). [INFO] [stderr] [2025-08-31T18:48:22.719971Z /opt/rustwide/workdir/src/fork.rs:44] Clone 1 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.720166Z /opt/rustwide/workdir/src/fork.rs:66] Found 1 wakers. [INFO] [stderr] [2025-08-31T18:48:22.720253Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 1 back into the fork with state: NoUnseenQueuedThenBasePending(NoUnseenQueuedThenBasePending { waker: Waker { data: 0x7b18e400c680, vtable: 0x606bebed3ac8 }, most_recent_queue_item_index: 0 }). [INFO] [stderr] [2025-08-31T18:48:22.727738Z /opt/rustwide/workdir/src/fork.rs:44] Clone 1 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.727860Z /opt/rustwide/workdir/src/states/cold_queue/never_polled.rs:30] Currently in state 'NeverPolled' [INFO] [stderr] [2025-08-31T18:48:22.727940Z /opt/rustwide/workdir/src/fork.rs:66] Found 2 wakers. [INFO] [stderr] [2025-08-31T18:48:22.727997Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 1 back into the fork with state: QueueEmptyThenBasePending(QueueEmptyThenBasePending { waker: Waker { data: 0x7b18dc006900, vtable: 0x606bebed3ac8 } }). [INFO] [stderr] [2025-08-31T18:48:22.728123Z /opt/rustwide/workdir/src/fork.rs:44] Clone 0 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.728184Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:29] Currently in state 'QueueEmptyThenBasePending [INFO] [stderr] [2025-08-31T18:48:22.728247Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:31] Queue is empty [INFO] [stderr] [2025-08-31T18:48:22.728305Z /opt/rustwide/workdir/src/fork.rs:66] Found 2 wakers. [INFO] [stderr] [2025-08-31T18:48:22.728357Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 0 back into the fork with state: QueueEmptyThenBasePending(QueueEmptyThenBasePending { waker: Waker { data: 0x7b18dc006700, vtable: 0x606bebed3ac8 } }). [INFO] [stderr] [2025-08-31T18:48:22.728471Z tests/late_poll.rs:38] Cancelled next() await of adam. [INFO] [stderr] [2025-08-31T18:48:22.728522Z /opt/rustwide/workdir/src/fork.rs:82] Unregistering clone 0. [INFO] [stderr] [2025-08-31T18:48:22.730576Z src/fork.rs:122] Waking up all sleeping clones. [INFO] [stderr] [2025-08-31T18:48:22.730724Z /opt/rustwide/workdir/src/fork.rs:44] Clone 1 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.730818Z /opt/rustwide/workdir/src/fork.rs:66] Found 1 wakers. [INFO] [stderr] [2025-08-31T18:48:22.730889Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 1 back into the fork with state: NoUnseenQueuedThenBaseReady(NoUnseenQueuedThenBaseReady). [INFO] [stderr] [2025-08-31T18:48:22.731017Z /opt/rustwide/workdir/src/fork.rs:82] Unregistering clone 1. [INFO] [stdout] test poll_after_send ... ok [INFO] [stderr] [2025-08-31T18:48:22.740423Z /opt/rustwide/workdir/src/fork.rs:44] Clone 0 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.740608Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_ready.rs:24] The queue was empty on last poll of this clone, but the base was ready. [INFO] [stderr] [2025-08-31T18:48:22.740715Z /opt/rustwide/workdir/src/fork.rs:66] Found 1 wakers. [INFO] [stderr] [2025-08-31T18:48:22.740874Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_ready.rs:39] No other clone is interested in the new item. [INFO] [stderr] [2025-08-31T18:48:22.740986Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 0 back into the fork with state: QueueEmptyThenBaseReady(QueueEmptyThenBaseReady). [INFO] [stderr] [2025-08-31T18:48:22.741080Z /opt/rustwide/workdir/src/fork.rs:82] Unregistering clone 0. [INFO] [stdout] test poll_before_send ... ok [INFO] [stderr] [2025-08-31T18:48:22.782939Z src/fork.rs:122] Waking up all sleeping clones. [INFO] [stdout] [INFO] [stderr] [2025-08-31T18:48:22.783086Z /opt/rustwide/workdir/src/fork.rs:44] Clone 1 is being polled through the fork. [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.12s [INFO] [stderr] [2025-08-31T18:48:22.783150Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:29] Currently in state 'QueueEmptyThenBasePending [INFO] [stdout] [INFO] [stderr] [2025-08-31T18:48:22.783211Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:31] Queue is empty [INFO] [stderr] [2025-08-31T18:48:22.783267Z /opt/rustwide/workdir/src/fork.rs:66] Found 1 wakers. [INFO] [stderr] [2025-08-31T18:48:22.783313Z /opt/rustwide/workdir/src/states/cold_queue/queue_empty_then_base_pending.rs:46] No other clone is interested in the new item. [INFO] [stderr] [2025-08-31T18:48:22.783377Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 1 back into the fork with state: QueueEmptyThenBaseReady(QueueEmptyThenBaseReady). [INFO] [stderr] [2025-08-31T18:48:22.783458Z /opt/rustwide/workdir/src/fork.rs:82] Unregistering clone 1. [INFO] [stderr] Running tests/queue_len.rs (/opt/rustwide/target/debug/deps/queue_len-875daa606f45f795) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stderr] [2025-08-31T18:48:22.791471Z /opt/rustwide/workdir/src/fork.rs:74] Registering clone 0. [INFO] [stderr] [2025-08-31T18:48:22.870066Z /opt/rustwide/workdir/src/fork.rs:44] Clone 0 is being polled through the fork. [INFO] [stderr] [2025-08-31T18:48:22.870189Z /opt/rustwide/workdir/src/states/cold_queue/never_polled.rs:30] Currently in state 'NeverPolled' [INFO] [stderr] [2025-08-31T18:48:22.870246Z /opt/rustwide/workdir/src/fork.rs:66] Found 1 wakers. [INFO] [stderr] [2025-08-31T18:48:22.870290Z /opt/rustwide/workdir/src/fork.rs:52] Inserting clone 0 back into the fork with state: QueueEmptyThenBasePending(QueueEmptyThenBasePending { waker: Waker { data: 0x754c3c00c700, vtable: 0x5d630c7c4308 } }). [INFO] [stderr] [2025-08-31T18:48:22.870400Z /opt/rustwide/workdir/src/clone.rs:99] Getting the number of queued items for clone 0. [INFO] [stderr] [2025-08-31T18:48:22.870447Z /opt/rustwide/workdir/src/fork.rs:82] Unregistering clone 0. [INFO] [stderr] [2025-08-31T18:48:22.908756Z src/fork.rs:122] Waking up all sleeping clones. [INFO] [stdout] test cancelled_next_queue_empty ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- cancelled_next_queue_empty stdout ---- [INFO] [stdout] [INFO] [stdout] thread 'cancelled_next_queue_empty' (35) panicked at tests/queue_len.rs:22:28: [INFO] [stdout] called `Result::unwrap()` on an `Err` value: SendError { .. } [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5d630c77ea82 - std::backtrace_rs::backtrace::libunwind::trace::h4b3f5960ed1c0917 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x5d630c77ea82 - std::backtrace_rs::backtrace::trace_unsynchronized::h2f16383c47f8bd04 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x5d630c77ea82 - std::sys::backtrace::_print_fmt::h698d661724c270ea [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x5d630c77ea82 - ::fmt::h1c8375013274064c [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x5d630c7901df - core::fmt::rt::Argument::fmt::h9232377a772d3f8c [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x5d630c7901df - core::fmt::write::h6dc9e168423d9633 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/fmt/mod.rs:1468:25 [INFO] [stdout] 6: 0x5d630c74a843 - std::io::default_write_fmt::hec53a38f10739f86 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x5d630c74a843 - std::io::Write::write_fmt::hf5089d8bbbe5aaa6 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x5d630c7567c2 - std::sys::backtrace::BacktraceLock::print::hf83c3d8cbd01f904 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x5d630c75b32f - std::panicking::default_hook::{{closure}}::h3d4c49adc0898362 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:301:27 [INFO] [stdout] 10: 0x5d630c75b1c1 - std::panicking::default_hook::hccea28f2487e7471 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:325:9 [INFO] [stdout] 11: 0x5d630c65dbfe - as core::ops::function::Fn>::call::h06e1b96f84d5792f [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 12: 0x5d630c65dbfe - test::test_main_with_exit_callback::{{closure}}::hf6db6d7b1242fcd4 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x5d630c75ba7e - as core::ops::function::Fn>::call::hfeb094aff1e6591e [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 14: 0x5d630c75ba7e - std::panicking::panic_with_hook::h694b8196629eb5e1 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:842:13 [INFO] [stdout] 15: 0x5d630c75b79a - std::panicking::panic_handler::{{closure}}::h0d99dd765f7e6554 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:707:13 [INFO] [stdout] 16: 0x5d630c7568f9 - std::sys::backtrace::__rust_end_short_backtrace::h8b43cbde72f7d592 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 17: 0x5d630c73ea4d - __rustc[d1dec8fc82f9a585]::rust_begin_unwind [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:698:5 [INFO] [stdout] 18: 0x5d630c798d70 - core::panicking::panic_fmt::h315eeb0e4d90fcdc [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/panicking.rs:75:14 [INFO] [stdout] 19: 0x5d630c798056 - core::result::unwrap_failed::h3188dfca3824df71 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/result.rs:1852:5 [INFO] [stdout] 20: 0x5d630c37c0fd - core::result::Result::unwrap::h344bf00f3ddac146 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/result.rs:1223:23 [INFO] [stdout] 21: 0x5d630c37c0fd - queue_len::cancelled_next_queue_empty::{{closure}}::{{closure}}::h3dc8bc3957b1609e [INFO] [stdout] at /opt/rustwide/workdir/tests/queue_len.rs:22:28 [INFO] [stdout] 22: 0x5d630c362684 - tokio::runtime::task::core::Core::poll::{{closure}}::hc92d83d58c90b6cc [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/task/core.rs:365:24 [INFO] [stdout] 23: 0x5d630c361b0d - tokio::loom::std::unsafe_cell::UnsafeCell::with_mut::he1130c4f7380efe4 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/loom/std/unsafe_cell.rs:16:9 [INFO] [stdout] 24: 0x5d630c361b0d - tokio::runtime::task::core::Core::poll::hf4fd06bfe796ec21 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/task/core.rs:354:30 [INFO] [stdout] 25: 0x5d630c35966a - tokio::runtime::task::harness::poll_future::{{closure}}::he7e7d4b98513c73e [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/task/harness.rs:535:30 [INFO] [stdout] 26: 0x5d630c371ee0 - as core::ops::function::FnOnce<()>>::call_once::h61be5342d08f5210 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 27: 0x5d630c377444 - std::panicking::catch_unwind::do_call::h30e9947141cf663a [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:590:40 [INFO] [stdout] 28: 0x5d630c36e9eb - __rust_try [INFO] [stdout] 29: 0x5d630c36c456 - std::panicking::catch_unwind::he621fbb27bee977f [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:553:19 [INFO] [stdout] 30: 0x5d630c36c456 - std::panic::catch_unwind::h71c5cbc28f5d416f [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panic.rs:359:14 [INFO] [stdout] 31: 0x5d630c357f5f - tokio::runtime::task::harness::poll_future::h75425524e8e8076b [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/task/harness.rs:523:18 [INFO] [stdout] 32: 0x5d630c35a0bf - tokio::runtime::task::harness::Harness::poll_inner::hb9cfa833a6f49df7 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/task/harness.rs:210:27 [INFO] [stdout] 33: 0x5d630c35b423 - tokio::runtime::task::harness::Harness::poll::h0a46541e862e25ce [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/task/harness.rs:155:20 [INFO] [stdout] 34: 0x5d630c36666b - tokio::runtime::task::raw::poll::he97fbc08deb59ae3 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/task/raw.rs:325:13 [INFO] [stdout] 35: 0x5d630c6d7977 - tokio::runtime::task::raw::RawTask::poll::hea9908bf73fe54ad [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/task/raw.rs:255:18 [INFO] [stdout] 36: 0x5d630c37db01 - tokio::runtime::task::LocalNotified::run::hb485155d397ebadb [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/task/mod.rs:509:13 [INFO] [stdout] 37: 0x5d630c384caa - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::hba4486b8d5bdfb87 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:789:30 [INFO] [stdout] 38: 0x5d630c382ad5 - tokio::task::coop::with_budget::h34db38de1a8622ea [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/task/coop/mod.rs:167:5 [INFO] [stdout] 39: 0x5d630c382ad5 - tokio::task::coop::budget::hf895f7517517b154 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/task/coop/mod.rs:133:5 [INFO] [stdout] 40: 0x5d630c382ad5 - tokio::runtime::scheduler::current_thread::Context::run_task::{{closure}}::h22e227a1db9e2314 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:368:43 [INFO] [stdout] 41: 0x5d630c382556 - tokio::runtime::scheduler::current_thread::Context::enter::hfdf23c3f14eaae17 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:432:19 [INFO] [stdout] 42: 0x5d630c38292c - tokio::runtime::scheduler::current_thread::Context::run_task::h96210264cfaa2503 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:368:28 [INFO] [stdout] 43: 0x5d630c383fe4 - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::h3d3b3c0a8912f63d [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:785:43 [INFO] [stdout] 44: 0x5d630c3834f4 - tokio::runtime::scheduler::current_thread::CoreGuard::enter::{{closure}}::h45d8383a961baadd [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:829:68 [INFO] [stdout] 45: 0x5d630c36a68b - tokio::runtime::context::scoped::Scoped::set::h9f5f7a43d006f551 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context/scoped.rs:40:9 [INFO] [stdout] 46: 0x5d630c370fd9 - tokio::runtime::context::set_scheduler::{{closure}}::h93ab91d02ba2a7c1 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context.rs:176:38 [INFO] [stdout] 47: 0x5d630c38ae72 - std::thread::local::LocalKey::try_with::h141df84ac7f488da [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/thread/local.rs:315:12 [INFO] [stdout] 48: 0x5d630c38a97e - std::thread::local::LocalKey::with::hb0fa00a461dfadf7 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/thread/local.rs:279:20 [INFO] [stdout] 49: 0x5d630c370f0d - tokio::runtime::context::set_scheduler::ha014c41ede94cb39 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context.rs:176:17 [INFO] [stdout] 50: 0x5d630c382e80 - tokio::runtime::scheduler::current_thread::CoreGuard::enter::h5f63335ecdbab165 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:829:27 [INFO] [stdout] 51: 0x5d630c383533 - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::h7acf2c6d5714f792 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:729:24 [INFO] [stdout] 52: 0x5d630c380aa0 - tokio::runtime::scheduler::current_thread::CurrentThread::block_on::{{closure}}::h5e037b8f4db87ed4 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:200:33 [INFO] [stdout] 53: 0x5d630c367580 - tokio::runtime::context::runtime::enter_runtime::h420437a63c985be1 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context/runtime.rs:65:16 [INFO] [stdout] 54: 0x5d630c380861 - tokio::runtime::scheduler::current_thread::CurrentThread::block_on::hb473381d50f9e7dc [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:188:9 [INFO] [stdout] 55: 0x5d630c37e474 - tokio::runtime::runtime::Runtime::block_on_inner::hcba58c527ad16a14 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/runtime.rs:356:52 [INFO] [stdout] 56: 0x5d630c37e63c - tokio::runtime::runtime::Runtime::block_on::he0dd22d453a512f0 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/runtime.rs:330:18 [INFO] [stdout] 57: 0x5d630c37af11 - queue_len::cancelled_next_queue_empty::h1ce99d36e2f4b0e3 [INFO] [stdout] at /opt/rustwide/workdir/tests/queue_len.rs:49:7 [INFO] [stdout] 58: 0x5d630c37afa7 - queue_len::cancelled_next_queue_empty::{{closure}}::he1d7ae34c6d5505d [INFO] [stdout] at /opt/rustwide/workdir/tests/queue_len.rs:10:38 [INFO] [stdout] 59: 0x5d630c394716 - core::ops::function::FnOnce::call_once::hd62cf5a0931177b4 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 60: 0x5d630c65da6b - core::ops::function::FnOnce::call_once::h5852f0076c8c0d8d [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 61: 0x5d630c65da6b - test::__rust_begin_short_backtrace::hc8457233131a06d0 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/test/src/lib.rs:663:18 [INFO] [stdout] 62: 0x5d630c6734c5 - test::run_test_in_process::{{closure}}::ha3eda53784ae2639 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/test/src/lib.rs:686:74 [INFO] [stdout] 63: 0x5d630c6734c5 - as core::ops::function::FnOnce<()>>::call_once::hbd60f847cd185bc6 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 64: 0x5d630c6734c5 - std::panicking::catch_unwind::do_call::h11c1623aed953668 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:590:40 [INFO] [stdout] 65: 0x5d630c6734c5 - std::panicking::catch_unwind::hf8551f73b52d8ed0 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:553:19 [INFO] [stdout] 66: 0x5d630c6734c5 - std::panic::catch_unwind::h66ff870d847fc7cf [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panic.rs:359:14 [INFO] [stdout] 67: 0x5d630c6734c5 - test::run_test_in_process::h808a5fa5cb24efde [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/test/src/lib.rs:686:27 [INFO] [stdout] 68: 0x5d630c6734c5 - test::run_test::{{closure}}::hcf5782fef76f68bf [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/test/src/lib.rs:607:43 [INFO] [stdout] 69: 0x5d630c649e94 - test::run_test::{{closure}}::h59bb1f8f7167fee7 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/test/src/lib.rs:637:41 [INFO] [stdout] 70: 0x5d630c649e94 - std::sys::backtrace::__rust_begin_short_backtrace::h073f551083d60480 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 71: 0x5d630c64d77a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h9d6eff1e4da9d0f2 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 72: 0x5d630c64d77a - as core::ops::function::FnOnce<()>>::call_once::he37f75191b40d4b0 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 73: 0x5d630c64d77a - std::panicking::catch_unwind::do_call::h339b26991b458079 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:590:40 [INFO] [stdout] 74: 0x5d630c64d77a - std::panicking::catch_unwind::h2e5906b8a84cca89 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:553:19 [INFO] [stdout] 75: 0x5d630c64d77a - std::panic::catch_unwind::h3fea03a95c906d68 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panic.rs:359:14 [INFO] [stdout] 76: 0x5d630c64d77a - std::thread::Builder::spawn_unchecked_::{{closure}}::hab1ac593ae8241c8 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 77: 0x5d630c64d77a - core::ops::function::FnOnce::call_once{{vtable.shim}}::hb46ae6da79faf2c5 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 78: 0x5d630c750b7f - as core::ops::function::FnOnce>::call_once::h317217a5c86437d1 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 79: 0x5d630c750b7f - std::sys::pal::unix::thread::Thread::new::thread_start::h9b0e1220f8cf5182 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 80: 0x754c4088caa4 - [INFO] [stdout] 81: 0x754c40919a34 - clone [INFO] [stdout] 82: 0x0 - [INFO] [stdout] [INFO] [stdout] thread 'cancelled_next_queue_empty' (35) panicked at tests/queue_len.rs:48:25: [INFO] [stdout] called `Result::unwrap()` on an `Err` value: JoinError::Panic(Id(1), "called `Result::unwrap()` on an `Err` value: SendError { .. }", ...) [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5d630c77ea82 - std::backtrace_rs::backtrace::libunwind::trace::h4b3f5960ed1c0917 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x5d630c77ea82 - std::backtrace_rs::backtrace::trace_unsynchronized::h2f16383c47f8bd04 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x5d630c77ea82 - std::sys::backtrace::_print_fmt::h698d661724c270ea [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x5d630c77ea82 - ::fmt::h1c8375013274064c [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x5d630c7901df - core::fmt::rt::Argument::fmt::h9232377a772d3f8c [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x5d630c7901df - core::fmt::write::h6dc9e168423d9633 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/fmt/mod.rs:1468:25 [INFO] [stdout] 6: 0x5d630c74a843 - std::io::default_write_fmt::hec53a38f10739f86 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x5d630c74a843 - std::io::Write::write_fmt::hf5089d8bbbe5aaa6 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x5d630c7567c2 - std::sys::backtrace::BacktraceLock::print::hf83c3d8cbd01f904 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x5d630c75b32f - std::panicking::default_hook::{{closure}}::h3d4c49adc0898362 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:301:27 [INFO] [stdout] 10: 0x5d630c75b1c1 - std::panicking::default_hook::hccea28f2487e7471 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:325:9 [INFO] [stdout] 11: 0x5d630c65dbfe - as core::ops::function::Fn>::call::h06e1b96f84d5792f [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 12: 0x5d630c65dbfe - test::test_main_with_exit_callback::{{closure}}::hf6db6d7b1242fcd4 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x5d630c75ba7e - as core::ops::function::Fn>::call::hfeb094aff1e6591e [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 14: 0x5d630c75ba7e - std::panicking::panic_with_hook::h694b8196629eb5e1 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:842:13 [INFO] [stdout] 15: 0x5d630c75b79a - std::panicking::panic_handler::{{closure}}::h0d99dd765f7e6554 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:707:13 [INFO] [stdout] 16: 0x5d630c7568f9 - std::sys::backtrace::__rust_end_short_backtrace::h8b43cbde72f7d592 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 17: 0x5d630c73ea4d - __rustc[d1dec8fc82f9a585]::rust_begin_unwind [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:698:5 [INFO] [stdout] 18: 0x5d630c798d70 - core::panicking::panic_fmt::h315eeb0e4d90fcdc [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/panicking.rs:75:14 [INFO] [stdout] 19: 0x5d630c798056 - core::result::unwrap_failed::h3188dfca3824df71 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/result.rs:1852:5 [INFO] [stdout] 20: 0x5d630c37c205 - core::result::Result::unwrap::h381409642d047488 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/result.rs:1223:23 [INFO] [stdout] 21: 0x5d630c37c205 - queue_len::cancelled_next_queue_empty::{{closure}}::{{closure}}::h82882d7ff99a412c [INFO] [stdout] at /opt/rustwide/workdir/tests/queue_len.rs:48:25 [INFO] [stdout] 22: 0x5d630c3683f3 - as core::iter::traits::iterator::Iterator>::for_each::hcea83f13805c3d65 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/slice/iter/macros.rs:277:21 [INFO] [stdout] 23: 0x5d630c37b511 - queue_len::cancelled_next_queue_empty::{{closure}}::hfd1f43289d99b4e9 [INFO] [stdout] at /opt/rustwide/workdir/tests/queue_len.rs:47:6 [INFO] [stdout] 24: 0x5d630c371452 - as core::future::future::Future>::poll::h8ed0057dcbb217b2 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/future/future.rs:133:9 [INFO] [stdout] 25: 0x5d630c3714cd - as core::future::future::Future>::poll::hcfd95c97008d2d00 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/future/future.rs:133:9 [INFO] [stdout] 26: 0x5d630c384e6d - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::{{closure}}::h3b913e0a43045bf9 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:742:70 [INFO] [stdout] 27: 0x5d630c384c0b - tokio::task::coop::with_budget::h8666e5179bdb2691 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/task/coop/mod.rs:167:5 [INFO] [stdout] 28: 0x5d630c384c0b - tokio::task::coop::budget::hf4bf8a22d7c2f024 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/task/coop/mod.rs:133:5 [INFO] [stdout] 29: 0x5d630c384c0b - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::h1309f434cb2b87d3 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:742:25 [INFO] [stdout] 30: 0x5d630c381f30 - tokio::runtime::scheduler::current_thread::Context::enter::h7eee0f00ed1dc2fa [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:432:19 [INFO] [stdout] 31: 0x5d630c38384d - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::h3d3b3c0a8912f63d [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:741:44 [INFO] [stdout] 32: 0x5d630c3834f4 - tokio::runtime::scheduler::current_thread::CoreGuard::enter::{{closure}}::h45d8383a961baadd [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:829:68 [INFO] [stdout] 33: 0x5d630c36a68b - tokio::runtime::context::scoped::Scoped::set::h9f5f7a43d006f551 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context/scoped.rs:40:9 [INFO] [stdout] 34: 0x5d630c370fd9 - tokio::runtime::context::set_scheduler::{{closure}}::h93ab91d02ba2a7c1 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context.rs:176:38 [INFO] [stdout] 35: 0x5d630c38ae72 - std::thread::local::LocalKey::try_with::h141df84ac7f488da [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/thread/local.rs:315:12 [INFO] [stdout] 36: 0x5d630c38a97e - std::thread::local::LocalKey::with::hb0fa00a461dfadf7 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/thread/local.rs:279:20 [INFO] [stdout] 37: 0x5d630c370f0d - tokio::runtime::context::set_scheduler::ha014c41ede94cb39 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context.rs:176:17 [INFO] [stdout] 38: 0x5d630c382e80 - tokio::runtime::scheduler::current_thread::CoreGuard::enter::h5f63335ecdbab165 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:829:27 [INFO] [stdout] 39: 0x5d630c383533 - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::h7acf2c6d5714f792 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:729:24 [INFO] [stdout] 40: 0x5d630c380aa0 - tokio::runtime::scheduler::current_thread::CurrentThread::block_on::{{closure}}::h5e037b8f4db87ed4 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:200:33 [INFO] [stdout] 41: 0x5d630c367580 - tokio::runtime::context::runtime::enter_runtime::h420437a63c985be1 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context/runtime.rs:65:16 [INFO] [stdout] 42: 0x5d630c380861 - tokio::runtime::scheduler::current_thread::CurrentThread::block_on::hb473381d50f9e7dc [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/current_thread/mod.rs:188:9 [INFO] [stdout] 43: 0x5d630c37e474 - tokio::runtime::runtime::Runtime::block_on_inner::hcba58c527ad16a14 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/runtime.rs:356:52 [INFO] [stdout] 44: 0x5d630c37e63c - tokio::runtime::runtime::Runtime::block_on::he0dd22d453a512f0 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/runtime.rs:330:18 [INFO] [stdout] 45: 0x5d630c37af11 - queue_len::cancelled_next_queue_empty::h1ce99d36e2f4b0e3 [INFO] [stdout] at /opt/rustwide/workdir/tests/queue_len.rs:49:7 [INFO] [stdout] 46: 0x5d630c37afa7 - queue_len::cancelled_next_queue_empty::{{closure}}::he1d7ae34c6d5505d [INFO] [stdout] at /opt/rustwide/workdir/tests/queue_len.rs:10:38 [INFO] [stdout] 47: 0x5d630c394716 - core::ops::function::FnOnce::call_once::hd62cf5a0931177b4 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 48: 0x5d630c65da6b - core::ops::function::FnOnce::call_once::h5852f0076c8c0d8d [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 49: 0x5d630c65da6b - test::__rust_begin_short_backtrace::hc8457233131a06d0 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/test/src/lib.rs:663:18 [INFO] [stdout] 50: 0x5d630c6734c5 - test::run_test_in_process::{{closure}}::ha3eda53784ae2639 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/test/src/lib.rs:686:74 [INFO] [stdout] 51: 0x5d630c6734c5 - as core::ops::function::FnOnce<()>>::call_once::hbd60f847cd185bc6 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 52: 0x5d630c6734c5 - std::panicking::catch_unwind::do_call::h11c1623aed953668 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:590:40 [INFO] [stdout] 53: 0x5d630c6734c5 - std::panicking::catch_unwind::hf8551f73b52d8ed0 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:553:19 [INFO] [stdout] 54: 0x5d630c6734c5 - std::panic::catch_unwind::h66ff870d847fc7cf [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panic.rs:359:14 [INFO] [stdout] 55: 0x5d630c6734c5 - test::run_test_in_process::h808a5fa5cb24efde [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/test/src/lib.rs:686:27 [INFO] [stdout] 56: 0x5d630c6734c5 - test::run_test::{{closure}}::hcf5782fef76f68bf [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/test/src/lib.rs:607:43 [INFO] [stdout] 57: 0x5d630c649e94 - test::run_test::{{closure}}::h59bb1f8f7167fee7 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/test/src/lib.rs:637:41 [INFO] [stdout] 58: 0x5d630c649e94 - std::sys::backtrace::__rust_begin_short_backtrace::h073f551083d60480 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 59: 0x5d630c64d77a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h9d6eff1e4da9d0f2 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 60: 0x5d630c64d77a - as core::ops::function::FnOnce<()>>::call_once::he37f75191b40d4b0 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 61: 0x5d630c64d77a - std::panicking::catch_unwind::do_call::h339b26991b458079 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:590:40 [INFO] [stdout] 62: 0x5d630c64d77a - std::panicking::catch_unwind::h2e5906b8a84cca89 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panicking.rs:553:19 [INFO] [stdout] 63: 0x5d630c64d77a - std::panic::catch_unwind::h3fea03a95c906d68 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/panic.rs:359:14 [INFO] [stdout] 64: 0x5d630c64d77a - std::thread::Builder::spawn_unchecked_::{{closure}}::hab1ac593ae8241c8 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 65: 0x5d630c64d77a - core::ops::function::FnOnce::call_once{{vtable.shim}}::hb46ae6da79faf2c5 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 66: 0x5d630c750b7f - as core::ops::function::FnOnce>::call_once::h317217a5c86437d1 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 67: 0x5d630c750b7f - std::sys::pal::unix::thread::Thread::new::thread_start::h9b0e1220f8cf5182 [INFO] [stdout] at /rustc/16fbf98f22730e073c09e7b03f0eafb87a295545/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 68: 0x754c4088caa4 - [INFO] [stdout] 69: 0x754c40919a34 - clone [INFO] [stdout] 70: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] cancelled_next_queue_empty [INFO] [stdout] [INFO] [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.12s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--test queue_len` [INFO] running `Command { std: "docker" "inspect" "74189898aaf0fecc635a81709a5d8b2cb59a7dd9bfebc5b457abede5e8130224", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "74189898aaf0fecc635a81709a5d8b2cb59a7dd9bfebc5b457abede5e8130224", kill_on_drop: false }` [INFO] [stdout] 74189898aaf0fecc635a81709a5d8b2cb59a7dd9bfebc5b457abede5e8130224