[INFO] fetching crate perftool 0.2.0...
[INFO] testing perftool-0.2.0 against master#caccb4d0368bd918ef6668af8e13834d07040417 for pr-146098-1
[INFO] extracting crate perftool 0.2.0 into /workspace/builds/worker-5-tc1/source
[INFO] started tweaking crates.io crate perftool 0.2.0
[INFO] finished tweaking crates.io crate perftool 0.2.0
[INFO] tweaked toml for crates.io crate perftool 0.2.0 written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate perftool 0.2.0 on toolchain caccb4d0368bd918ef6668af8e13834d07040417
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+caccb4d0368bd918ef6668af8e13834d07040417" "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" "+caccb4d0368bd918ef6668af8e13834d07040417" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]      Locking 63 packages to latest compatible versions
[INFO] [stderr]       Adding env_logger v0.8.4 (available: v0.11.8)
[INFO] [stderr]       Adding rand v0.8.5 (available: v0.9.2)
[INFO] [stderr]       Adding tokio v0.3.7 (available: v1.47.1)
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+caccb4d0368bd918ef6668af8e13834d07040417" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded tokio-macros v0.3.2
[INFO] [stderr]   Downloaded bytes v0.6.0
[INFO] [stderr]   Downloaded tokio v0.3.7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+caccb4d0368bd918ef6668af8e13834d07040417" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 35e074f0ef37e07e18cac5a67ca529cc3812bb152b117e77ad6811becfc5bea2
[INFO] running `Command { std: "docker" "start" "-a" "35e074f0ef37e07e18cac5a67ca529cc3812bb152b117e77ad6811becfc5bea2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "35e074f0ef37e07e18cac5a67ca529cc3812bb152b117e77ad6811becfc5bea2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "35e074f0ef37e07e18cac5a67ca529cc3812bb152b117e77ad6811becfc5bea2", kill_on_drop: false }`
[INFO] [stdout] 35e074f0ef37e07e18cac5a67ca529cc3812bb152b117e77ad6811becfc5bea2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+caccb4d0368bd918ef6668af8e13834d07040417" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] cf31fddc5a98fb0d7d93d7a4b0ab50fd0f3df0b510e4a1da86da1993ab4f3fe5
[INFO] running `Command { std: "docker" "start" "-a" "cf31fddc5a98fb0d7d93d7a4b0ab50fd0f3df0b510e4a1da86da1993ab4f3fe5", kill_on_drop: false }`
[INFO] [stderr]    Compiling instant v0.1.13
[INFO] [stderr]    Compiling tokio v0.3.7
[INFO] [stderr]    Compiling regex-automata v0.4.11
[INFO] [stderr]    Compiling mio v0.7.14
[INFO] [stderr]    Compiling bytes v0.6.0
[INFO] [stderr]    Compiling humantime v2.3.0
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling tokio-macros v0.3.2
[INFO] [stderr]    Compiling parking_lot_core v0.8.6
[INFO] [stderr]    Compiling parking_lot v0.11.2
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling regex v1.11.3
[INFO] [stderr]    Compiling env_logger v0.8.4
[INFO] [stderr]    Compiling futures-executor v0.3.31
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling perftool v0.2.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unused import: `rand::Rng`
[INFO] [stdout]  --> src/lib.rs:4:5
[INFO] [stdout]   |
[INFO] [stdout] 4 | use rand::Rng;
[INFO] [stdout]   |     ^^^^^^^^^
[INFO] [stdout]   |
[INFO] [stdout]   = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 7.88s
[INFO] running `Command { std: "docker" "inspect" "cf31fddc5a98fb0d7d93d7a4b0ab50fd0f3df0b510e4a1da86da1993ab4f3fe5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "cf31fddc5a98fb0d7d93d7a4b0ab50fd0f3df0b510e4a1da86da1993ab4f3fe5", kill_on_drop: false }`
[INFO] [stdout] cf31fddc5a98fb0d7d93d7a4b0ab50fd0f3df0b510e4a1da86da1993ab4f3fe5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+caccb4d0368bd918ef6668af8e13834d07040417" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 11bd8dc18385b0cc1a20eeb0a92263f2703deb9edb204fca803fdaf9662c9a34
[INFO] running `Command { std: "docker" "start" "-a" "11bd8dc18385b0cc1a20eeb0a92263f2703deb9edb204fca803fdaf9662c9a34", kill_on_drop: false }`
[INFO] [stdout] warning: unused import: `rand::Rng`
[INFO] [stdout]  --> src/lib.rs:4:5
[INFO] [stdout]   |
[INFO] [stdout] 4 | use rand::Rng;
[INFO] [stdout]   |     ^^^^^^^^^
[INFO] [stdout]   |
[INFO] [stdout]   = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]    Compiling perftool v0.2.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing
[INFO] [stdout]    --> src/lib.rs:114:19
[INFO] [stdout]     |
[INFO] [stdout] 114 |         fn new(n: &str) -> WorkerInput {
[INFO] [stdout]     |                   ^^^^     ^^^^^^^^^^^ the same lifetime is hidden here
[INFO] [stdout]     |                   |
[INFO] [stdout]     |                   the lifetime is elided here
[INFO] [stdout]     |
[INFO] [stdout]     = help: the same lifetime is referred to in inconsistent ways, making the signature confusing
[INFO] [stdout]     = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default
[INFO] [stdout] help: use `'_` for type paths
[INFO] [stdout]     |
[INFO] [stdout] 114 |         fn new(n: &str) -> WorkerInput<'_> {
[INFO] [stdout]     |                                       ++++
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: panic message is not a string literal
[INFO] [stdout]    --> src/lib.rs:167:44
[INFO] [stdout]     |
[INFO] [stdout] 167 |             Ok(Err(msg)) => assert!(false, msg),
[INFO] [stdout]     |                                            ^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: this usage of `assert!()` is deprecated; it will be a hard error in Rust 2021
[INFO] [stdout]     = note: for more information, see <https://doc.rust-lang.org/edition-guide/rust-2021/panic-macro-consistency.html>
[INFO] [stdout]     = note: `#[warn(non_fmt_panics)]` (part of `#[warn(rust_2021_compatibility)]`) on by default
[INFO] [stdout] help: add a "{}" format string to `Display` the message
[INFO] [stdout]     |
[INFO] [stdout] 167 |             Ok(Err(msg)) => assert!(false, "{}", msg),
[INFO] [stdout]     |                                            +++++
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.05s
[INFO] running `Command { std: "docker" "inspect" "11bd8dc18385b0cc1a20eeb0a92263f2703deb9edb204fca803fdaf9662c9a34", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "11bd8dc18385b0cc1a20eeb0a92263f2703deb9edb204fca803fdaf9662c9a34", kill_on_drop: false }`
[INFO] [stdout] 11bd8dc18385b0cc1a20eeb0a92263f2703deb9edb204fca803fdaf9662c9a34
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+caccb4d0368bd918ef6668af8e13834d07040417" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] afe508f7ad89cea8a2236e58bd5ba0b3bc65b76f98c2ca9058104d14e1f0f0c8
[INFO] running `Command { std: "docker" "start" "-a" "afe508f7ad89cea8a2236e58bd5ba0b3bc65b76f98c2ca9058104d14e1f0f0c8", kill_on_drop: false }`
[INFO] [stderr] warning: unused import: `rand::Rng`
[INFO] [stderr]  --> src/lib.rs:4:5
[INFO] [stderr]   |
[INFO] [stderr] 4 | use rand::Rng;
[INFO] [stderr]   |     ^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stdout] 
[INFO] [stderr] warning: hiding a lifetime that's elided elsewhere is confusing
[INFO] [stdout] running 1 test
[INFO] [stderr]    --> src/lib.rs:114:19
[INFO] [stderr]     |
[INFO] [stderr] 114 |         fn new(n: &str) -> WorkerInput {
[INFO] [stderr]     |                   ^^^^     ^^^^^^^^^^^ the same lifetime is hidden here
[INFO] [stderr]     |                   |
[INFO] [stderr]     |                   the lifetime is elided here
[INFO] [stderr]     |
[INFO] [stderr]     = help: the same lifetime is referred to in inconsistent ways, making the signature confusing
[INFO] [stderr]     = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default
[INFO] [stderr] help: use `'_` for type paths
[INFO] [stderr]     |
[INFO] [stderr] 114 |         fn new(n: &str) -> WorkerInput<'_> {
[INFO] [stderr]     |                                       ++++
[INFO] [stderr] 
[INFO] [stderr] warning: panic message is not a string literal
[INFO] [stderr]    --> src/lib.rs:167:44
[INFO] [stderr]     |
[INFO] [stderr] 167 |             Ok(Err(msg)) => assert!(false, msg),
[INFO] [stderr]     |                                            ^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: this usage of `assert!()` is deprecated; it will be a hard error in Rust 2021
[INFO] [stderr]     = note: for more information, see <https://doc.rust-lang.org/edition-guide/rust-2021/panic-macro-consistency.html>
[INFO] [stderr]     = note: `#[warn(non_fmt_panics)]` (part of `#[warn(rust_2021_compatibility)]`) on by default
[INFO] [stderr] help: add a "{}" format string to `Display` the message
[INFO] [stderr]     |
[INFO] [stderr] 167 |             Ok(Err(msg)) => assert!(false, "{}", msg),
[INFO] [stderr]     |                                            +++++
[INFO] [stderr] 
[INFO] [stderr] warning: `perftool` (lib) generated 1 warning (run `cargo fix --lib -p perftool` to apply 1 suggestion)
[INFO] [stderr] warning: `perftool` (lib test) generated 2 warnings (run `cargo fix --lib -p perftool --tests` to apply 2 suggestions)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.07s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/perftool-8ec2f17fa88dfbf0)
[INFO] [stderr] [2025-10-01T17:21:50Z DEBUG perftool] starting ramp up
[INFO] [stderr] [2025-10-01T17:21:50Z INFO  perftool::tests] test waiting for ramp up to finish
[INFO] [stderr] [2025-10-01T17:21:50Z DEBUG perftool] actual #workers - 0, expected #workers - 1
[INFO] [stderr] [2025-10-01T17:21:50Z INFO  perftool::tests] hello, 145, my name is PETEA
[INFO] [stderr] [2025-10-01T17:21:51Z DEBUG perftool] actual #workers - 1, expected #workers - 2
[INFO] [stderr] [2025-10-01T17:21:51Z INFO  perftool::tests] hello, 193, my name is KOLEA
[INFO] [stderr] [2025-10-01T17:21:51Z DEBUG perftool] actual #workers - 2, expected #workers - 3
[INFO] [stderr] [2025-10-01T17:21:51Z INFO  perftool::tests] hello, 67, my name is PETEA
[INFO] [stderr] [2025-10-01T17:21:52Z DEBUG perftool] actual #workers - 3, expected #workers - 4
[INFO] [stderr] [2025-10-01T17:21:52Z INFO  perftool::tests] hello, 48, my name is KOLEA
[INFO] [stderr] [2025-10-01T17:21:52Z INFO  perftool::tests] I'm done, 145
[INFO] [stderr] [2025-10-01T17:21:52Z DEBUG perftool] actual #workers - 3, expected #workers - 4
[INFO] [stderr] [2025-10-01T17:21:52Z INFO  perftool::tests] hello, 53, my name is KOLEA
[INFO] [stderr] [2025-10-01T17:21:52Z DEBUG perftool] actual #workers - 4, expected #workers - 5
[INFO] [stderr] [2025-10-01T17:21:52Z INFO  perftool::tests] hello, 32, my name is KOLEA
[INFO] [stderr] [2025-10-01T17:21:53Z INFO  perftool::tests] I'm done, 193
[INFO] [stderr] [2025-10-01T17:21:53Z DEBUG perftool] actual #workers - 4, expected #workers - 5
[INFO] [stderr] [2025-10-01T17:21:53Z INFO  perftool::tests] hello, 182, my name is KOLEA
[INFO] [stderr] [2025-10-01T17:21:53Z DEBUG perftool] actual #workers - 5, expected #workers - 6
[INFO] [stderr] [2025-10-01T17:21:53Z INFO  perftool::tests] hello, 237, my name is KOLEA
[INFO] [stderr] [2025-10-01T17:21:53Z INFO  perftool::tests] I'm done, 67
[INFO] [stderr] [2025-10-01T17:21:53Z DEBUG perftool] actual #workers - 5, expected #workers - 6
[INFO] [stderr] [2025-10-01T17:21:53Z INFO  perftool::tests] hello, 30, my name is JORA
[INFO] [stderr] [2025-10-01T17:21:54Z DEBUG perftool] actual #workers - 6, expected #workers - 7
[INFO] [stderr] [2025-10-01T17:21:54Z INFO  perftool::tests] hello, 249, my name is VANEA
[INFO] [stderr] [2025-10-01T17:21:54Z INFO  perftool::tests] I'm done, 48
[INFO] [stderr] [2025-10-01T17:21:54Z DEBUG perftool] actual #workers - 6, expected #workers - 7
[INFO] [stderr] [2025-10-01T17:21:54Z INFO  perftool::tests] hello, 157, my name is PETEA
[INFO] [stderr] [2025-10-01T17:21:54Z INFO  perftool::tests] I'm done, 53
[INFO] [stderr] [2025-10-01T17:21:54Z DEBUG perftool] actual #workers - 6, expected #workers - 7
[INFO] [stderr] [2025-10-01T17:21:54Z INFO  perftool::tests] hello, 124, my name is PETEA
[INFO] [stderr] [2025-10-01T17:21:54Z DEBUG perftool] actual #workers - 7, expected #workers - 8
[INFO] [stderr] [2025-10-01T17:21:54Z INFO  perftool::tests] hello, 227, my name is FEDEA
[INFO] [stderr] [2025-10-01T17:21:54Z INFO  perftool::tests] I'm done, 32
[INFO] [stderr] [2025-10-01T17:21:54Z DEBUG perftool] actual #workers - 7, expected #workers - 8
[INFO] [stderr] [2025-10-01T17:21:54Z INFO  perftool::tests] hello, 110, my name is JORA
[INFO] [stderr] [2025-10-01T17:21:55Z INFO  perftool::tests] I'm done, 182
[INFO] [stderr] [2025-10-01T17:21:55Z DEBUG perftool] actual #workers - 7, expected #workers - 8
[INFO] [stderr] [2025-10-01T17:21:55Z INFO  perftool::tests] hello, 30, my name is JORA
[INFO] [stderr] [2025-10-01T17:21:55Z DEBUG perftool] actual #workers - 8, expected #workers - 9
[INFO] [stderr] [2025-10-01T17:21:55Z INFO  perftool::tests] hello, 230, my name is JORA
[INFO] [stderr] [2025-10-01T17:21:55Z INFO  perftool::tests] I'm done, 237
[INFO] [stderr] [2025-10-01T17:21:55Z DEBUG perftool] actual #workers - 8, expected #workers - 9
[INFO] [stderr] [2025-10-01T17:21:55Z INFO  perftool::tests] hello, 21, my name is VANEA
[INFO] [stderr] [2025-10-01T17:21:55Z INFO  perftool::tests] test starts checkng actual wps
[INFO] [stderr] [2025-10-01T17:21:55Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:55Z INFO  perftool::tests] hello, 1, my name is KOLEA
[INFO] [stderr] [2025-10-01T17:21:55Z INFO  perftool::tests] I'm done, 30
[INFO] [stderr] [2025-10-01T17:21:55Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:55Z INFO  perftool::tests] hello, 95, my name is VANEA
[INFO] [stderr] [2025-10-01T17:21:56Z INFO  perftool::tests] I'm done, 249
[INFO] [stderr] [2025-10-01T17:21:56Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:56Z INFO  perftool::tests] hello, 187, my name is JORA
[INFO] [stderr] [2025-10-01T17:21:56Z DEBUG perftool] ramp up done
[INFO] [stderr] [2025-10-01T17:21:56Z DEBUG perftool] starting test with constant load
[INFO] [stderr] [2025-10-01T17:21:56Z INFO  perftool::tests] I'm done, 157
[INFO] [stderr] [2025-10-01T17:21:56Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:56Z INFO  perftool::tests] hello, 83, my name is FEDEA
[INFO] [stderr] [2025-10-01T17:21:56Z INFO  perftool::tests] I'm done, 124
[INFO] [stderr] [2025-10-01T17:21:56Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:56Z INFO  perftool::tests] hello, 133, my name is FEDEA
[INFO] [stderr] [2025-10-01T17:21:56Z INFO  perftool::tests] I'm done, 227
[INFO] [stderr] [2025-10-01T17:21:56Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:56Z INFO  perftool::tests] hello, 243, my name is VASEA
[INFO] [stderr] [2025-10-01T17:21:56Z INFO  perftool::tests] I'm done, 110
[INFO] [stderr] [2025-10-01T17:21:56Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:56Z INFO  perftool::tests] hello, 78, my name is FEDEA
[INFO] [stderr] [2025-10-01T17:21:57Z INFO  perftool::tests] I'm done, 30
[INFO] [stderr] [2025-10-01T17:21:57Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:57Z INFO  perftool::tests] hello, 121, my name is FEDEA
[INFO] [stderr] [2025-10-01T17:21:57Z INFO  perftool::tests] I'm done, 230
[INFO] [stderr] [2025-10-01T17:21:57Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:57Z INFO  perftool::tests] hello, 22, my name is FEDEA
[INFO] [stderr] [2025-10-01T17:21:57Z INFO  perftool::tests] I'm done, 21
[INFO] [stderr] [2025-10-01T17:21:57Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:57Z INFO  perftool::tests] hello, 193, my name is PETEA
[INFO] [stderr] [2025-10-01T17:21:57Z INFO  perftool::tests] I'm done, 1
[INFO] [stderr] [2025-10-01T17:21:57Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:57Z INFO  perftool::tests] hello, 199, my name is VANEA
[INFO] [stderr] [2025-10-01T17:21:57Z INFO  perftool::tests] I'm done, 95
[INFO] [stderr] [2025-10-01T17:21:57Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:57Z INFO  perftool::tests] hello, 247, my name is VASEA
[INFO] [stderr] [2025-10-01T17:21:58Z INFO  perftool::tests] I'm done, 187
[INFO] [stderr] [2025-10-01T17:21:58Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:58Z INFO  perftool::tests] hello, 13, my name is PETEA
[INFO] [stderr] [2025-10-01T17:21:58Z INFO  perftool::tests] I'm done, 83
[INFO] [stderr] [2025-10-01T17:21:58Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:58Z INFO  perftool::tests] hello, 132, my name is FEDEA
[INFO] [stderr] [2025-10-01T17:21:58Z INFO  perftool::tests] I'm done, 133
[INFO] [stderr] [2025-10-01T17:21:58Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:58Z INFO  perftool::tests] hello, 92, my name is KOLEA
[INFO] [stderr] [2025-10-01T17:21:58Z INFO  perftool::tests] I'm done, 243
[INFO] [stderr] [2025-10-01T17:21:58Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:58Z INFO  perftool::tests] hello, 190, my name is PETEA
[INFO] [stderr] [2025-10-01T17:21:58Z INFO  perftool::tests] I'm done, 78
[INFO] [stderr] [2025-10-01T17:21:58Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:58Z INFO  perftool::tests] hello, 80, my name is VANEA
[INFO] [stderr] [2025-10-01T17:21:59Z INFO  perftool::tests] I'm done, 121
[INFO] [stderr] [2025-10-01T17:21:59Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:59Z INFO  perftool::tests] hello, 110, my name is VANEA
[INFO] [stderr] [2025-10-01T17:21:59Z INFO  perftool::tests] I'm done, 22
[INFO] [stderr] [2025-10-01T17:21:59Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:59Z INFO  perftool::tests] hello, 110, my name is VASEA
[INFO] [stderr] [2025-10-01T17:21:59Z INFO  perftool::tests] I'm done, 193
[INFO] [stderr] [2025-10-01T17:21:59Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:59Z INFO  perftool::tests] hello, 229, my name is PETEA
[INFO] [stderr] [2025-10-01T17:21:59Z INFO  perftool::tests] I'm done, 199
[INFO] [stderr] [2025-10-01T17:21:59Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:59Z INFO  perftool::tests] hello, 31, my name is JORA
[INFO] [stderr] [2025-10-01T17:21:59Z INFO  perftool::tests] I'm done, 247
[INFO] [stderr] [2025-10-01T17:21:59Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:21:59Z INFO  perftool::tests] hello, 150, my name is VASEA
[INFO] [stderr] [2025-10-01T17:22:00Z INFO  perftool::tests] I'm done, 13
[INFO] [stderr] [2025-10-01T17:22:00Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:00Z INFO  perftool::tests] hello, 113, my name is VASEA
[INFO] [stderr] [2025-10-01T17:22:00Z INFO  perftool::tests] I'm done, 132
[INFO] [stderr] [2025-10-01T17:22:00Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:00Z INFO  perftool::tests] hello, 48, my name is FEDEA
[INFO] [stderr] [2025-10-01T17:22:00Z INFO  perftool::tests] I'm done, 92
[INFO] [stderr] [2025-10-01T17:22:00Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:00Z INFO  perftool::tests] hello, 205, my name is VASEA
[INFO] [stderr] [2025-10-01T17:22:00Z INFO  perftool::tests] I'm done, 190
[INFO] [stderr] [2025-10-01T17:22:00Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:00Z INFO  perftool::tests] hello, 111, my name is PETEA
[INFO] [stderr] [2025-10-01T17:22:00Z INFO  perftool::tests] I'm done, 80
[INFO] [stderr] [2025-10-01T17:22:00Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:00Z INFO  perftool::tests] hello, 115, my name is JORA
[INFO] [stderr] [2025-10-01T17:22:01Z INFO  perftool::tests] I'm done, 110
[INFO] [stderr] [2025-10-01T17:22:01Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:01Z INFO  perftool::tests] hello, 205, my name is PETEA
[INFO] [stderr] [2025-10-01T17:22:01Z INFO  perftool::tests] I'm done, 110
[INFO] [stderr] [2025-10-01T17:22:01Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:01Z INFO  perftool::tests] hello, 224, my name is PETEA
[INFO] [stderr] [2025-10-01T17:22:01Z INFO  perftool::tests] I'm done, 229
[INFO] [stderr] [2025-10-01T17:22:01Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:01Z INFO  perftool::tests] hello, 251, my name is KOLEA
[INFO] [stderr] [2025-10-01T17:22:01Z INFO  perftool::tests] I'm done, 31
[INFO] [stderr] [2025-10-01T17:22:01Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:01Z INFO  perftool::tests] hello, 222, my name is VANEA
[INFO] [stderr] [2025-10-01T17:22:01Z INFO  perftool::tests] I'm done, 150
[INFO] [stderr] [2025-10-01T17:22:01Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:01Z INFO  perftool::tests] hello, 153, my name is VANEA
[INFO] [stderr] [2025-10-01T17:22:02Z INFO  perftool::tests] I'm done, 113
[INFO] [stderr] [2025-10-01T17:22:02Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:02Z INFO  perftool::tests] hello, 226, my name is KOLEA
[INFO] [stderr] [2025-10-01T17:22:02Z INFO  perftool::tests] I'm done, 48
[INFO] [stderr] [2025-10-01T17:22:02Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:02Z INFO  perftool::tests] hello, 209, my name is JORA
[INFO] [stderr] [2025-10-01T17:22:02Z INFO  perftool::tests] I'm done, 205
[INFO] [stderr] [2025-10-01T17:22:02Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:02Z INFO  perftool::tests] hello, 21, my name is JORA
[INFO] [stderr] [2025-10-01T17:22:02Z INFO  perftool::tests] I'm done, 111
[INFO] [stderr] [2025-10-01T17:22:02Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:02Z INFO  perftool::tests] hello, 103, my name is JORA
[INFO] [stderr] [2025-10-01T17:22:02Z INFO  perftool::tests] I'm done, 115
[INFO] [stderr] [2025-10-01T17:22:02Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:02Z INFO  perftool::tests] hello, 77, my name is JORA
[INFO] [stderr] [2025-10-01T17:22:03Z INFO  perftool::tests] I'm done, 205
[INFO] [stderr] [2025-10-01T17:22:03Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:03Z INFO  perftool::tests] hello, 12, my name is VASEA
[INFO] [stderr] [2025-10-01T17:22:03Z INFO  perftool::tests] I'm done, 224
[INFO] [stderr] [2025-10-01T17:22:03Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:03Z INFO  perftool::tests] hello, 45, my name is VASEA
[INFO] [stderr] [2025-10-01T17:22:03Z INFO  perftool::tests] I'm done, 251
[INFO] [stderr] [2025-10-01T17:22:03Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:03Z INFO  perftool::tests] hello, 84, my name is VASEA
[INFO] [stderr] [2025-10-01T17:22:03Z INFO  perftool::tests] I'm done, 222
[INFO] [stderr] [2025-10-01T17:22:03Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:03Z INFO  perftool::tests] hello, 38, my name is JORA
[INFO] [stderr] [2025-10-01T17:22:03Z INFO  perftool::tests] I'm done, 153
[INFO] [stderr] [2025-10-01T17:22:03Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:03Z INFO  perftool::tests] hello, 122, my name is PETEA
[INFO] [stderr] [2025-10-01T17:22:04Z INFO  perftool::tests] I'm done, 226
[INFO] [stderr] [2025-10-01T17:22:04Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:04Z INFO  perftool::tests] hello, 205, my name is VASEA
[INFO] [stderr] [2025-10-01T17:22:04Z INFO  perftool::tests] I'm done, 209
[INFO] [stderr] [2025-10-01T17:22:04Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:04Z INFO  perftool::tests] hello, 201, my name is VASEA
[INFO] [stderr] [2025-10-01T17:22:04Z INFO  perftool::tests] I'm done, 21
[INFO] [stderr] [2025-10-01T17:22:04Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:04Z INFO  perftool::tests] hello, 48, my name is VASEA
[INFO] [stderr] [2025-10-01T17:22:04Z INFO  perftool::tests] I'm done, 103
[INFO] [stderr] [2025-10-01T17:22:04Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:04Z INFO  perftool::tests] hello, 152, my name is FEDEA
[INFO] [stderr] [2025-10-01T17:22:04Z INFO  perftool::tests] I'm done, 77
[INFO] [stderr] [2025-10-01T17:22:04Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:04Z INFO  perftool::tests] hello, 135, my name is VASEA
[INFO] [stderr] [2025-10-01T17:22:05Z INFO  perftool::tests] I'm done, 12
[INFO] [stderr] [2025-10-01T17:22:05Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:05Z INFO  perftool::tests] hello, 161, my name is JORA
[INFO] [stderr] [2025-10-01T17:22:05Z INFO  perftool::tests] I'm done, 45
[INFO] [stderr] [2025-10-01T17:22:05Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:05Z INFO  perftool::tests] hello, 60, my name is VANEA
[INFO] [stderr] [2025-10-01T17:22:05Z INFO  perftool::tests] I'm done, 84
[INFO] [stderr] [2025-10-01T17:22:05Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:05Z INFO  perftool::tests] hello, 175, my name is KOLEA
[INFO] [stderr] [2025-10-01T17:22:05Z INFO  perftool::tests] I'm done, 38
[INFO] [stderr] [2025-10-01T17:22:05Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:05Z INFO  perftool::tests] hello, 133, my name is JORA
[INFO] [stderr] [2025-10-01T17:22:05Z INFO  perftool::tests] I'm done, 122
[INFO] [stderr] [2025-10-01T17:22:05Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:05Z INFO  perftool::tests] hello, 184, my name is JORA
[INFO] [stderr] [2025-10-01T17:22:06Z INFO  perftool::tests] I'm done, 205
[INFO] [stderr] [2025-10-01T17:22:06Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:06Z INFO  perftool::tests] hello, 239, my name is JORA
[INFO] [stderr] [2025-10-01T17:22:06Z INFO  perftool::tests] I'm done, 201
[INFO] [stderr] [2025-10-01T17:22:06Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:06Z INFO  perftool::tests] hello, 172, my name is PETEA
[INFO] [stderr] [2025-10-01T17:22:06Z INFO  perftool::tests] I'm done, 48
[INFO] [stderr] [2025-10-01T17:22:06Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:06Z INFO  perftool::tests] hello, 138, my name is KOLEA
[INFO] [stderr] [2025-10-01T17:22:06Z INFO  perftool::tests] I'm done, 152
[INFO] [stderr] [2025-10-01T17:22:06Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:06Z INFO  perftool::tests] hello, 179, my name is VANEA
[INFO] [stderr] [2025-10-01T17:22:06Z INFO  perftool::tests] I'm done, 135
[INFO] [stderr] [2025-10-01T17:22:06Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:06Z INFO  perftool::tests] hello, 194, my name is VASEA
[INFO] [stderr] [2025-10-01T17:22:07Z INFO  perftool::tests] I'm done, 161
[INFO] [stderr] [2025-10-01T17:22:07Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:07Z INFO  perftool::tests] hello, 194, my name is KOLEA
[INFO] [stderr] [2025-10-01T17:22:07Z INFO  perftool::tests] I'm done, 60
[INFO] [stderr] [2025-10-01T17:22:07Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:07Z INFO  perftool::tests] hello, 10, my name is FEDEA
[INFO] [stderr] [2025-10-01T17:22:07Z INFO  perftool::tests] I'm done, 175
[INFO] [stderr] [2025-10-01T17:22:07Z DEBUG perftool] actual #workers - 9, expected #workers - 10
[INFO] [stderr] [2025-10-01T17:22:07Z INFO  perftool::tests] hello, 213, my name is PETEA
[INFO] [stderr] [2025-10-01T17:22:07Z DEBUG perftool] test done, waiting for workers to finish
[INFO] [stderr] [2025-10-01T17:22:07Z INFO  perftool::tests] I'm done, 133
[INFO] [stderr] [2025-10-01T17:22:07Z INFO  perftool::tests] I'm done, 184
[INFO] [stderr] [2025-10-01T17:22:08Z INFO  perftool::tests] I'm done, 239
[INFO] [stderr] [2025-10-01T17:22:08Z INFO  perftool::tests] I'm done, 172
[INFO] [stderr] [2025-10-01T17:22:08Z INFO  perftool::tests] I'm done, 138
[INFO] [stderr] [2025-10-01T17:22:08Z INFO  perftool::tests] I'm done, 179
[INFO] [stderr] [2025-10-01T17:22:08Z INFO  perftool::tests] I'm done, 194
[INFO] [stderr] [2025-10-01T17:22:09Z INFO  perftool::tests] I'm done, 194
[INFO] [stderr] [2025-10-01T17:22:09Z INFO  perftool::tests] I'm done, 10
[INFO] [stderr] [2025-10-01T17:22:09Z INFO  perftool::tests] I'm done, 213
[INFO] [stdout] test tests::test_simple ... ok
[INFO] [stderr] [2025-10-01T17:22:09Z DEBUG perftool] all workers done
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests perftool
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 19.05s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "afe508f7ad89cea8a2236e58bd5ba0b3bc65b76f98c2ca9058104d14e1f0f0c8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "afe508f7ad89cea8a2236e58bd5ba0b3bc65b76f98c2ca9058104d14e1f0f0c8", kill_on_drop: false }`
[INFO] [stdout] afe508f7ad89cea8a2236e58bd5ba0b3bc65b76f98c2ca9058104d14e1f0f0c8