[INFO] fetching crate wasm_safe_mutex 0.1.0... [INFO] testing wasm_safe_mutex-0.1.0 against master#caccb4d0368bd918ef6668af8e13834d07040417 for pr-146098-1 [INFO] extracting crate wasm_safe_mutex 0.1.0 into /workspace/builds/worker-5-tc1/source [INFO] started tweaking crates.io crate wasm_safe_mutex 0.1.0 [INFO] finished tweaking crates.io crate wasm_safe_mutex 0.1.0 [INFO] tweaked toml for crates.io crate wasm_safe_mutex 0.1.0 written to /workspace/builds/worker-5-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate wasm_safe_mutex 0.1.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] crate crates.io crate wasm_safe_mutex 0.1.0 already has a lockfile, it will not be regenerated [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+caccb4d0368bd918ef6668af8e13834d07040417" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded logwise_proc v0.1.1 [INFO] [stderr] Downloaded some_executor v0.6.0 [INFO] [stderr] Downloaded logwise v0.2.4 [INFO] [stderr] Downloaded test_executors_proc v0.3.5 [INFO] [stderr] Downloaded blocking_semaphore v0.1.0 [INFO] [stderr] Downloaded wasm_thread v0.3.3 [INFO] [stderr] Downloaded continue v0.1.1 [INFO] [stderr] Downloaded logwise v0.3.0 [INFO] [stderr] Downloaded priority v0.1.1 [INFO] [stderr] Downloaded test_executors v0.3.5 [INFO] [stderr] Downloaded logwise_proc v0.3.0 [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] 9b8b5eee828b4660c74c3325311dae3c5b1f080159fa95ea9e7989a4e2f908c5 [INFO] running `Command { std: "docker" "start" "-a" "9b8b5eee828b4660c74c3325311dae3c5b1f080159fa95ea9e7989a4e2f908c5", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "9b8b5eee828b4660c74c3325311dae3c5b1f080159fa95ea9e7989a4e2f908c5", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "9b8b5eee828b4660c74c3325311dae3c5b1f080159fa95ea9e7989a4e2f908c5", kill_on_drop: false }` [INFO] [stdout] 9b8b5eee828b4660c74c3325311dae3c5b1f080159fa95ea9e7989a4e2f908c5 [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] 3e9b18340cc8ac7c2e608349f791f33d1b1172b01efd1e913c8a9b72f47218cb [INFO] running `Command { std: "docker" "start" "-a" "3e9b18340cc8ac7c2e608349f791f33d1b1172b01efd1e913c8a9b72f47218cb", kill_on_drop: false }` [INFO] [stderr] Compiling thiserror v2.0.15 [INFO] [stderr] Compiling logwise_proc v0.1.1 [INFO] [stderr] Compiling thiserror-impl v2.0.15 [INFO] [stderr] Compiling logwise v0.2.4 [INFO] [stderr] Compiling continue v0.1.1 [INFO] [stderr] Compiling wasm_safe_mutex v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.80s [INFO] running `Command { std: "docker" "inspect" "3e9b18340cc8ac7c2e608349f791f33d1b1172b01efd1e913c8a9b72f47218cb", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3e9b18340cc8ac7c2e608349f791f33d1b1172b01efd1e913c8a9b72f47218cb", kill_on_drop: false }` [INFO] [stdout] 3e9b18340cc8ac7c2e608349f791f33d1b1172b01efd1e913c8a9b72f47218cb [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] a0536427e9fa7b806bbe0eb5c4ae543d1a49e3a4d4ef9cc4e28e39cff5072c14 [INFO] running `Command { std: "docker" "start" "-a" "a0536427e9fa7b806bbe0eb5c4ae543d1a49e3a4d4ef9cc4e28e39cff5072c14", kill_on_drop: false }` [INFO] [stderr] Compiling hashbrown v0.15.5 [INFO] [stderr] Compiling winnow v0.7.12 [INFO] [stderr] Compiling logwise_proc v0.3.0 [INFO] [stderr] Compiling priority v0.1.1 [INFO] [stderr] Compiling syn v2.0.106 [INFO] [stderr] Compiling logwise v0.3.0 [INFO] [stderr] Compiling blocking_semaphore v0.1.0 [INFO] [stderr] Compiling indexmap v2.10.0 [INFO] [stderr] Compiling toml_edit v0.22.27 [INFO] [stderr] Compiling thiserror-impl v2.0.15 [INFO] [stderr] Compiling proc-macro-crate v3.3.0 [INFO] [stderr] Compiling test_executors_proc v0.3.5 [INFO] [stderr] Compiling thiserror v2.0.15 [INFO] [stderr] Compiling continue v0.1.1 [INFO] [stderr] Compiling some_executor v0.6.0 [INFO] [stderr] Compiling wasm_safe_mutex v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Compiling test_executors v0.3.5 [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 3.97s [INFO] running `Command { std: "docker" "inspect" "a0536427e9fa7b806bbe0eb5c4ae543d1a49e3a4d4ef9cc4e28e39cff5072c14", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "a0536427e9fa7b806bbe0eb5c4ae543d1a49e3a4d4ef9cc4e28e39cff5072c14", kill_on_drop: false }` [INFO] [stdout] a0536427e9fa7b806bbe0eb5c4ae543d1a49e3a4d4ef9cc4e28e39cff5072c14 [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] 9a3f6e1a45df0320e4a47bea36af7e85bafcac5230f6a05ffe6e12107af6db68 [INFO] running `Command { std: "docker" "start" "-a" "9a3f6e1a45df0320e4a47bea36af7e85bafcac5230f6a05ffe6e12107af6db68", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.06s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/wasm_safe_mutex-826a89f6bf270cf3) [INFO] [stdout] [INFO] [stdout] running 10 tests [INFO] [stderr] 1 PERFWARN: BEGIN /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/blocking_semaphore-0.1.0/src/one.rs:104:9 [0ns] Semaphore implementation uses mutex [INFO] [stderr] 2 PERFWARN: BEGIN /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/blocking_semaphore-0.1.0/src/one.rs:104:9 [6.96µs] Semaphore implementation uses mutex [INFO] [stderr] 3 PERFWARN: BEGIN /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/blocking_semaphore-0.1.0/src/one.rs:89:13 [3.16309ms] Semaphore implementation uses mutex [INFO] [stderr] 3 PERWARN: END [3.24301ms] Semaphore implementation uses mutex [interval took 5.93µs] [INFO] [stderr] 1 PERWARN: END [3.26059ms] Semaphore implementation uses mutex [interval took 3.05535ms] [INFO] [stderr] 1 PERFWARN: statistics[Semaphore implementation uses mutex: 3.05535ms,] [INFO] [stderr] 0 PERFWARN: BEGIN /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/blocking_semaphore-0.1.0/src/one.rs:104:9 [3.39702ms] Semaphore implementation uses mutex [INFO] [stderr] 3 PERFWARN: statistics[Semaphore implementation uses mutex: 5.93µs,] [INFO] [stdout] test tests::async_test_test_mutex_try_lock_contention ... ok [INFO] [stdout] test tests::test_guard_drop_releases_lock ... ok [INFO] [stdout] test tests::test_mutex_async_contention ... ok [INFO] [stdout] test tests::test_mutex_lock_async ... ok [INFO] [stderr] 5 PERFWARN: BEGIN /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/blocking_semaphore-0.1.0/src/one.rs:89:13 [4.10272ms] Semaphore implementation uses mutex [INFO] [stderr] 5 PERWARN: END [4.13658ms] Semaphore implementation uses mutex [interval took 6.76µs] [INFO] [stderr] 0 PERWARN: END [4.14491ms] Semaphore implementation uses mutex [interval took 454.21µs] [INFO] [stderr] 0 PERFWARN: statistics[Semaphore implementation uses mutex: 454.21µs,] [INFO] [stderr] 5 PERFWARN: statistics[Semaphore implementation uses mutex: 6.76µs,] [INFO] [stdout] test tests::test_mutex_lock_spin ... ok [INFO] [stderr] 4 PERFWARN: BEGIN /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/blocking_semaphore-0.1.0/src/one.rs:104:9 [4.30944ms] Semaphore implementation uses mutex [INFO] [stdout] test tests::test_spinlock_basic ... ok [INFO] [stderr] 6 PERFWARN: BEGIN /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/blocking_semaphore-0.1.0/src/one.rs:89:13 [4.44489ms] Semaphore implementation uses mutex [INFO] [stdout] test tests::async_test_test_mutex_concurrent_increment ... ok [INFO] [stderr] 6 PERWARN: END [4.47094ms] Semaphore implementation uses mutex [interval took 5.07µs] [INFO] [stdout] test tests::test_mutex_try_lock_success ... ok [INFO] [stderr] 4 PERWARN: END [4.47474ms] Semaphore implementation uses mutex [interval took 147.07µs] [INFO] [stderr] 6 PERFWARN: statistics[Semaphore implementation uses mutex: 5.07µs,] [INFO] [stderr] 4 PERFWARN: statistics[Semaphore implementation uses mutex: 147.07µs,] [INFO] [stdout] test tests::async_test_test_spinlock_concurrent_access ... ok [INFO] [stderr] 7 PERFWARN: BEGIN /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/blocking_semaphore-0.1.0/src/one.rs:89:13 [13.23087ms] Semaphore implementation uses mutex [INFO] [stderr] 7 PERWARN: END [13.55096ms] Semaphore implementation uses mutex [interval took 8.031µs] [INFO] [stderr] 7 PERFWARN: statistics[Semaphore implementation uses mutex: 8.031µs,] [INFO] [stderr] 2 PERWARN: END [14.067989ms] Semaphore implementation uses mutex [interval took 13.703189ms] [INFO] [stderr] 2 PERFWARN: statistics[Semaphore implementation uses mutex: 13.703189ms,] [INFO] [stdout] test tests::async_test_test_mutex_lock_block ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s [INFO] [stdout] [INFO] [stderr] Doc-tests wasm_safe_mutex [INFO] [stdout] [INFO] [stdout] running 37 tests [INFO] [stdout] test src/guard.rs - guard::Guard (line 19) ... ok [INFO] [stdout] test src/lib.rs - (line 73) ... ok [INFO] [stdout] test src/lib.rs - (line 57) ... ok [INFO] [stdout] test src/lib.rs - (line 42) ... ok [INFO] [stdout] test src/lib.rs - Mutex (line 187) ... ok [INFO] [stdout] test src/lib.rs - Mutex::fmt (line 849) ... ok [INFO] [stdout] test src/lib.rs - Mutex (line 205) ... ok [INFO] [stdout] test src/lib.rs - Mutex::default (line 827) ... ok [INFO] [stdout] test src/lib.rs - Mutex::lock_async (line 437) ... ok [INFO] [stdout] test src/lib.rs - (line 92) ... ok [INFO] [stdout] test src/lib.rs - Mutex::lock_async (line 455) ... ok [INFO] [stdout] test src/lib.rs - Mutex::lock_spin (line 313) ... ok [INFO] [stdout] test src/lib.rs - Mutex::lock_sync (line 531) ... ok [INFO] [stdout] test src/lib.rs - Mutex::lock_block (line 369) ... ok [INFO] [stdout] test src/lib.rs - Mutex::lock_spin (line 325) ... ok [INFO] [stdout] test src/lib.rs - Mutex::lock_sync (line 544) ... ok [INFO] [stdout] test src/lib.rs - Mutex::from (line 871) ... ok [INFO] [stdout] test src/lib.rs - Mutex::with_async (line 707) ... ok [INFO] [stdout] test src/lib.rs - Mutex::try_lock (line 277) ... ok [INFO] [stdout] test src/lib.rs - Mutex::try_lock (line 260) ... ok [INFO] [stdout] test src/lib.rs - Mutex::new (line 235) ... ok [INFO] [stdout] test src/lib.rs - Mutex::with_mut_async (line 763) ... ok [INFO] [stdout] test src/lib.rs - Mutex::with_mut_async (line 781) ... ok [INFO] [stdout] test src/lib.rs - Mutex::with_async (line 723) ... ok [INFO] [stdout] test src/lib.rs - Mutex::with_mut_sync (line 657) ... ok [INFO] [stdout] test src/lib.rs - Mutex::with_mut_sync (line 672) ... ok [INFO] [stdout] test src/lib.rs - Mutex::with_sync (line 607) ... ok [INFO] [stdout] test src/spinlock.rs - spinlock::Spinlock::fmt (line 198) ... ok [INFO] [stdout] test src/spinlock.rs - spinlock::Spinlock::default (line 148) ... ok [INFO] [stdout] test src/spinlock.rs - spinlock::Spinlock::from (line 172) ... ok [INFO] [stdout] test src/spinlock.rs - spinlock::Spinlock::new (line 43) ... ok [INFO] [stdout] test src/spinlock.rs - spinlock::Spinlock::with_mut (line 73) ... ok [INFO] [stdout] test src/lib.rs - Mutex::with_sync (line 623) ... ok [INFO] [stdout] test src/lib.rs - NotAvailable (line 134) ... ok [INFO] [stdout] test src/spinlock.rs - spinlock::Spinlock::with_mut (line 92) ... ok [INFO] [stdout] test src/spinlock.rs - spinlock::Spinlock (line 20) ... ok [INFO] [stdout] test src/lib.rs - Mutex::lock_block (line 382) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 37 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "9a3f6e1a45df0320e4a47bea36af7e85bafcac5230f6a05ffe6e12107af6db68", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "9a3f6e1a45df0320e4a47bea36af7e85bafcac5230f6a05ffe6e12107af6db68", kill_on_drop: false }` [INFO] [stdout] 9a3f6e1a45df0320e4a47bea36af7e85bafcac5230f6a05ffe6e12107af6db68