[INFO] cloning repository https://github.com/Nehliin/vortex [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/Nehliin/vortex" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FNehliin%2Fvortex", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FNehliin%2Fvortex'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] a335aa4dabea73560430457fc1f3173f2c293bea [INFO] testing Nehliin/vortex against try#0936b3db0ae648ba2d5b60eb08cf4faf2ddab0c6 for pr-137122-1 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FNehliin%2Fvortex" "/workspace/builds/worker-4-tc2/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc2/source'... [INFO] [stderr] done. [INFO] started tweaking git repo https://github.com/Nehliin/vortex [INFO] finished tweaking git repo https://github.com/Nehliin/vortex [INFO] tweaked toml for git repo https://github.com/Nehliin/vortex written to /workspace/builds/worker-4-tc2/source/Cargo.toml [INFO] validating manifest of git repo https://github.com/Nehliin/vortex on toolchain 0936b3db0ae648ba2d5b60eb08cf4faf2ddab0c6 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+0936b3db0ae648ba2d5b60eb08cf4faf2ddab0c6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate git repo https://github.com/Nehliin/vortex 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" "+0936b3db0ae648ba2d5b60eb08cf4faf2ddab0c6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+0936b3db0ae648ba2d5b60eb08cf4faf2ddab0c6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] bf0c15327db9be07cf10b07305e1bee65b4d2dc36633bcdd2355586ef6a821f5 [INFO] running `Command { std: "docker" "start" "-a" "bf0c15327db9be07cf10b07305e1bee65b4d2dc36633bcdd2355586ef6a821f5", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "bf0c15327db9be07cf10b07305e1bee65b4d2dc36633bcdd2355586ef6a821f5", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "bf0c15327db9be07cf10b07305e1bee65b4d2dc36633bcdd2355586ef6a821f5", kill_on_drop: false }` [INFO] [stdout] bf0c15327db9be07cf10b07305e1bee65b4d2dc36633bcdd2355586ef6a821f5 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+0936b3db0ae648ba2d5b60eb08cf4faf2ddab0c6" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] b6a236b5b10b93e3abdb061d2ad78e1653c75c66c64d034c09dcd1013e7943f8 [INFO] running `Command { std: "docker" "start" "-a" "b6a236b5b10b93e3abdb061d2ad78e1653c75c66c64d034c09dcd1013e7943f8", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.172 [INFO] [stderr] Compiling proc-macro2 v1.0.95 [INFO] [stderr] Compiling unicode-ident v1.0.18 [INFO] [stderr] Compiling autocfg v1.4.0 [INFO] [stderr] Compiling serde v1.0.219 [INFO] [stderr] Compiling version_check v0.9.5 [INFO] [stderr] Compiling shlex v1.3.0 [INFO] [stderr] Compiling pin-project-lite v0.2.16 [INFO] [stderr] Compiling memchr v2.7.4 [INFO] [stderr] Compiling once_cell v1.21.3 [INFO] [stderr] Compiling futures-core v0.3.31 [INFO] [stderr] Compiling typenum v1.18.0 [INFO] [stderr] Compiling futures-sink v0.3.31 [INFO] [stderr] Compiling itoa v1.0.15 [INFO] [stderr] Compiling crossbeam-utils v0.8.21 [INFO] [stderr] Compiling slab v0.4.9 [INFO] [stderr] Compiling generic-array v0.14.7 [INFO] [stderr] Compiling getrandom v0.3.2 [INFO] [stderr] Compiling fs_extra v1.3.0 [INFO] [stderr] Compiling dunce v1.0.5 [INFO] [stderr] Compiling futures-channel v0.3.31 [INFO] [stderr] Compiling zerocopy v0.8.25 [INFO] [stderr] Compiling futures-task v0.3.31 [INFO] [stderr] Compiling quote v1.0.40 [INFO] [stderr] Compiling futures-io v0.3.31 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling zeroize v1.8.1 [INFO] [stderr] Compiling semver v1.0.26 [INFO] [stderr] Compiling syn v2.0.101 [INFO] [stderr] Compiling aws-lc-rs v1.13.0 [INFO] [stderr] Compiling cpufeatures v0.2.17 [INFO] [stderr] Compiling jobserver v0.1.33 [INFO] [stderr] Compiling socket2 v0.5.9 [INFO] [stderr] Compiling mio v1.0.3 [INFO] [stderr] Compiling foldhash v0.1.5 [INFO] [stderr] Compiling cc v1.2.20 [INFO] [stderr] Compiling rand_core v0.9.3 [INFO] [stderr] Compiling hashbrown v0.15.2 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling block-buffer v0.10.4 [INFO] [stderr] Compiling crypto-common v0.1.6 [INFO] [stderr] Compiling crossbeam-epoch v0.9.18 [INFO] [stderr] Compiling digest v0.10.7 [INFO] [stderr] Compiling ahash v0.8.11 [INFO] [stderr] Compiling tracing-core v0.1.33 [INFO] [stderr] Compiling subtle v2.6.1 [INFO] [stderr] Compiling lock_api v0.4.12 [INFO] [stderr] Compiling cmake v0.1.54 [INFO] [stderr] Compiling rustls-pki-types v1.11.0 [INFO] [stderr] Compiling smallvec v1.15.0 [INFO] [stderr] Compiling zerocopy v0.7.35 [INFO] [stderr] Compiling httparse v1.10.1 [INFO] [stderr] Compiling bitflags v2.9.0 [INFO] [stderr] Compiling ppv-lite86 v0.2.21 [INFO] [stderr] Compiling equivalent v1.0.2 [INFO] [stderr] Compiling rustc_version v0.4.1 [INFO] [stderr] Compiling indexmap v2.9.0 [INFO] [stderr] Compiling rustls v0.23.26 [INFO] [stderr] Compiling thiserror v2.0.12 [INFO] [stderr] Compiling untrusted v0.9.0 [INFO] [stderr] Compiling try-lock v0.2.5 [INFO] [stderr] Compiling thiserror v1.0.69 [INFO] [stderr] Compiling rayon-core v1.12.1 [INFO] [stderr] Compiling aws-lc-sys v0.28.2 [INFO] [stderr] Compiling rand_chacha v0.9.0 [INFO] [stderr] Compiling utf8parse v0.2.2 [INFO] [stderr] Compiling atomic-waker v1.1.2 [INFO] [stderr] Compiling log v0.4.27 [INFO] [stderr] Compiling anstyle-parse v0.2.6 [INFO] [stderr] Compiling want v0.3.1 [INFO] [stderr] Compiling curve25519-dalek v4.1.3 [INFO] [stderr] Compiling rand v0.9.1 [INFO] [stderr] Compiling crossbeam-deque v0.8.6 [INFO] [stderr] Compiling aho-corasick v1.1.3 [INFO] [stderr] Compiling num-traits v0.2.19 [INFO] [stderr] Compiling anstyle-query v1.1.2 [INFO] [stderr] Compiling anstyle v1.0.10 [INFO] [stderr] Compiling io-uring v0.6.4 [INFO] [stderr] Compiling anyhow v1.0.98 [INFO] [stderr] Compiling regex-syntax v0.8.5 [INFO] [stderr] Compiling radium v0.7.0 [INFO] [stderr] Compiling is_terminal_polyfill v1.70.1 [INFO] [stderr] Compiling either v1.15.0 [INFO] [stderr] Compiling colorchoice v1.0.3 [INFO] [stderr] Compiling httpdate v1.0.3 [INFO] [stderr] Compiling anstream v0.6.18 [INFO] [stderr] Compiling tikv-jemalloc-sys v0.5.4+5.3.0-patched [INFO] [stderr] Compiling metrics v0.24.2 [INFO] [stderr] Compiling raw-cpuid v11.5.0 [INFO] [stderr] Compiling sha1 v0.10.6 [INFO] [stderr] Compiling parking_lot_core v0.9.10 [INFO] [stderr] Compiling openssl-probe v0.1.6 [INFO] [stderr] Compiling custom_derive v0.1.7 [INFO] [stderr] Compiling tower-service v0.3.3 [INFO] [stderr] Compiling signature v2.2.0 [INFO] [stderr] Compiling io-uring v0.7.5 [INFO] [stderr] Compiling tap v1.0.1 [INFO] [stderr] Compiling byteorder v1.5.0 [INFO] [stderr] Compiling heapless v0.8.0 [INFO] [stderr] Compiling wyz v0.5.1 [INFO] [stderr] Compiling hash32 v0.3.1 [INFO] [stderr] Compiling ed25519 v2.2.3 [INFO] [stderr] Compiling regex-automata v0.4.9 [INFO] [stderr] Compiling conv v0.3.3 [INFO] [stderr] Compiling rustls-native-certs v0.8.1 [INFO] [stderr] Compiling quanta v0.12.5 [INFO] [stderr] Compiling rayon v1.10.0 [INFO] [stderr] Compiling itertools v0.10.5 [INFO] [stderr] Compiling spin v0.9.8 [INFO] [stderr] Compiling serde_derive v1.0.219 [INFO] [stderr] Compiling tokio-macros v2.5.0 [INFO] [stderr] Compiling futures-macro v0.3.31 [INFO] [stderr] Compiling tracing-attributes v0.1.28 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling thiserror-impl v2.0.12 [INFO] [stderr] Compiling thiserror-impl v1.0.69 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling curve25519-dalek-derive v0.1.1 [INFO] [stderr] Compiling regex v1.11.1 [INFO] [stderr] Compiling sha2 v0.10.8 [INFO] [stderr] Compiling rand_xoshiro v0.7.0 [INFO] [stderr] Compiling socket2 v0.4.10 [INFO] [stderr] Compiling num_cpus v1.16.0 [INFO] [stderr] Compiling slotmap v1.0.7 [INFO] [stderr] Compiling clap_lex v0.7.4 [INFO] [stderr] Compiling funty v2.0.0 [INFO] [stderr] Compiling litrs v0.4.1 [INFO] [stderr] Compiling stable_deref_trait v1.2.0 [INFO] [stderr] Compiling crc-catalog v2.4.0 [INFO] [stderr] Compiling sketches-ddsketch v0.3.0 [INFO] [stderr] Compiling strsim v0.11.1 [INFO] [stderr] Compiling heck v0.5.0 [INFO] [stderr] Compiling serde_json v1.0.140 [INFO] [stderr] Compiling lava_torrent v0.11.1 [INFO] [stderr] Compiling clap_derive v4.5.32 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling clap_builder v4.5.39 [INFO] [stderr] Compiling document-features v0.2.11 [INFO] [stderr] Compiling bitvec v1.0.1 [INFO] [stderr] Compiling ed25519-dalek v2.1.1 [INFO] [stderr] Compiling metrics-util v0.19.1 [INFO] [stderr] Compiling crc v3.2.1 [INFO] [stderr] Compiling bytes v1.10.1 [INFO] [stderr] Compiling serde_bytes v0.11.17 [INFO] [stderr] Compiling bt_bencode v0.8.2 [INFO] [stderr] Compiling deranged v0.4.0 [INFO] [stderr] Compiling serde_bencode v0.2.4 [INFO] [stderr] Compiling tokio v1.44.2 [INFO] [stderr] Compiling http v1.3.1 [INFO] [stderr] Compiling env_filter v0.1.3 [INFO] [stderr] Compiling flume v0.11.1 [INFO] [stderr] Compiling btoi v0.4.3 [INFO] [stderr] Compiling ipnet v2.11.0 [INFO] [stderr] Compiling num-conv v0.1.0 [INFO] [stderr] Compiling ryu v1.0.20 [INFO] [stderr] Compiling base64 v0.22.1 [INFO] [stderr] Compiling http-body v1.0.1 [INFO] [stderr] Compiling smallvec v2.0.0-alpha.11 [INFO] [stderr] Compiling http-body-util v0.1.3 [INFO] [stderr] Compiling jiff v0.2.10 [INFO] [stderr] Compiling sha1_smol v1.0.1 [INFO] [stderr] Compiling lru v0.13.0 [INFO] [stderr] Compiling num_threads v0.1.7 [INFO] [stderr] Compiling time-core v0.1.4 [INFO] [stderr] Compiling dyn-clone v1.0.19 [INFO] [stderr] Compiling vortex-bittorrent v0.2.0 (/opt/rustwide/workdir/bittorrent) [INFO] [stderr] Compiling time v0.3.41 [INFO] [stderr] Compiling mainline v5.3.1 [INFO] [stdout] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stdout] --> bittorrent/src/buf_pool.rs:22:23 [INFO] [stdout] | [INFO] [stdout] 22 | pub fn get_buffer(&mut self) -> Buffer { [INFO] [stdout] | ^^^^^^^^^ ------ the lifetime gets resolved as `'_` [INFO] [stdout] | | [INFO] [stdout] | this lifetime flows to the output [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stdout] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stdout] | [INFO] [stdout] 22 | pub fn get_buffer(&mut self) -> Buffer<'_> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Compiling parking_lot v0.12.3 [INFO] [stderr] Compiling serde_bencoded v0.3.2 [INFO] [stderr] Compiling clap v4.5.39 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling crossbeam-channel v0.5.15 [INFO] [stderr] Compiling tokio-util v0.7.15 [INFO] [stderr] Compiling tokio-uring v0.5.0 (https://github.com/tokio-rs/tokio-uring?branch=master#7761222a) [INFO] [stderr] Compiling h2 v0.4.9 [INFO] [stderr] Compiling env_logger v0.11.8 [INFO] [stderr] Compiling vortex-dht v0.1.1 (/opt/rustwide/workdir/dht) [INFO] [stderr] Compiling utp-socket v0.1.1 (/opt/rustwide/workdir/utp-socket) [INFO] [stdout] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stdout] --> dht/src/krpc.rs:124:17 [INFO] [stdout] | [INFO] [stdout] 124 | pub fn ping(&self, body: Ping) -> QueryBuilder { [INFO] [stdout] | ^^^^^ ------------------ the lifetime gets resolved as `'_` [INFO] [stdout] | | [INFO] [stdout] | this lifetime flows to the output [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stdout] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stdout] | [INFO] [stdout] 124 | pub fn ping(&self, body: Ping) -> QueryBuilder<'_, Ping> { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stdout] --> dht/src/krpc.rs:134:23 [INFO] [stdout] | [INFO] [stdout] 134 | pub fn find_nodes(&self, body: FindNodes) -> QueryBuilder { [INFO] [stdout] | ^^^^^ ----------------------- the lifetime gets resolved as `'_` [INFO] [stdout] | | [INFO] [stdout] | this lifetime flows to the output [INFO] [stdout] | [INFO] [stdout] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stdout] | [INFO] [stdout] 134 | pub fn find_nodes(&self, body: FindNodes) -> QueryBuilder<'_, FindNodes> { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stdout] --> dht/src/krpc.rs:144:22 [INFO] [stdout] | [INFO] [stdout] 144 | pub fn get_peers(&self, body: GetPeers) -> QueryBuilder { [INFO] [stdout] | ^^^^^ ---------------------- the lifetime gets resolved as `'_` [INFO] [stdout] | | [INFO] [stdout] | this lifetime flows to the output [INFO] [stdout] | [INFO] [stdout] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stdout] | [INFO] [stdout] 144 | pub fn get_peers(&self, body: GetPeers) -> QueryBuilder<'_, GetPeers> { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stdout] --> dht/src/krpc.rs:154:26 [INFO] [stdout] | [INFO] [stdout] 154 | pub fn announce_peer(&self, body: AnnouncePeer) -> QueryBuilder { [INFO] [stdout] | ^^^^^ -------------------------- the lifetime gets resolved as `'_` [INFO] [stdout] | | [INFO] [stdout] | this lifetime flows to the output [INFO] [stdout] | [INFO] [stdout] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stdout] | [INFO] [stdout] 154 | pub fn announce_peer(&self, body: AnnouncePeer) -> QueryBuilder<'_, AnnouncePeer> { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Compiling hyper v1.6.0 [INFO] [stderr] Compiling hyper-util v0.1.11 [INFO] [stderr] Compiling rustls-webpki v0.103.1 [INFO] [stderr] Compiling tokio-rustls v0.26.2 [INFO] [stderr] Compiling hyper-rustls v0.27.5 [INFO] [stderr] Compiling metrics-exporter-prometheus v0.16.2 [INFO] [stderr] Compiling tikv-jemallocator v0.5.4 [INFO] [stderr] Compiling vortex-cli v0.1.0 (/opt/rustwide/workdir/cli) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 4m 43s [INFO] running `Command { std: "docker" "inspect" "b6a236b5b10b93e3abdb061d2ad78e1653c75c66c64d034c09dcd1013e7943f8", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b6a236b5b10b93e3abdb061d2ad78e1653c75c66c64d034c09dcd1013e7943f8", kill_on_drop: false }` [INFO] [stdout] b6a236b5b10b93e3abdb061d2ad78e1653c75c66c64d034c09dcd1013e7943f8 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+0936b3db0ae648ba2d5b60eb08cf4faf2ddab0c6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] d64722d2bc639f64751f8a7880a7c24c86ba7dcdfeaf8d6da4376c456a5baa2c [INFO] running `Command { std: "docker" "start" "-a" "d64722d2bc639f64751f8a7880a7c24c86ba7dcdfeaf8d6da4376c456a5baa2c", kill_on_drop: false }` [INFO] [stderr] Compiling num-traits v0.2.19 [INFO] [stderr] Compiling nibble_vec v0.1.0 [INFO] [stderr] Compiling endian-type v0.1.2 [INFO] [stdout] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stdout] --> bittorrent/src/buf_pool.rs:22:23 [INFO] [stdout] | [INFO] [stdout] 22 | pub fn get_buffer(&mut self) -> Buffer { [INFO] [stdout] | ^^^^^^^^^ ------ the lifetime gets resolved as `'_` [INFO] [stdout] | | [INFO] [stdout] | this lifetime flows to the output [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stdout] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stdout] | [INFO] [stdout] 22 | pub fn get_buffer(&mut self) -> Buffer<'_> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Compiling tokio-stream v0.1.17 [INFO] [stderr] Compiling radix_trie v0.2.1 [INFO] [stderr] Compiling utp-socket v0.1.1 (/opt/rustwide/workdir/utp-socket) [INFO] [stderr] Compiling ordered-float v4.6.0 [INFO] [stderr] Compiling btoi v0.4.3 [INFO] [stderr] Compiling num-integer v0.1.46 [INFO] [stderr] Compiling serde_bencoded v0.3.2 [INFO] [stderr] Compiling vortex-dht v0.1.1 (/opt/rustwide/workdir/dht) [INFO] [stderr] Compiling num-bigint v0.4.6 [INFO] [stderr] Compiling metrics-util v0.19.1 [INFO] [stderr] Compiling metrics-exporter-prometheus v0.17.0 [INFO] [stderr] Compiling metrics-exporter-prometheus v0.16.2 [INFO] [stdout] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stdout] --> dht/src/krpc.rs:124:17 [INFO] [stdout] | [INFO] [stdout] 124 | pub fn ping(&self, body: Ping) -> QueryBuilder { [INFO] [stdout] | ^^^^^ ------------------ the lifetime gets resolved as `'_` [INFO] [stdout] | | [INFO] [stdout] | this lifetime flows to the output [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stdout] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stdout] | [INFO] [stdout] 124 | pub fn ping(&self, body: Ping) -> QueryBuilder<'_, Ping> { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stdout] --> dht/src/krpc.rs:134:23 [INFO] [stdout] | [INFO] [stdout] 134 | pub fn find_nodes(&self, body: FindNodes) -> QueryBuilder { [INFO] [stdout] | ^^^^^ ----------------------- the lifetime gets resolved as `'_` [INFO] [stdout] | | [INFO] [stdout] | this lifetime flows to the output [INFO] [stdout] | [INFO] [stdout] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stdout] | [INFO] [stdout] 134 | pub fn find_nodes(&self, body: FindNodes) -> QueryBuilder<'_, FindNodes> { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stdout] --> dht/src/krpc.rs:144:22 [INFO] [stdout] | [INFO] [stdout] 144 | pub fn get_peers(&self, body: GetPeers) -> QueryBuilder { [INFO] [stdout] | ^^^^^ ---------------------- the lifetime gets resolved as `'_` [INFO] [stdout] | | [INFO] [stdout] | this lifetime flows to the output [INFO] [stdout] | [INFO] [stdout] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stdout] | [INFO] [stdout] 144 | pub fn get_peers(&self, body: GetPeers) -> QueryBuilder<'_, GetPeers> { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stdout] --> dht/src/krpc.rs:154:26 [INFO] [stdout] | [INFO] [stdout] 154 | pub fn announce_peer(&self, body: AnnouncePeer) -> QueryBuilder { [INFO] [stdout] | ^^^^^ -------------------------- the lifetime gets resolved as `'_` [INFO] [stdout] | | [INFO] [stdout] | this lifetime flows to the output [INFO] [stdout] | [INFO] [stdout] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stdout] | [INFO] [stdout] 154 | pub fn announce_peer(&self, body: AnnouncePeer) -> QueryBuilder<'_, AnnouncePeer> { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stdout] --> dht/src/krpc.rs:124:17 [INFO] [stdout] | [INFO] [stdout] 124 | pub fn ping(&self, body: Ping) -> QueryBuilder { [INFO] [stdout] | ^^^^^ ------------------ the lifetime gets resolved as `'_` [INFO] [stdout] | | [INFO] [stdout] | this lifetime flows to the output [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stdout] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stdout] | [INFO] [stdout] 124 | pub fn ping(&self, body: Ping) -> QueryBuilder<'_, Ping> { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stdout] --> dht/src/krpc.rs:134:23 [INFO] [stdout] | [INFO] [stdout] 134 | pub fn find_nodes(&self, body: FindNodes) -> QueryBuilder { [INFO] [stdout] | ^^^^^ ----------------------- the lifetime gets resolved as `'_` [INFO] [stdout] | | [INFO] [stdout] | this lifetime flows to the output [INFO] [stdout] | [INFO] [stdout] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stdout] | [INFO] [stdout] 134 | pub fn find_nodes(&self, body: FindNodes) -> QueryBuilder<'_, FindNodes> { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stdout] --> dht/src/krpc.rs:144:22 [INFO] [stdout] | [INFO] [stdout] 144 | pub fn get_peers(&self, body: GetPeers) -> QueryBuilder { [INFO] [stdout] | ^^^^^ ---------------------- the lifetime gets resolved as `'_` [INFO] [stdout] | | [INFO] [stdout] | this lifetime flows to the output [INFO] [stdout] | [INFO] [stdout] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stdout] | [INFO] [stdout] 144 | pub fn get_peers(&self, body: GetPeers) -> QueryBuilder<'_, GetPeers> { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stdout] --> dht/src/krpc.rs:154:26 [INFO] [stdout] | [INFO] [stdout] 154 | pub fn announce_peer(&self, body: AnnouncePeer) -> QueryBuilder { [INFO] [stdout] | ^^^^^ -------------------------- the lifetime gets resolved as `'_` [INFO] [stdout] | | [INFO] [stdout] | this lifetime flows to the output [INFO] [stdout] | [INFO] [stdout] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stdout] | [INFO] [stdout] 154 | pub fn announce_peer(&self, body: AnnouncePeer) -> QueryBuilder<'_, AnnouncePeer> { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Compiling vortex-bittorrent v0.2.0 (/opt/rustwide/workdir/bittorrent) [INFO] [stderr] Compiling vortex-cli v0.1.0 (/opt/rustwide/workdir/cli) [INFO] [stdout] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stdout] --> bittorrent/src/buf_pool.rs:22:23 [INFO] [stdout] | [INFO] [stdout] 22 | pub fn get_buffer(&mut self) -> Buffer { [INFO] [stdout] | ^^^^^^^^^ ------ the lifetime gets resolved as `'_` [INFO] [stdout] | | [INFO] [stdout] | this lifetime flows to the output [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stdout] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stdout] | [INFO] [stdout] 22 | pub fn get_buffer(&mut self) -> Buffer<'_> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 27.99s [INFO] running `Command { std: "docker" "inspect" "d64722d2bc639f64751f8a7880a7c24c86ba7dcdfeaf8d6da4376c456a5baa2c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "d64722d2bc639f64751f8a7880a7c24c86ba7dcdfeaf8d6da4376c456a5baa2c", kill_on_drop: false }` [INFO] [stdout] d64722d2bc639f64751f8a7880a7c24c86ba7dcdfeaf8d6da4376c456a5baa2c [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+0936b3db0ae648ba2d5b60eb08cf4faf2ddab0c6" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 1eb3347c0d9b7c4c69a85016cf78330bf981161a155084b5975a8d3fef5ce391 [INFO] running `Command { std: "docker" "start" "-a" "1eb3347c0d9b7c4c69a85016cf78330bf981161a155084b5975a8d3fef5ce391", kill_on_drop: false }` [INFO] [stderr] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stderr] --> bittorrent/src/buf_pool.rs:22:23 [INFO] [stderr] | [INFO] [stderr] 22 | pub fn get_buffer(&mut self) -> Buffer { [INFO] [stderr] | ^^^^^^^^^ ------ the lifetime gets resolved as `'_` [INFO] [stderr] | | [INFO] [stderr] | this lifetime flows to the output [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stderr] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stderr] | [INFO] [stderr] 22 | pub fn get_buffer(&mut self) -> Buffer<'_> { [INFO] [stderr] | ++++ [INFO] [stderr] [INFO] [stderr] warning: `vortex-bittorrent` (lib) generated 1 warning [INFO] [stderr] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stderr] --> dht/src/krpc.rs:124:17 [INFO] [stderr] | [INFO] [stderr] 124 | pub fn ping(&self, body: Ping) -> QueryBuilder { [INFO] [stderr] | ^^^^^ ------------------ the lifetime gets resolved as `'_` [INFO] [stderr] | | [INFO] [stderr] | this lifetime flows to the output [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stderr] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stderr] | [INFO] [stderr] 124 | pub fn ping(&self, body: Ping) -> QueryBuilder<'_, Ping> { [INFO] [stderr] | +++ [INFO] [stderr] [INFO] [stderr] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stderr] --> dht/src/krpc.rs:134:23 [INFO] [stderr] | [INFO] [stderr] 134 | pub fn find_nodes(&self, body: FindNodes) -> QueryBuilder { [INFO] [stderr] | ^^^^^ ----------------------- the lifetime gets resolved as `'_` [INFO] [stderr] | | [INFO] [stderr] | this lifetime flows to the output [INFO] [stderr] | [INFO] [stderr] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stderr] | [INFO] [stderr] 134 | pub fn find_nodes(&self, body: FindNodes) -> QueryBuilder<'_, FindNodes> { [INFO] [stderr] | +++ [INFO] [stderr] [INFO] [stderr] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stderr] --> dht/src/krpc.rs:144:22 [INFO] [stderr] | [INFO] [stderr] 144 | pub fn get_peers(&self, body: GetPeers) -> QueryBuilder { [INFO] [stderr] | ^^^^^ ---------------------- the lifetime gets resolved as `'_` [INFO] [stderr] | | [INFO] [stderr] | this lifetime flows to the output [INFO] [stderr] | [INFO] [stderr] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stderr] | [INFO] [stderr] 144 | pub fn get_peers(&self, body: GetPeers) -> QueryBuilder<'_, GetPeers> { [INFO] [stderr] | +++ [INFO] [stderr] [INFO] [stderr] warning: lifetime flowing from input to output with different syntax can be confusing [INFO] [stderr] --> dht/src/krpc.rs:154:26 [INFO] [stderr] | [INFO] [stderr] 154 | pub fn announce_peer(&self, body: AnnouncePeer) -> QueryBuilder { [INFO] [stderr] | ^^^^^ -------------------------- the lifetime gets resolved as `'_` [INFO] [stderr] | | [INFO] [stderr] | this lifetime flows to the output [INFO] [stderr] | [INFO] [stderr] help: one option is to remove the lifetime for references and use the anonymous lifetime for paths [INFO] [stderr] | [INFO] [stderr] 154 | pub fn announce_peer(&self, body: AnnouncePeer) -> QueryBuilder<'_, AnnouncePeer> { [INFO] [stderr] | +++ [INFO] [stderr] [INFO] [stderr] warning: `vortex-dht` (lib) generated 4 warnings [INFO] [stderr] warning: `vortex-dht` (lib test) generated 4 warnings (4 duplicates) [INFO] [stderr] warning: `vortex-bittorrent` (lib test) generated 1 warning (1 duplicate) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.24s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/utp_socket-33a5a246abc830ea) [INFO] [stdout] [INFO] [stdout] running 9 tests [INFO] [stdout] test reorder_buffer::test::insertion_orderd_large_gap ... ok [INFO] [stdout] test reorder_buffer::test::index_collision ... ok [INFO] [stdout] test reorder_buffer::test::removal ... ok [INFO] [stdout] test reorder_buffer::test::removal_of_last_with_wraparound ... ok [INFO] [stdout] test reorder_buffer::test::insertion_orderd ... ok [INFO] [stdout] test reorder_buffer::test::insertion_unorderd ... ok [INFO] [stdout] test reorder_buffer::test::insertion_unorderd_large_gap ... ok [INFO] [stdout] test reorder_buffer::test::removal_of_last_with_wraparound_v2 ... ok [INFO] [stdout] test reorder_buffer::test::resizing ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s [INFO] [stdout] [INFO] [stderr] Running tests/end_to_end.rs (/opt/rustwide/target/debug/deps/end_to_end-dd2c0d9bf4be3575) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test end_to_end ... ignored, Flakey on ci [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/vortex_bittorrent-21a87eb214e91fb0) [INFO] [stdout] [INFO] [stdout] running 52 tests [INFO] [stdout] test file_store::tests::basic_single_file_aligned ... ok [INFO] [stdout] test file_store::tests::errors_on_invalid_piece_index ... ok [INFO] [stdout] test file_store::tests::basic_single_file_aligned_unaligned_subpiece ... ok [INFO] [stdout] test file_store::tests::multifile_misalinged_v2 ... ok [INFO] [stdout] test file_store::tests::single_file_misaligned ... ok [INFO] [stdout] test file_store::tests::single_file_misaligned_v2 ... ok [INFO] [stdout] test io_utils::tests::test_user_data_roundtrip ... ok [INFO] [stdout] test peer_comm::peer_protocol::tests::bitfield ... ok [INFO] [stdout] test file_store::tests::multifile_not_multiple_of_piece_size ... ok [INFO] [stdout] test file_store::tests::multifile_misalinged_v3 ... ok [INFO] [stdout] test peer_comm::peer_protocol::tests::empty_bitfield ... ok [INFO] [stdout] test peer_comm::peer_protocol::tests::fuzz_encoded_length_bug ... ok [INFO] [stdout] test peer_comm::peer_protocol::tests::bitfield_roundtrip ... ok [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x3d0x380xab0xac0x5a0x920x290x430xc10xa20x6b0x57] Have all received [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 5 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x3d0x380xab0xac0x5a0x920x290x430xc10xa20x6b0x57] Recived a piece index: 5, begin: 0, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 5, 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x3d0x380xab0xac0x5a0x920x290x430xc10xa20x6b0x57] Recived a piece index: 5, begin: 16384, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 5, 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 1 [INFO] [stdout] test file_store::tests::small_multifile_misalinged_files_and_subpiece ... ok [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Piece 5 download completed, sending to hash thread [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::event_loop] Tick!: 1 [INFO] [stderr] [2025-06-28T09:54:03Z ERROR vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x3f0xbc0xed0xd0x1a0x530xb80xda0xb70x200x7f0x86] Received invalid bitfield, expected 9, got: 8 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x2f0xc40x720xa80x880x310x920x4d0x150x7d0x5f0x5a] Bifield received [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x660x840x600x8c0x140x5e0xe70x840xab0x5b0x3d0x7b] Bifield received [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x40x130x250x760x8a0x3b0x270xe90x270x4d0x750x89] Bifield received [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 3 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 2 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 0 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 4 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::event_loop] Tick!: 1 [INFO] [stdout] test file_store::tests::small_multifile_misalinged ... ok [INFO] [stdout] test peer_comm::tests::bitfield_recv ... ok [INFO] [stdout] test file_store::tests::multifile_misalinged ... ok [INFO] [stdout] test peer_comm::tests::desired_queue_size ... ok [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x420x10x1b0x840x280x720xbf0xb40x540xe60xc10x20] Have all received [INFO] [stdout] test file_store::tests::basic_multifile_alinged ... ok [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x260xe30x920xa40x4a0x360x710x2f0x110xd10x920xe5] Have all received [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x690x350x1e0x2c0x550x3d0xd90xa00x780x50x4f0xfc] Have all received [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 1 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x260xe30x920xa40x4a0x360x710x2f0x110xd10x920xe5] Recived a piece index: 1, begin: 0, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 1, 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x260xe30x920xa40x4a0x360x710x2f0x110xd10x920xe5] Recived a piece index: 1, begin: 16384, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 1, 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 1 [INFO] [stdout] test peer_comm::tests::assume_intrest_when_request_recv ... ok [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Piece 1 download completed, sending to hash thread [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 2 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 3 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x260xe30x920xa40x4a0x360x710x2f0x110xd10x920xe5] Recived a piece index: 3, begin: 0, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 3, 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x260xe30x920xa40x4a0x360x710x2f0x110xd10x920xe5] Recived a piece index: 3, begin: 16384, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 3, 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 1 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Piece 3 download completed, sending to hash thread [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 4 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Extended message handshake [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Extended message handshake [INFO] [stdout] test peer_comm::tests::extension_handshake_with_reqq ... ok [INFO] [stderr] [2025-06-28T09:54:03Z ERROR vortex_bittorrent::peer_comm::peer_connection] Unexpected extended msg [INFO] [stdout] test peer_comm::tests::extension_message_unknown_id ... ok [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Extended message handshake [INFO] [stdout] test peer_comm::tests::extension_handshake_malformed ... ok [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Piece 3 completed! [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Piece 1 completed! [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 1, index: 5 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 1, index: 6 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 1, index: 7 [INFO] [stdout] test peer_comm::tests::extension_handshake_generates_correct_message ... ok [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::piece_selector] Peer 0 is entering endgame mode [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 5 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::piece_selector] Peer 0 is entering endgame mode [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 6 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::piece_selector] Peer 0 is entering endgame mode [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 7 [INFO] [stdout] test peer_comm::tests::extension_handshake_missing_m_field ... ok [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Extended message handshake [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xcd0x8e0x7c0xe50x750xcb0xbc0xf90x690x130xe30x54] Have all received [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xfc0xd50x400xe90xca0x180x330xc30x4d0x770xf80x3a] Have all received [INFO] [stdout] test peer_comm::tests::endgame_mode ... ok [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x360x90x820x7c0x5f0x60x510xff0x340x3c0x8d0x7] Have None received [INFO] [stdout] test peer_comm::tests::extension_protocol_handshake ... ok [INFO] [stdout] test peer_comm::tests::fast_ext_have_all ... ok [INFO] [stdout] test peer_comm::tests::fast_ext_have_none ... ok [INFO] [stdout] test peer_comm::tests::have_invalid_indicies ... ok [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x5f0x9e0xf90x580x7f0x6a0xa80x280x4c0xc70xab0x85] Have all received [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 5 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x5f0x9e0xf90x580x7f0x6a0xa80x280x4c0xc70xab0x85] Recived a piece index: 5, begin: 0, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 5, 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 0 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x420x980xe10x5f0x9c0x6e0x940x200x660x8e0x880x92] Peer have piece with index: 7 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x420x980xe10x5f0x9c0x6e0x940x200x660x8e0x880x92] Peer have piece with index: 7 [INFO] [stderr] [2025-06-28T09:54:03Z WARN vortex_bittorrent::piece_selector] Random piece selection failed [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 7 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x450x520x550xee0x5c0x450x5b0xcc0xf50x240x7c0x56] Peer have piece with index: 7 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x420x980xe10x5f0x9c0x6e0x940x200x660x8e0x880x92] Recived a piece index: 7, begin: 0, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 7, 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x420x980xe10x5f0x9c0x6e0x940x200x660x8e0x880x92] Recived a piece index: 7, begin: 16384, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 7, 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 1 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Piece 7 download completed, sending to hash thread [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Extended message handshake [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::extended_protocol] Got metadata extension message of type: 1 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x5f0xc50x350xb60xc00x6e0xb80x3c0xa10x1c0x7a0xb] Have all received [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x590x710xe10xf00x8a0x230x620x6e0x60x830x150x9a] Peer have piece with index: 8 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x180x2e0x870x3c0x550x6e0x760xa00x9a0xa60xff0xb9] Bifield received [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 2 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 4 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x180x2e0x870x3c0x550x6e0x760xa00x9a0xa60xff0xb9] Recived a piece index: 2, begin: 0, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 2, 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 0 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 2 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xe60x7c0x5e0x7f0x460x420xf70xe00x240x860xdf0x5b] Have all received [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 1, index: 2 [INFO] [stderr] [2025-06-28T09:54:03Z ERROR vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xe60x7c0x5e0x7f0x460x420xf70xe00x240x860xdf0x5b] Piece Reject request was invalid, index=-2 begin=0 length=16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::extended_protocol] Got metadata extension message of type: 1 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::extended_protocol] Got metadata extension message of type: 1 [INFO] [stderr] [2025-06-28T09:54:03Z ERROR vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xe60x7c0x5e0x7f0x460x420xf70xe00x240x860xdf0x5b] Piece Reject request was invalid, index=2 begin=16385 length=16384 [INFO] [stderr] [2025-06-28T09:54:03Z ERROR vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xe60x7c0x5e0x7f0x460x420xf70xe00x240x860xdf0x5b] Piece Reject request was invalid, index=2 begin=0 length=16385 [INFO] [stderr] [2025-06-28T09:54:03Z WARN vortex_bittorrent::peer_comm::peer_connection] [PeerId: -VT0020-0x460x8a0x150xd80x310xbd0xf00x330x5e0x970x1c0x41] (HaveAll) Bitfield already received [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x460x8a0x150xd80x310xbd0xf00x330x5e0x970x1c0x41] Have all received [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x180x2e0x870x3c0x550x6e0x760xa00x9a0xa60xff0xb9] Recived a piece index: 2, begin: 16384, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 2, 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 1 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Piece 2 download completed, sending to hash thread [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 2 [INFO] [stderr] [2025-06-28T09:54:03Z WARN vortex_bittorrent::peer_comm::peer_connection] [PeerId: -VT0020-0x600x1e0xa70xc80x740xa70xf40xe90x290x830x6b0x9b] (HaveAll) Bitfield already received [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x600x1e0xa70xc80x740xa70xf40xe90x290x830x6b0x9b] Have all received [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 2 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x5f0x9e0xf90x580x7f0x6a0xa80x280x4c0xc70xab0x85] Recived a piece index: 5, begin: 0, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z ERROR vortex_bittorrent::peer_comm::peer_connection] Received unexpected piece message, index: 5 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x5f0x9e0xf90x580x7f0x6a0xa80x280x4c0xc70xab0x85] Recived a piece index: 5, begin: 16384, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 5, 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 1 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Piece 5 download completed, sending to hash thread [INFO] [stdout] test peer_comm::tests::have_without_interest ... ok [INFO] [stdout] test peer_comm::tests::invalid_reject_request ... ok [INFO] [stdout] test peer_comm::tests::invalid_piece ... ok [INFO] [stdout] test peer_comm::tests::metadata_download_multiple_pieces ... ok [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Piece 7 completed! [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x920xf40xbd0x720xc90x520xac0x990x10x3e0x5d0x3] Peer have piece with index: 7 [INFO] [stdout] test peer_comm::tests::have ... ok [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Extended message handshake [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Extended message handshake [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::extended_protocol] Got metadata extension message of type: 99 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Extended message handshake [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::extended_protocol] Got metadata extension message of type: 1 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::extended_protocol] Got metadata extension message of type: 1 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Extended message handshake [INFO] [stderr] [2025-06-28T09:54:03Z ERROR vortex_bittorrent::peer_comm::extended_protocol] Got metadata extension unknown type: 99 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::extended_protocol] Got metadata extension message of type: 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::extended_protocol] Got metadata extension message of type: 0 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Extended message handshake [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Piece 2 completed! [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x180x2e0x870x3c0x550x6e0x760xa00x9a0xa60xff0xb9] Recived a piece index: 4, begin: 0, length: 16384 [INFO] [stdout] test peer_comm::tests::metadata_extension_data_message ... ok [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::extended_protocol] Got metadata extension message of type: 2 [INFO] [stdout] test peer_comm::tests::metadata_extension_invalid_message_type ... ok [INFO] [stderr] [2025-06-28T09:54:03Z WARN vortex_bittorrent::peer_comm::extended_protocol] Got reject request [INFO] [stdout] test peer_comm::tests::metadata_extension_piece_bounds_validation ... ok [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 4, 0 [INFO] [stdout] test peer_comm::tests::metadata_download_single_piece ... ok [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 0 [INFO] [stdout] test peer_comm::tests::metadata_extension_reject_message ... ok [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x180x2e0x870x3c0x550x6e0x760xa00x9a0xa60xff0xb9] Recived a piece index: 4, begin: 16384, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 4, 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 1 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Piece 4 download completed, sending to hash thread [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x520x520xb30xee0xe50x9c0x3d0x650xc30x40xe70x78] Have all received [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 5 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x520x520xb30xee0xe50x9c0x3d0x650xc30x40xe70x78] Recived a piece index: 5, begin: 0, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 5, 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x520x520xb30xee0xe50x9c0x3d0x650xc30x40xe70x78] Recived a piece index: 5, begin: 16384, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 5, 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 1 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Piece 5 download completed, sending to hash thread [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x270x930xfa0xb70xf70xbb0xe80x740x180xe60xbf0x98] Peer have piece with index: 1 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x270x930xfa0xb70xf70xbb0xe80x740x180xe60xbf0x98] Peer have piece with index: 2 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x270x930xfa0xb70xf70xbb0xe80x740x180xe60xbf0x98] Peer have piece with index: 3 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x270x930xfa0xb70xf70xbb0xe80x740x180xe60xbf0x98] Peer have piece with index: 4 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x270x930xfa0xb70xf70xbb0xe80x740x180xe60xbf0x98] Peer have piece with index: 5 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x270x930xfa0xb70xf70xbb0xe80x740x180xe60xbf0x98] Peer have piece with index: 6 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x270x930xfa0xb70xf70xbb0xe80x740x180xe60xbf0x98] Unchoked and start downloading [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 5 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 3 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 2 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 4 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 1 [INFO] [stderr] [2025-06-28T09:54:03Z WARN vortex_bittorrent::piece_selector] Random piece selection failed [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 6 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x270x930xfa0xb70xf70xbb0xe80x740x180xe60xbf0x98] Recived a piece index: 5, begin: 0, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 5, 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x270x930xfa0xb70xf70xbb0xe80x740x180xe60xbf0x98] Recived a piece index: 5, begin: 16384, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 5, 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 1 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Piece 5 download completed, sending to hash thread [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::event_loop] Tick!: 0.65 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x270x930xfa0xb70xf70xbb0xe80x740x180xe60xbf0x98] Peer is choking us! [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Piece 5 completed! [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x5f0x9e0xf90x580x7f0x6a0xa80x280x4c0xc70xab0x85] Recived a piece index: 5, begin: 16384, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z ERROR vortex_bittorrent::peer_comm::peer_connection] Received unexpected piece message, index: 5 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Deallocating piece: conn_id: 0, index: 6 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] marked as not allocated: conn_id: 0, index: 6 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x550x340xb60x80x750xff0x5c0x680x650xe40x4d0x16] Peer have piece with index: 1 [INFO] [stdout] test peer_comm::tests::handles_duplicate_piece_recv ... ok [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x550x340xb60x80x750xff0x5c0x680x650xe40x4d0x16] Peer have piece with index: 2 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x550x340xb60x80x750xff0x5c0x680x650xe40x4d0x16] Peer have piece with index: 3 [INFO] [stdout] test peer_comm::tests::peer_choke_recv_supports_fast ... ok [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x550x340xb60x80x750xff0x5c0x680x650xe40x4d0x16] Peer have piece with index: 4 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x550x340xb60x80x750xff0x5c0x680x650xe40x4d0x16] Peer have piece with index: 5 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x550x340xb60x80x750xff0x5c0x680x650xe40x4d0x16] Peer have piece with index: 6 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x550x340xb60x80x750xff0x5c0x680x650xe40x4d0x16] Unchoked and start downloading [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 5 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Extended message handshake [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 3 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 2 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::extended_protocol] Got metadata extension message of type: 0 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 4 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 1 [INFO] [stderr] [2025-06-28T09:54:03Z WARN vortex_bittorrent::piece_selector] Random piece selection failed [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 6 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x550x340xb60x80x750xff0x5c0x680x650xe40x4d0x16] Recived a piece index: 5, begin: 0, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 5, 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x550x340xb60x80x750xff0x5c0x680x650xe40x4d0x16] Recived a piece index: 5, begin: 16384, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 5, 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 1 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Piece 5 download completed, sending to hash thread [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::event_loop] Tick!: 0.65 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x550x340xb60x80x750xff0x5c0x680x650xe40x4d0x16] Peer is choking us! [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Deallocating piece: conn_id: 0, index: 6 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] marked as not allocated: conn_id: 0, index: 6 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Deallocating piece: conn_id: 0, index: 3 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] marked as not allocated: conn_id: 0, index: 3 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Deallocating piece: conn_id: 0, index: 2 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] marked as not allocated: conn_id: 0, index: 2 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Deallocating piece: conn_id: 0, index: 4 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] marked as not allocated: conn_id: 0, index: 4 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Deallocating piece: conn_id: 0, index: 1 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] marked as not allocated: conn_id: 0, index: 1 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xc60xfa0x3d0xc80x590x710x6e0x6b0xdb0xbc0x210xb] Have all received [INFO] [stdout] test peer_comm::tests::metadata_extension_request_message ... ok [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 5 [INFO] [stderr] [2025-06-28T09:54:03Z WARN vortex_bittorrent::peer_comm::peer_connection] [PeerId -VT0020-0xc60xfa0x3d0xc80x590x710x6e0x6b0xdb0xbc0x210xb]: Subpiece request rejected: 5, 0 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Deallocating piece: conn_id: 0, index: 5 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] marked as not allocated: conn_id: 0, index: 5 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 3 [INFO] [stdout] test peer_comm::tests::reject_request_requests_new ... ok [INFO] [stdout] test peer_comm::tests::peer_choke_recv_does_not_support_fast ... ok [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xbc0x620xa10x290x280x420xba0x200x300xf70x6a0x8c] Bifield received [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x680x40xf50x9b0x920x1a0x110x160x2b0xaf0x3b0x9e] Bifield received [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 2 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 1, index: 4 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xbc0x620xa10x290x280x420xba0x200x300xf70x6a0x8c] Recived a piece index: 2, begin: 0, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 2, 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xbc0x620xa10x290x280x420xba0x200x300xf70x6a0x8c] Recived a piece index: 2, begin: 16384, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 2, 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 1 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Piece 2 download completed, sending to hash thread [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x1e0x400xaf0x130xc90xbc0x330xad0x3a0x5c0x4c0xc0] Have all received [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 5 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x1e0x400xaf0x130xc90xbc0x330xad0x3a0x5c0x4c0xc0] Recived a piece index: 5, begin: 0, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 5, 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 0 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::event_loop] Tick!: 1 [INFO] [stderr] [2025-06-28T09:54:03Z ERROR vortex_bittorrent::event_loop] TIMEOUT: -VT0020-0x1e0x400xaf0x130xc90xbc0x330xad0x3a0x5c0x4c0xc0 [INFO] [stderr] [2025-06-28T09:54:03Z WARN vortex_bittorrent::peer_comm::peer_connection] [PeerId -VT0020-0x1e0x400xaf0x130xc90xbc0x330xad0x3a0x5c0x4c0xc0]: Subpiece timed out: 5, 16384 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Deallocating piece: conn_id: 0, index: 5 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] marked as not allocated: conn_id: 0, index: 5 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 3 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x1e0x400xaf0x130xc90xbc0x330xad0x3a0x5c0x4c0xc0] Recived a piece index: 3, begin: 0, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 3, 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 0 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::event_loop] Tick!: 1 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 5 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 2 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Piece 4 completed! [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x770x00xe40x170x2e0xc80x340xd80xa00x8d0xa50xe9] Have all received [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x770x00xe40x170x2e0xc80x340xd80xa00x8d0xa50xe9] Unchoked and start downloading [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 1, index: 5 [INFO] [stdout] test peer_comm::tests::snubbed_peer ... ok [INFO] [stdout] test peer_comm::tests::interest_is_updated_when_recv_piece ... ok [INFO] [stdout] test peer_comm::tests::unchoke_recv ... ok [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::event_loop] Tick!: 1 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xf40x490x7e0x8f0xba0xe60x6b0x260x2e0x810x180xd7] Peer have piece with index: 1 [INFO] [stderr] [2025-06-28T09:54:03Z WARN vortex_bittorrent::piece_selector] Random piece selection failed [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 1 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xf40x490x7e0x8f0xba0xe60x6b0x260x2e0x810x180xd7] Recived a piece index: 1, begin: 0, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 1, 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xf40x490x7e0x8f0xba0xe60x6b0x260x2e0x810x180xd7] Recived a piece index: 1, begin: 16384, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 1, 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 1 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Piece 1 download completed, sending to hash thread [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::event_loop] Tick!: 1.5 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xf40x490x7e0x8f0xba0xe60x6b0x260x2e0x810x180xd7] Peer have piece with index: 2 [INFO] [stderr] [2025-06-28T09:54:03Z WARN vortex_bittorrent::piece_selector] Random piece selection failed [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 2 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Piece 5 completed! [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xf40x490x7e0x8f0xba0xe60x6b0x260x2e0x810x180xd7] Recived a piece index: 2, begin: 0, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 2, 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xf40x490x7e0x8f0xba0xe60x6b0x260x2e0x810x180xd7] Recived a piece index: 2, begin: 16384, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 2, 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 1 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Piece 2 download completed, sending to hash thread [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::event_loop] Tick!: 1 [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xf40x490x7e0x8f0xba0xe60x6b0x260x2e0x810x180xd7] Peer have piece with index: 3 [INFO] [stderr] [2025-06-28T09:54:03Z WARN vortex_bittorrent::piece_selector] Random piece selection failed [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Allocating piece: conn_id: 0, index: 3 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xf40x490x7e0x8f0xba0xe60x6b0x260x2e0x810x180xd7] Recived a piece index: 3, begin: 0, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 3, 0 [INFO] [stdout] test peer_comm::tests::piece_recv ... ok [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0xf40x490x7e0x8f0xba0xe60x6b0x260x2e0x810x180xd7] Recived a piece index: 3, begin: 16384, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 3, 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 1 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Piece 3 download completed, sending to hash thread [INFO] [stderr] [2025-06-28T09:54:03Z INFO vortex_bittorrent::event_loop] Tick!: 1 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::event_loop] [Peer -VT0020-0xf40x490x7e0x8f0xba0xe60x6b0x260x2e0x810x180xd7] Exiting slow start [INFO] [stdout] test peer_comm::tests::slow_start ... ok [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent] Piece 2 completed! [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x680x40xf50x9b0x920x1a0x110x160x2b0xaf0x3b0x9e] Recived a piece index: 4, begin: 0, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 4, 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 0 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] [Peer: -VT0020-0x680x40xf50x9b0x920x1a0x110x160x2b0xaf0x3b0x9e] Recived a piece index: 4, begin: 16384, length: 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::peer_comm::peer_connection] Subpiece completed: 4, 16384 [INFO] [stderr] [2025-06-28T09:54:03Z TRACE vortex_bittorrent::piece_selector] Subpiece index received: 1 [INFO] [stderr] [2025-06-28T09:54:03Z DEBUG vortex_bittorrent::peer_comm::peer_connection] Piece 4 download completed, sending to hash thread [INFO] [stderr] [2025-06-28T09:54:04Z DEBUG vortex_bittorrent] Piece 4 completed! [INFO] [stdout] test peer_comm::tests::send_have_to_peers_when_piece_completes ... ok [INFO] [stdout] test event_loop::tests::handshake_timeout has been running for over 60 seconds [ERROR] error running command: no output for 300 seconds [INFO] running `Command { std: "docker" "inspect" "1eb3347c0d9b7c4c69a85016cf78330bf981161a155084b5975a8d3fef5ce391", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "1eb3347c0d9b7c4c69a85016cf78330bf981161a155084b5975a8d3fef5ce391", kill_on_drop: false }` [INFO] [stdout] 1eb3347c0d9b7c4c69a85016cf78330bf981161a155084b5975a8d3fef5ce391