[INFO] fetching crate images_and_words 0.1.0...
[INFO] testing images_and_words-0.1.0 against master#c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38 for pr-146098-7
[INFO] extracting crate images_and_words 0.1.0 into /workspace/builds/worker-2-tc1/source
[INFO] started tweaking crates.io crate images_and_words 0.1.0
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate images_and_words 0.1.0
[INFO] tweaked toml for crates.io crate images_and_words 0.1.0 written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate images_and_words 0.1.0 on toolchain c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate images_and_words 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" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded wasm-bindgen-test-macro v0.3.51
[INFO] [stderr]   Downloaded wgpu-core-deps-emscripten v26.0.0
[INFO] [stderr]   Downloaded logwise_proc v0.1.1
[INFO] [stderr]   Downloaded test_executors_proc v0.3.5
[INFO] [stderr]   Downloaded atspi v0.28.0
[INFO] [stderr]   Downloaded swift-rs v1.0.7
[INFO] [stderr]   Downloaded logwise_proc v0.3.0
[INFO] [stderr]   Downloaded accesskit v0.21.0
[INFO] [stderr]   Downloaded accesskit_consumer v0.30.0
[INFO] [stderr]   Downloaded polling v3.10.0
[INFO] [stderr]   Downloaded continue_stream v0.1.0
[INFO] [stderr]   Downloaded atomiclock_spinlock v0.1.0
[INFO] [stderr]   Downloaded zbus v5.10.0
[INFO] [stderr]   Downloaded logwise v0.3.0
[INFO] [stderr]   Downloaded logwise v0.2.4
[INFO] [stderr]   Downloaded continue v0.1.1
[INFO] [stderr]   Downloaded atspi-common v0.12.0
[INFO] [stderr]   Downloaded wasm_safe_mutex v0.1.0
[INFO] [stderr]   Downloaded blocking_semaphore v0.1.0
[INFO] [stderr]   Downloaded test_executors v0.3.5
[INFO] [stderr]   Downloaded priority v0.1.1
[INFO] [stderr]   Downloaded zune-png v0.4.10
[INFO] [stderr]   Downloaded accesskit_atspi_common v0.14.0
[INFO] [stderr]   Downloaded accesskit_unix v0.17.0
[INFO] [stderr]   Downloaded zbus_macros v5.10.0
[INFO] [stderr]   Downloaded atspi-proxies v0.12.0
[INFO] [stderr]   Downloaded wasm-bindgen-test v0.3.51
[INFO] [stderr]   Downloaded wasm_thread v0.3.3
[INFO] [stderr]   Downloaded send_cells v0.2.0
[INFO] [stderr]   Downloaded atspi-connection v0.12.0
[INFO] [stderr]   Downloaded ampsc v0.2.0
[INFO] [stderr]   Downloaded atomiclock v0.1.0
[INFO] [stderr]   Downloaded await_values v0.1.0
[INFO] [stderr]   Downloaded some_executor v0.6.1
[INFO] [stderr]   Downloaded vec_parallel v0.1.3
[INFO] [stderr]   Downloaded portable_async_sleep v0.1.1
[INFO] [stderr]   Downloaded async_file v0.1.1
[INFO] [stderr]   Downloaded atomiclock_async v0.1.0
[INFO] [stderr]   Downloaded tgar v1.0.0
[INFO] [stderr]   Downloaded vectormatrix v0.1.2
[INFO] [stderr]   Downloaded app_window v0.3.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] a3d65c3b291091dec36ef1c57c093a8d5264146afe6f85f3660c1ac9de613bfd
[INFO] running `Command { std: "docker" "start" "-a" "a3d65c3b291091dec36ef1c57c093a8d5264146afe6f85f3660c1ac9de613bfd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a3d65c3b291091dec36ef1c57c093a8d5264146afe6f85f3660c1ac9de613bfd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a3d65c3b291091dec36ef1c57c093a8d5264146afe6f85f3660c1ac9de613bfd", kill_on_drop: false }`
[INFO] [stdout] a3d65c3b291091dec36ef1c57c093a8d5264146afe6f85f3660c1ac9de613bfd
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 242f3ffa94dd6ed206613ceaa044ec6cfb389cd326b7ae2cd01b867495a736cb
[INFO] running `Command { std: "docker" "start" "-a" "242f3ffa94dd6ed206613ceaa044ec6cfb389cd326b7ae2cd01b867495a736cb", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.101
[INFO] [stderr]    Compiling serde v1.0.219
[INFO] [stderr]    Compiling logwise_proc v0.1.1
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling hashbrown v0.15.5
[INFO] [stderr]    Compiling naga v26.0.0
[INFO] [stderr]    Compiling libloading v0.8.8
[INFO] [stderr]    Compiling khronos-egl v6.0.0
[INFO] [stderr]    Compiling bit-vec v0.8.0
[INFO] [stderr]    Compiling ash v0.38.0+1.3.281
[INFO] [stderr]    Compiling wgpu-hal v26.0.4
[INFO] [stderr]    Compiling concurrent-queue v2.5.0
[INFO] [stderr]    Compiling parking_lot_core v0.9.11
[INFO] [stderr]    Compiling codespan-reporting v0.12.0
[INFO] [stderr]    Compiling rustc-hash v1.1.0
[INFO] [stderr]    Compiling hexf-parse v0.2.1
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling event-listener v5.4.1
[INFO] [stderr]    Compiling raw-window-handle v0.6.2
[INFO] [stderr]    Compiling renderdoc-sys v1.1.0
[INFO] [stderr]    Compiling parking_lot v0.12.4
[INFO] [stderr]    Compiling logwise_proc v0.3.0
[INFO] [stderr]    Compiling logwise v0.2.4
[INFO] [stderr]    Compiling profiling v1.0.17
[INFO] [stderr]    Compiling glow v0.16.0
[INFO] [stderr]    Compiling priority v0.1.1
[INFO] [stderr]    Compiling quote v1.0.40
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling wgpu-core v26.0.1
[INFO] [stderr]    Compiling event-listener-strategy v0.5.4
[INFO] [stderr]    Compiling fastrand v2.3.0
[INFO] [stderr]    Compiling syn v2.0.106
[INFO] [stderr]    Compiling async-channel v2.5.0
[INFO] [stderr]    Compiling futures-lite v2.6.1
[INFO] [stderr]    Compiling wgpu v26.0.1
[INFO] [stderr]    Compiling flate2 v1.1.2
[INFO] [stderr]    Compiling indexmap v2.11.0
[INFO] [stderr]    Compiling logwise v0.3.0
[INFO] [stderr]    Compiling piper v0.2.4
[INFO] [stderr]    Compiling num_cpus v1.17.0
[INFO] [stderr]    Compiling await_values v0.1.0
[INFO] [stderr]    Compiling tgar v1.0.0
[INFO] [stderr]    Compiling blocking_semaphore v0.1.0
[INFO] [stderr]    Compiling blocking v1.6.2
[INFO] [stderr]    Compiling vectormatrix v0.1.2
[INFO] [stderr]    Compiling send_cells v0.2.0
[INFO] [stderr]    Compiling half v2.6.0
[INFO] [stderr]    Compiling ordered-float v5.0.0
[INFO] [stderr]    Compiling toml_edit v0.22.27
[INFO] [stderr]    Compiling bitflags v2.9.4
[INFO] [stderr]    Compiling gpu-alloc-types v0.3.0
[INFO] [stderr]    Compiling spirv v0.3.0+sdk-1.3.268.0
[INFO] [stderr]    Compiling gpu-descriptor-types v0.2.0
[INFO] [stderr]    Compiling png v0.18.0
[INFO] [stderr]    Compiling gpu-alloc v0.6.0
[INFO] [stderr]    Compiling gpu-descriptor v0.3.2
[INFO] [stderr]    Compiling proc-macro-crate v3.3.0
[INFO] [stderr]    Compiling thiserror-impl v2.0.16
[INFO] [stderr]    Compiling bytemuck_derive v1.10.1
[INFO] [stderr]    Compiling test_executors_proc v0.3.5
[INFO] [stderr]    Compiling bytemuck v1.23.2
[INFO] [stderr]    Compiling thiserror v2.0.16
[INFO] [stderr]    Compiling continue v0.1.1
[INFO] [stderr]    Compiling async_file v0.1.1
[INFO] [stderr]    Compiling some_executor v0.6.1
[INFO] [stderr]    Compiling portable_async_sleep v0.1.1
[INFO] [stderr]    Compiling wgpu-types v26.0.0
[INFO] [stderr]    Compiling vec_parallel v0.1.3
[INFO] [stderr]    Compiling test_executors v0.3.5
[INFO] [stderr]    Compiling wgpu-core-deps-windows-linux-android v26.0.0
[INFO] [stderr]    Compiling images_and_words v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 49.92s
[INFO] running `Command { std: "docker" "inspect" "242f3ffa94dd6ed206613ceaa044ec6cfb389cd326b7ae2cd01b867495a736cb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "242f3ffa94dd6ed206613ceaa044ec6cfb389cd326b7ae2cd01b867495a736cb", kill_on_drop: false }`
[INFO] [stdout] 242f3ffa94dd6ed206613ceaa044ec6cfb389cd326b7ae2cd01b867495a736cb
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 9828504dada5f6037cde3ea5866e654c6ec8d69748124679becbe74b7c27781e
[INFO] running `Command { std: "docker" "start" "-a" "9828504dada5f6037cde3ea5866e654c6ec8d69748124679becbe74b7c27781e", kill_on_drop: false }`
[INFO] [stderr]    Compiling wasm-bindgen-shared v0.2.101
[INFO] [stderr]    Compiling syn v2.0.106
[INFO] [stderr]    Compiling futures-lite v2.6.1
[INFO] [stderr]    Compiling wasm-bindgen v0.2.101
[INFO] [stderr]    Compiling async-channel v2.5.0
[INFO] [stderr]    Compiling blocking v1.6.2
[INFO] [stderr]    Compiling wasm-bindgen-backend v0.2.101
[INFO] [stderr]    Compiling thiserror-impl v2.0.16
[INFO] [stderr]    Compiling bytemuck_derive v1.10.1
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling test_executors_proc v0.3.5
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling wasm-bindgen-macro-support v0.2.101
[INFO] [stderr]    Compiling bytemuck v1.23.2
[INFO] [stderr]    Compiling thiserror v2.0.16
[INFO] [stderr]    Compiling continue v0.1.1
[INFO] [stderr]    Compiling naga v26.0.0
[INFO] [stderr]    Compiling wgpu-types v26.0.0
[INFO] [stderr]    Compiling async_file v0.1.1
[INFO] [stderr]    Compiling wasm-bindgen-macro v0.2.101
[INFO] [stderr]    Compiling some_executor v0.6.1
[INFO] [stderr]    Compiling portable_async_sleep v0.1.1
[INFO] [stderr]    Compiling test_executors v0.3.5
[INFO] [stderr]    Compiling vec_parallel v0.1.3
[INFO] [stderr]    Compiling console_error_panic_hook v0.1.7
[INFO] [stderr]    Compiling futures-executor v0.3.31
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling wgpu-hal v26.0.4
[INFO] [stderr]    Compiling wgpu-core-deps-windows-linux-android v26.0.0
[INFO] [stderr]    Compiling wgpu-core v26.0.1
[INFO] [stderr]    Compiling wgpu v26.0.1
[INFO] [stderr]    Compiling images_and_words v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 49.43s
[INFO] running `Command { std: "docker" "inspect" "9828504dada5f6037cde3ea5866e654c6ec8d69748124679becbe74b7c27781e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9828504dada5f6037cde3ea5866e654c6ec8d69748124679becbe74b7c27781e", kill_on_drop: false }`
[INFO] [stdout] 9828504dada5f6037cde3ea5866e654c6ec8d69748124679becbe74b7c27781e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 36b3eaf0f4321aede9bae4a9a95d8e3a6664ad62d5903791df8ae568a9896593
[INFO] running `Command { std: "docker" "start" "-a" "36b3eaf0f4321aede9bae4a9a95d8e3a6664ad62d5903791df8ae568a9896593", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.28s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/images_and_words-b5cf050052a59a8f)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test bindings::software::texture::scaled_iterator::tests::test ... ok
[INFO] [stdout] test bindings::forward::dynamic::buffer::tests::send ... ok
[INFO] [stdout] test bindings::software::texture::scaled_32::clamped ... ok
[INFO] [stdout] test imp::wgpu::cell::tests::test_cell_construction ... ok
[INFO] [stdout] test bindings::software::texture::scaled_row_cell::test_rescale ... ok
[INFO] [stdout] test bindings::software::texture::scaled_iterator::tests::output_coordinates ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/buffer_performance.rs (/opt/rustwide/target/debug/deps/buffer_performance-cdb0bb2040496cb3)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]  0 INFO: src/images/engine.rs:40:9 [0ns] Engine::rendering_to() started
[INFO] [stderr]  0 INFO: src/images/engine.rs:42:9 [32.82µs] Creating EntryPoint...
[INFO] [stderr] error: XDG_RUNTIME_DIR is invalid or not set in the environment.
[INFO] [stderr]  0 INFO: src/images/engine.rs:44:9 [1.341121292s] EntryPoint created successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:46:9 [1.341161272s] Providing EntryPoint to view...
[INFO] [stderr]  0 INFO: src/images/engine.rs:48:9 [1.341179232s] EntryPoint provided to view successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:50:9 [1.341190872s] Getting view size and scale...
[INFO] [stderr]  0 INFO: src/images/engine.rs:52:9 [1.341202402s] View size: 600x600, scale: 600
[INFO] [stderr]  0 INFO: src/images/engine.rs:59:9 [1.341235772s] Picking unbound device...
[INFO] [stderr]  0 INFO: src/images/engine.rs:61:9 [1.380856968s] Unbound device picked successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:63:9 [1.380896128s] Binding device...
[INFO] [stderr]  0 INFO: src/images/engine.rs:65:9 [1.584361568s] Device bound successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:67:9 [1.584637199s] Creating implementation engine...
[INFO] [stderr]  0 INFO: src/imp/wgpu/engine.rs:8:9 [1.584964668s] wgpu::Engine::rendering_to_view() started
[INFO] [stderr]  0 INFO: src/imp/wgpu/engine.rs:10:9 [1.584980508s] wgpu::Engine::rendering_to_view() completed
[INFO] [stderr]  0 INFO: src/images/engine.rs:70:9 [1.584993078s] Implementation engine created successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:72:9 [1.585005488s] Creating Engine struct...
[INFO] [stderr]  0 INFO: src/images/engine.rs:79:9 [1.585017558s] Engine struct created successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:81:9 [1.585029318s] Creating final port...
[INFO] [stderr]  0 INFO: src/imp/wgpu/port/internal.rs:102:17 [1.590316738s] Port surface not initialized - picking Bgra8UnormSrgb as default format
[INFO] [stderr]  0 INFO: src/images/engine.rs:90:9 [1.590366518s] Final port created successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:92:9 [1.590380698s] Setting main port...
[INFO] [stderr]  0 INFO: src/images/engine.rs:94:9 [1.590396478s] Engine::rendering_to() completed successfully
[INFO] [stderr]  0 INFO: tests/buffer_performance.rs:128:13 [1.590727618s] === Testing buffer write performance ===
[INFO] [stderr]  0 INFO: tests/buffer_performance.rs:151:17 [1.597619147s]   Buffer write iteration 1 took: 11.09µs
[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 [1.598443677s] 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:89:13 [1.599414797s] Semaphore implementation uses mutex
[INFO] [stderr]  2 PERWARN: END [1.599604577s] Semaphore implementation uses mutex [interval took 5.4µs] 
[INFO] [stderr]  0 PERWARN: END [1.599917377s] Semaphore implementation uses mutex [interval took 1.2765ms] 
[INFO] [stderr]  0 INFO: tests/buffer_performance.rs:151:17 [1.77677497s]   Buffer write iteration 2 took: 14.63µs
[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 [1.77710522s] 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:89:13 [1.780878529s] Semaphore implementation uses mutex
[INFO] [stderr]  2 PERWARN: END [1.780962649s] Semaphore implementation uses mutex [interval took 14.669µs] 
[INFO] [stderr]  0 PERWARN: END [1.781000729s] Semaphore implementation uses mutex [interval took 3.747849ms] 
[INFO] [stderr]  0 INFO: tests/buffer_performance.rs:151:17 [1.785378719s]   Buffer write iteration 3 took: 12.79µs
[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 [1.785455759s] 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:89:13 [1.786518469s] Semaphore implementation uses mutex
[INFO] [stderr]  2 PERWARN: END [1.786564669s] Semaphore implementation uses mutex [interval took 5.83µs] 
[INFO] [stderr]  0 PERWARN: END [1.786592059s] Semaphore implementation uses mutex [interval took 1.12033ms] 
[INFO] [stderr]  0 INFO: tests/buffer_performance.rs:163:13 [1.786615079s] Average buffer write time: 12.836µs
[INFO] [stderr] 0 PERFWARN: statistics[Semaphore implementation uses mutex: 6.144679ms,]
[INFO] [stdout] test main ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.79s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/sendable_futures.rs (/opt/rustwide/target/debug/deps/sendable_futures-0498ffaef291ea55)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]  0 INFO: src/images/engine.rs:40:9 [0ns] Engine::rendering_to() started
[INFO] [stderr]  0 INFO: src/images/engine.rs:42:9 [28.069µs] Creating EntryPoint...
[INFO] [stderr] error: XDG_RUNTIME_DIR is invalid or not set in the environment.
[INFO] [stderr]  0 INFO: src/images/engine.rs:44:9 [93.79738ms] EntryPoint created successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:46:9 [93.835871ms] Providing EntryPoint to view...
[INFO] [stderr]  0 INFO: src/images/engine.rs:48:9 [93.8533ms] EntryPoint provided to view successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:50:9 [93.863971ms] Getting view size and scale...
[INFO] [stderr]  0 INFO: src/images/engine.rs:52:9 [93.874911ms] View size: 600x600, scale: 600
[INFO] [stderr]  0 INFO: src/images/engine.rs:59:9 [93.900931ms] Picking unbound device...
[INFO] [stderr]  0 INFO: src/images/engine.rs:61:9 [440.204087ms] Unbound device picked successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:63:9 [440.367977ms] Binding device...
[INFO] [stderr]  0 INFO: src/images/engine.rs:65:9 [512.61607ms] Device bound successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:67:9 [512.65488ms] Creating implementation engine...
[INFO] [stderr]  0 INFO: src/imp/wgpu/engine.rs:8:9 [512.66766ms] wgpu::Engine::rendering_to_view() started
[INFO] [stderr]  0 INFO: src/imp/wgpu/engine.rs:10:9 [512.67926ms] wgpu::Engine::rendering_to_view() completed
[INFO] [stderr]  0 INFO: src/images/engine.rs:70:9 [512.70132ms] Implementation engine created successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:72:9 [512.73071ms] Creating Engine struct...
[INFO] [stderr]  0 INFO: src/images/engine.rs:79:9 [512.74289ms] Engine struct created successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:81:9 [512.75438ms] Creating final port...
[INFO] [stderr]  0 INFO: src/imp/wgpu/port/internal.rs:102:17 [514.22735ms] Port surface not initialized - picking Bgra8UnormSrgb as default format
[INFO] [stderr]  0 INFO: src/images/engine.rs:90:9 [514.25387ms] Final port created successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:92:9 [514.26605ms] Setting main port...
[INFO] [stderr]  0 INFO: src/images/engine.rs:94:9 [514.27909ms] Engine::rendering_to() completed successfully
[INFO] [stdout] test main ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.84s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running tests/texture_alignment.rs (/opt/rustwide/target/debug/deps/texture_alignment-d030c1c7c583e39e)
[INFO] [stdout] running 5 tests
[INFO] [stderr]  0 INFO: src/images/engine.rs:40:9 [0ns] Engine::rendering_to() started
[INFO] [stderr]  0 INFO: src/images/engine.rs:42:9 [36.54µs] Creating EntryPoint...
[INFO] [stderr]  2 INFO: src/images/engine.rs:40:9 [83.16µs] Engine::rendering_to() started
[INFO] [stderr]  2 INFO: src/images/engine.rs:42:9 [97.9µs] Creating EntryPoint...
[INFO] [stderr]  3 INFO: src/images/engine.rs:40:9 [115.88µs] Engine::rendering_to() started
[INFO] [stderr]  3 INFO: src/images/engine.rs:42:9 [132.39µs] Creating EntryPoint...
[INFO] [stderr]  1 INFO: src/images/engine.rs:40:9 [19.05µs] Engine::rendering_to() started
[INFO] [stderr]  1 INFO: src/images/engine.rs:42:9 [657.66µs] Creating EntryPoint...
[INFO] [stderr] error: XDG_RUNTIME_DIR is invalid or not set in the environment.
[INFO] [stderr] error: XDG_RUNTIME_DIR is invalid or not set in the environment.
[INFO] [stderr] error: XDG_RUNTIME_DIR is invalid or not set in the environment.
[INFO] [stderr]  4 INFO: src/images/engine.rs:40:9 [74.287833ms] Engine::rendering_to() started
[INFO] [stderr]  4 INFO: src/images/engine.rs:42:9 [74.327063ms] Creating EntryPoint...
[INFO] [stderr] error: XDG_RUNTIME_DIR is invalid or not set in the environment.
[INFO] [stderr] error: XDG_RUNTIME_DIR is invalid or not set in the environment.
[INFO] [stderr]  1 INFO: src/images/engine.rs:44:9 [116.555559ms] EntryPoint created successfully
[INFO] [stderr]  1 INFO: src/images/engine.rs:46:9 [116.591659ms] Providing EntryPoint to view...
[INFO] [stderr]  1 INFO: src/images/engine.rs:48:9 [116.608959ms] EntryPoint provided to view successfully
[INFO] [stderr]  1 INFO: src/images/engine.rs:50:9 [116.626989ms] Getting view size and scale...
[INFO] [stderr]  1 INFO: src/images/engine.rs:52:9 [116.638179ms] View size: 600x600, scale: 600
[INFO] [stderr]  1 INFO: src/images/engine.rs:59:9 [116.664329ms] Picking unbound device...
[INFO] [stderr]  0 INFO: src/images/engine.rs:44:9 [691.328284ms] EntryPoint created successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:46:9 [691.609734ms] Providing EntryPoint to view...
[INFO] [stderr]  0 INFO: src/images/engine.rs:48:9 [691.631444ms] EntryPoint provided to view successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:50:9 [691.643844ms] Getting view size and scale...
[INFO] [stderr]  0 INFO: src/images/engine.rs:52:9 [691.655314ms] View size: 600x600, scale: 600
[INFO] [stderr]  0 INFO: src/images/engine.rs:59:9 [691.675544ms] Picking unbound device...
[INFO] [stderr]  2 INFO: src/images/engine.rs:44:9 [712.370912ms] EntryPoint created successfully
[INFO] [stderr]  2 INFO: src/images/engine.rs:46:9 [712.405942ms] Providing EntryPoint to view...
[INFO] [stderr]  2 INFO: src/images/engine.rs:48:9 [712.423482ms] EntryPoint provided to view successfully
[INFO] [stderr]  2 INFO: src/images/engine.rs:50:9 [712.434372ms] Getting view size and scale...
[INFO] [stderr]  2 INFO: src/images/engine.rs:52:9 [712.445302ms] View size: 600x600, scale: 600
[INFO] [stderr]  2 INFO: src/images/engine.rs:59:9 [712.463932ms] Picking unbound device...
[INFO] [stderr]  4 INFO: src/images/engine.rs:44:9 [749.003388ms] EntryPoint created successfully
[INFO] [stderr]  4 INFO: src/images/engine.rs:46:9 [749.119359ms] Providing EntryPoint to view...
[INFO] [stderr]  4 INFO: src/images/engine.rs:48:9 [749.137888ms] EntryPoint provided to view successfully
[INFO] [stderr]  4 INFO: src/images/engine.rs:50:9 [749.149108ms] Getting view size and scale...
[INFO] [stderr]  4 INFO: src/images/engine.rs:52:9 [749.160739ms] View size: 600x600, scale: 600
[INFO] [stderr]  4 INFO: src/images/engine.rs:59:9 [749.201008ms] Picking unbound device...
[INFO] [stderr]  0 INFO: src/images/engine.rs:61:9 [750.828288ms] Unbound device picked successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:63:9 [752.102868ms] Binding device...
[INFO] [stderr]  1 INFO: src/images/engine.rs:61:9 [783.857265ms] Unbound device picked successfully
[INFO] [stderr]  1 INFO: src/images/engine.rs:63:9 [783.894925ms] Binding device...
[INFO] [stderr]  4 INFO: src/images/engine.rs:61:9 [784.323725ms] Unbound device picked successfully
[INFO] [stderr]  4 INFO: src/images/engine.rs:63:9 [784.342725ms] Binding device...
[INFO] [stderr]  2 INFO: src/images/engine.rs:61:9 [784.669685ms] Unbound device picked successfully
[INFO] [stderr]  2 INFO: src/images/engine.rs:63:9 [784.696745ms] Binding device...
[INFO] [stderr]  3 INFO: src/images/engine.rs:44:9 [784.890135ms] EntryPoint created successfully
[INFO] [stderr]  3 INFO: src/images/engine.rs:46:9 [784.917755ms] Providing EntryPoint to view...
[INFO] [stderr]  3 INFO: src/images/engine.rs:48:9 [784.934635ms] EntryPoint provided to view successfully
[INFO] [stderr]  3 INFO: src/images/engine.rs:50:9 [784.946305ms] Getting view size and scale...
[INFO] [stderr]  3 INFO: src/images/engine.rs:52:9 [784.958785ms] View size: 600x600, scale: 600
[INFO] [stderr]  3 INFO: src/images/engine.rs:59:9 [784.979825ms] Picking unbound device...
[INFO] [stderr]  1 INFO: src/images/engine.rs:65:9 [874.496887ms] Device bound successfully
[INFO] [stderr]  1 INFO: src/images/engine.rs:67:9 [874.531196ms] Creating implementation engine...
[INFO] [stderr]  1 INFO: src/imp/wgpu/engine.rs:8:9 [874.543167ms] wgpu::Engine::rendering_to_view() started
[INFO] [stderr]  1 INFO: src/imp/wgpu/engine.rs:10:9 [874.553796ms] wgpu::Engine::rendering_to_view() completed
[INFO] [stderr]  1 INFO: src/images/engine.rs:70:9 [874.565427ms] Implementation engine created successfully
[INFO] [stderr]  1 INFO: src/images/engine.rs:72:9 [874.576476ms] Creating Engine struct...
[INFO] [stderr]  1 INFO: src/images/engine.rs:79:9 [874.588416ms] Engine struct created successfully
[INFO] [stderr]  1 INFO: src/images/engine.rs:81:9 [874.599347ms] Creating final port...
[INFO] [stderr]  1 INFO: src/imp/wgpu/port/internal.rs:102:17 [876.162756ms] Port surface not initialized - picking Bgra8UnormSrgb as default format
[INFO] [stderr]  1 INFO: src/images/engine.rs:90:9 [876.224006ms] Final port created successfully
[INFO] [stderr]  1 INFO: src/images/engine.rs:92:9 [876.235576ms] Setting main port...
[INFO] [stderr]  1 INFO: src/images/engine.rs:94:9 [876.248806ms] Engine::rendering_to() completed successfully
[INFO] [stderr]  3 INFO: src/images/engine.rs:61:9 [988.473716ms] Unbound device picked successfully
[INFO] [stderr]  3 INFO: src/images/engine.rs:63:9 [988.688696ms] Binding device...
[INFO] [stderr]  0 INFO: src/images/engine.rs:65:9 [1.0439956s] Device bound successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:67:9 [1.066664798s] Creating implementation engine...
[INFO] [stderr]  0 INFO: src/imp/wgpu/engine.rs:8:9 [1.066688838s] wgpu::Engine::rendering_to_view() started
[INFO] [stderr]  0 INFO: src/imp/wgpu/engine.rs:10:9 [1.066701138s] wgpu::Engine::rendering_to_view() completed
[INFO] [stderr]  0 INFO: src/images/engine.rs:70:9 [1.066712808s] Implementation engine created successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:72:9 [1.066724268s] Creating Engine struct...
[INFO] [stderr]  0 INFO: src/images/engine.rs:79:9 [1.066737488s] Engine struct created successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:81:9 [1.066760008s] Creating final port...
[INFO] [stderr]  4 INFO: src/images/engine.rs:65:9 [1.098075025s] Device bound successfully
[INFO] [stderr]  4 INFO: src/images/engine.rs:67:9 [1.098121545s] Creating implementation engine...
[INFO] [stderr]  4 INFO: src/imp/wgpu/engine.rs:8:9 [1.098143805s] wgpu::Engine::rendering_to_view() started
[INFO] [stderr]  4 INFO: src/imp/wgpu/engine.rs:10:9 [1.098157345s] wgpu::Engine::rendering_to_view() completed
[INFO] [stderr]  4 INFO: src/images/engine.rs:70:9 [1.098168995s] Implementation engine created successfully
[INFO] [stderr]  4 INFO: src/images/engine.rs:72:9 [1.098181015s] Creating Engine struct...
[INFO] [stderr]  4 INFO: src/images/engine.rs:79:9 [1.098208685s] Engine struct created successfully
[INFO] [stderr]  4 INFO: src/images/engine.rs:81:9 [1.098222835s] Creating final port...
[INFO] [stderr]  0 INFO: src/imp/wgpu/port/internal.rs:102:17 [1.099859995s] Port surface not initialized - picking Bgra8UnormSrgb as default format
[INFO] [stderr]  0 INFO: src/images/engine.rs:90:9 [1.100076445s] Final port created successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:92:9 [1.100374605s] Setting main port...
[INFO] [stderr]  0 INFO: src/images/engine.rs:94:9 [1.100514405s] Engine::rendering_to() completed successfully
[INFO] [stderr]  4 INFO: src/imp/wgpu/port/internal.rs:102:17 [1.103374625s] Port surface not initialized - picking Bgra8UnormSrgb as default format
[INFO] [stderr]  4 INFO: src/images/engine.rs:90:9 [1.103551685s] Final port created successfully
[INFO] [stderr]  4 INFO: src/images/engine.rs:92:9 [1.103688185s] Setting main port...
[INFO] [stderr]  4 INFO: src/images/engine.rs:94:9 [1.103824825s] Engine::rendering_to() completed successfully
[INFO] [stderr]  2 INFO: src/images/engine.rs:65:9 [1.131639642s] Device bound successfully
[INFO] [stderr]  2 INFO: src/images/engine.rs:67:9 [1.133808291s] Creating implementation engine...
[INFO] [stderr]  2 INFO: src/imp/wgpu/engine.rs:8:9 [1.135437661s] wgpu::Engine::rendering_to_view() started
[INFO] [stderr]  2 INFO: src/imp/wgpu/engine.rs:10:9 [1.138560631s] wgpu::Engine::rendering_to_view() completed
[INFO] [stderr]  2 INFO: src/images/engine.rs:70:9 [1.139395601s] Implementation engine created successfully
[INFO] [stderr]  2 INFO: src/images/engine.rs:72:9 [1.141226431s] Creating Engine struct...
[INFO] [stderr]  2 INFO: src/images/engine.rs:79:9 [1.141594721s] Engine struct created successfully
[INFO] [stderr]  2 INFO: src/images/engine.rs:81:9 [1.142226301s] Creating final port...
[INFO] [stderr]  2 INFO: src/imp/wgpu/port/internal.rs:102:17 [1.14489801s] Port surface not initialized - picking Bgra8UnormSrgb as default format
[INFO] [stderr]  2 INFO: src/images/engine.rs:90:9 [1.15072398s] Final port created successfully
[INFO] [stderr]  2 INFO: src/images/engine.rs:92:9 [1.15136323s] Setting main port...
[INFO] [stderr]  2 INFO: src/images/engine.rs:94:9 [1.15190397s] Engine::rendering_to() completed successfully
[INFO] [stderr]  3 INFO: src/images/engine.rs:65:9 [1.219500813s] Device bound successfully
[INFO] [stderr]  3 INFO: src/images/engine.rs:67:9 [1.219853633s] Creating implementation engine...
[INFO] [stderr]  3 INFO: src/imp/wgpu/engine.rs:8:9 [1.219870453s] wgpu::Engine::rendering_to_view() started
[INFO] [stderr]  3 INFO: src/imp/wgpu/engine.rs:10:9 [1.219899713s] wgpu::Engine::rendering_to_view() completed
[INFO] [stderr]  3 INFO: src/images/engine.rs:70:9 [1.219911513s] Implementation engine created successfully
[INFO] [stderr]  3 INFO: src/images/engine.rs:72:9 [1.219923133s] Creating Engine struct...
[INFO] [stderr]  3 INFO: src/images/engine.rs:79:9 [1.219936293s] Engine struct created successfully
[INFO] [stderr]  3 INFO: src/images/engine.rs:81:9 [1.219947673s] Creating final port...
[INFO] [stderr]  3 INFO: src/imp/wgpu/port/internal.rs:102:17 [1.221550663s] Port surface not initialized - picking Bgra8UnormSrgb as default format
[INFO] [stderr]  3 INFO: src/images/engine.rs:90:9 [1.221577393s] Final port created successfully
[INFO] [stderr]  3 INFO: src/images/engine.rs:92:9 [1.221589153s] Setting main port...
[INFO] [stderr]  3 INFO: src/images/engine.rs:94:9 [1.221603083s] Engine::rendering_to() completed successfully
[INFO] [stdout] test async_test_test_texture_alignment_ok_width_64 ... ok
[INFO] [stdout] test async_test_test_texture_alignment_error_width_63 ... ok
[INFO] [stdout] test async_test_test_texture_alignment_error_width_150 ... ok
[INFO] [stdout] test async_test_test_texture_alignment_ok_width_128 ... ok
[INFO] [stdout] test async_test_test_texture_alignment_error_width_100 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.45s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running tests/wgpu_cell_threading_error.rs (/opt/rustwide/target/debug/deps/wgpu_cell_threading_error-2d4a1049c5d89334)
[INFO] [stdout] running 1 test
[INFO] [stderr]  0 INFO: src/images/engine.rs:40:9 [0ns] Engine::rendering_to() started
[INFO] [stderr]  0 INFO: src/images/engine.rs:42:9 [33.65µs] Creating EntryPoint...
[INFO] [stderr] error: XDG_RUNTIME_DIR is invalid or not set in the environment.
[INFO] [stderr]  0 INFO: src/images/engine.rs:44:9 [99.37014ms] EntryPoint created successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:46:9 [99.41623ms] Providing EntryPoint to view...
[INFO] [stderr]  0 INFO: src/images/engine.rs:48:9 [99.43295ms] EntryPoint provided to view successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:50:9 [99.44355ms] Getting view size and scale...
[INFO] [stderr]  0 INFO: src/images/engine.rs:52:9 [99.4549ms] View size: 600x600, scale: 600
[INFO] [stderr]  0 INFO: src/images/engine.rs:59:9 [99.48304ms] Picking unbound device...
[INFO] [stderr]  0 INFO: src/images/engine.rs:61:9 [109.375849ms] Unbound device picked successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:63:9 [109.415479ms] Binding device...
[INFO] [stdout] test main ... ok
[INFO] [stdout] 
[INFO] [stderr]  0 INFO: src/images/engine.rs:65:9 [179.597693ms] Device bound successfully
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.18s
[INFO] [stderr]  0 INFO: src/images/engine.rs:67:9 [179.636962ms] Creating implementation engine...
[INFO] [stdout] 
[INFO] [stderr]  0 INFO: src/imp/wgpu/engine.rs:8:9 [179.649882ms] wgpu::Engine::rendering_to_view() started
[INFO] [stderr]  0 INFO: src/imp/wgpu/engine.rs:10:9 [179.661573ms] wgpu::Engine::rendering_to_view() completed
[INFO] [stderr]  0 INFO: src/images/engine.rs:70:9 [179.673473ms] Implementation engine created successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:72:9 [179.684293ms] Creating Engine struct...
[INFO] [stderr]  0 INFO: src/images/engine.rs:79:9 [179.696273ms] Engine struct created successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:81:9 [179.707153ms] Creating final port...
[INFO] [stderr]  0 INFO: src/imp/wgpu/port/internal.rs:102:17 [181.117192ms] Port surface not initialized - picking Bgra8UnormSrgb as default format
[INFO] [stderr]  0 INFO: src/images/engine.rs:90:9 [181.142562ms] Final port created successfully
[INFO] [stderr]  0 INFO: src/images/engine.rs:92:9 [181.154222ms] Setting main port...
[INFO] [stderr]  0 INFO: src/images/engine.rs:94:9 [181.166782ms] Engine::rendering_to() completed successfully
[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 [181.467972ms] Semaphore implementation uses mutex
[INFO] [stderr]    Doc-tests images_and_words
[INFO] [stdout] 
[INFO] [stdout] running 119 tests
[INFO] [stdout] test src/bindings/forward/dynamic.rs - bindings::forward::dynamic (line 70) ... ok
[INFO] [stdout] test src/bindings/bind_style.rs - bindings::bind_style::BindSlot (line 410) ... ok
[INFO] [stdout] test src/bindings/bind_style.rs - bindings::bind_style (line 19) ... ok
[INFO] [stdout] test src/bindings/forward.rs - bindings::forward (line 47) ... ok
[INFO] [stdout] test src/bindings/forward/dynamic/buffer.rs - bindings::forward::dynamic::buffer::Buffer<Element>::access_write (line 554) ... ok
[INFO] [stdout] test src/bindings/coordinates.rs - bindings::coordinates::RasterCoord2D::new (line 68) ... ok
[INFO] [stdout] test src/bindings/coordinates.rs - bindings::coordinates::RasterCoord2D::origin (line 83) ... ok
[INFO] [stdout] test src/bindings/coordinates.rs - bindings::coordinates::RasterCoord2D (line 39) ... ok
[INFO] [stdout] test src/bindings/forward/dynamic/buffer.rs - bindings::forward::dynamic::buffer::CRepr (line 625) ... ok
[INFO] [stdout] test src/bindings/forward/dynamic/buffer.rs - bindings::forward::dynamic::buffer::Buffer (line 166) ... ok
[INFO] [stdout] test src/bindings/forward/dynamic/buffer.rs - bindings::forward::dynamic::buffer::CPUWriteAccess<'_,Element>::write (line 250) ... ok
[INFO] [stdout] test src/bindings/forward/dynamic/frame_texture.rs - bindings::forward::dynamic::frame_texture::CPUWriteGuard (line 245) ... ok
[INFO] [stdout] test src/bindings/forward/dynamic/frame_texture.rs - bindings::forward::dynamic::frame_texture::CPUWriteGuard<'a,Format>::replace (line 496) ... ok
[INFO] [stdout] test src/bindings/forward/dynamic/buffer.rs - bindings::forward::dynamic::buffer::Buffer<Element>::new (line 468) ... ok
[INFO] [stdout] test src/bindings/forward/dynamic/frame_texture.rs - bindings::forward::dynamic::frame_texture::FrameTexture<Format>::dequeue (line 627) ... ok
[INFO] [stdout] test src/bindings/forward/static/buffer.rs - bindings::forward::r#static::buffer::Buffer (line 100) ... ok
[INFO] [stdout] test src/bindings/forward/dynamic/frame_texture.rs - bindings::forward::dynamic::frame_texture (line 26) ... ok
[INFO] [stdout] test src/bindings/forward/dynamic/frame_texture.rs - bindings::forward::dynamic::frame_texture::FrameTexture<Format>::new (line 549) ... ok
[INFO] [stdout] test src/bindings/forward/static/buffer.rs - bindings::forward::r#static::buffer (line 27) ... ok
[INFO] [stdout] test src/bindings/forward/static/texture.rs - bindings::forward::r#static::texture::Texture<Format>::from_software (line 188) ... ok
[INFO] [stdout] test src/bindings/forward/static/buffer.rs - bindings::forward::r#static::buffer::Buffer<Element>::new (line 222) ... ok
[INFO] [stdout] test src/bindings/forward/dynamic/buffer.rs - bindings::forward::dynamic::buffer (line 36) ... ok
[INFO] [stdout] test src/bindings/forward/dynamic/frame_texture.rs - bindings::forward::dynamic::frame_texture::TextureRenderSide (line 156) ... ok
[INFO] [stdout] test src/bindings/forward/static/texture.rs - bindings::forward::r#static::texture (line 17) ... ok
[INFO] [stdout] test src/bindings/software/texture.rs - bindings::software::texture::Texture<Format>::dump_tga (line 913) ... ok
[INFO] [stdout] test src/bindings/software/texture.rs - bindings::software::texture::Texture<Format>::new (line 481) ... ok
[INFO] [stdout] test src/bindings/software/texture.rs - bindings::software::texture::Texture<Format>::new_from_path (line 636) - compile ... ok
[INFO] [stdout] test src/bindings/software/texture.rs - bindings::software::texture::Texture (line 133) ... ok
[INFO] [stdout] test src/bindings/software/texture.rs - bindings::software::texture::Texture (line 147) ... ok
[INFO] [stdout] test src/bindings/software/texture.rs - bindings::software::texture::linear_to_srgb (line 87) ... ok
[INFO] [stdout] test src/bindings/software/texture/scaled_32.rs - bindings::software::texture::scaled_32::S32 (line 306) ... ok
[INFO] [stdout] test src/bindings/software/texture.rs - bindings::software::texture::Texture<Format>::new_with_parallel (line 562) ... ok
[INFO] [stdout] test src/bindings/software/texture.rs - bindings::software::texture::Normalized (line 296) ... ok
[INFO] [stdout] test src/bindings/software/texture.rs - bindings::software::texture::Texture<Format>::new_with (line 517) ... ok
[INFO] [stdout] test src/bindings/software/texture.rs - bindings::software::texture::Texel::new_clamping (line 228) ... ok
[INFO] [stdout] test src/bindings/software/texture.rs - bindings::software::texture (line 33) ... ok
[INFO] [stdout] test src/bindings/sampler.rs - bindings::sampler (line 16) ... ok
[INFO] [stdout] test src/bindings/forward/static/texture.rs - bindings::forward::r#static::texture::Texture<Format>::new (line 117) ... ok
[INFO] [stdout] test src/bindings/software/texture.rs - bindings::software::texture::Texture<Format>::new_cloning (line 710) ... ok
[INFO] [stdout] test src/bindings/software/texture.rs - bindings::software::texture::Texture<Format>::map (line 776) ... ok
[INFO] [stdout] test src/bindings/forward/static/texture.rs - bindings::forward::r#static::texture::Texture<Format>::new_slice (line 286) ... ok
[INFO] [stdout] test src/bindings/software/texture/scaled_32.rs - bindings::software::texture::scaled_32::Scaled32::offset_clamped (line 243) ... ok
[INFO] [stdout] test src/bindings/software/texture/scaled_32.rs - bindings::software::texture::scaled_32::Scaled32::x_evenly (line 160) ... ok
[INFO] [stdout] test src/bindings/software/texture/scaled_32.rs - bindings::software::texture::scaled_32::Scaled32 (line 33) ... ok
[INFO] [stdout] test src/bindings/software/texture/vtexture.rs - bindings::software::texture::vtexture (line 11) ... ok
[INFO] [stdout] test src/bindings/software/texture/scaled_32.rs - bindings::software::texture::scaled_32::Scaled32::from (line 339) ... ok
[INFO] [stdout] test src/bindings/software/texture/scaled_32.rs - bindings::software::texture::scaled_32::Scaled32::s32 (line 213) ... ok
[INFO] [stdout] test src/bindings/software/texture/vtexture.rs - bindings::software::texture::vtexture (line 31) ... ok
[INFO] [stdout] test src/bindings/software/texture/vtexture.rs - bindings::software::texture::vtexture::VTexture::height (line 101) ... ok
[INFO] [stdout] test src/bindings/software/texture.rs - bindings::software::texture::sealed (line 395) ... ok
[INFO] [stdout] test src/bindings/software/texture/scaled_32.rs - bindings::software::texture::scaled_32::Scaled32::y_evenly (line 175) ... ok
[INFO] [stdout] test src/bindings/forward/dynamic/frame_texture.rs - bindings::forward::dynamic::frame_texture::FrameTexture (line 418) ... ok
[INFO] [stdout] test src/bindings/software/texture/vtexture.rs - bindings::software::texture::vtexture::VTexture::read (line 127) ... ok
[INFO] [stdout] test src/bindings/software/texture/scaled_32.rs - bindings::software::texture::scaled_32::Scaled32::new_clamping_inside_imprecise (line 97) ... ok
[INFO] [stdout] test src/bindings/forward/static.rs - bindings::forward::r#static (line 58) ... ok
[INFO] [stdout] test src/bindings/software/texture/scaled_32.rs - bindings::software::texture::scaled_32::Scaled32::new (line 63) ... ok
[INFO] [stdout] test src/bindings/software/texture/scaled_32.rs - bindings::software::texture::scaled_32::Scaled32::reference_texel (line 192) ... ok
[INFO] [stdout] test src/bindings/visible_to.rs - bindings::visible_to::GPUBufferUsage (line 100) ... ok
[INFO] [stdout] test src/bindings/visible_to.rs - bindings::visible_to::TextureConfig (line 187) ... ok
[INFO] [stdout] test src/bindings/visible_to.rs - bindings::visible_to::CPUStrategy (line 153) ... ok
[INFO] [stdout] test src/bindings/visible_to.rs - bindings::visible_to::TextureUsage (line 44) ... ok
[INFO] [stdout] test src/images/port.rs - images::port (line 14) ... ok
[INFO] [stdout] test src/images/index_algorithms.rs - images::index_algorithms::IndexGenerator::num_triangles (line 199) ... ok
[INFO] [stdout] test src/images/index_algorithms.rs - images::index_algorithms::IndexGenerator::num_indices (line 180) ... ok
[INFO] [stdout] test src/images/index_algorithms.rs - images::index_algorithms (line 36) ... ok
[INFO] [stdout] test src/images/index_algorithms.rs - images::index_algorithms::IndexGenerator::index_for (line 231) ... ok
[INFO] [stdout] test src/images/port.rs - images::port::Port::start (line 665) ... ok
[INFO] [stdout] test src/images/projection.rs - images::projection (line 19) ... ok
[INFO] [stdout] test src/bindings/visible_to.rs - bindings::visible_to (line 20) ... ok
[INFO] [stdout] test src/bindings/software/texture/vtexture.rs - bindings::software::texture::vtexture::VTexture::width (line 87) ... ok
[INFO] [stdout] test src/images/index_algorithms.rs - images::index_algorithms (line 16) ... ok
[INFO] [stdout] test src/images/port.rs - images::port::Port::add_fixed_pass (line 548) ... ok
[INFO] [stdout] test src/images/port.rs - images::port::Port::new (line 499) ... ok
[INFO] [stdout] test src/images/projection.rs - images::projection (line 35) ... ok
[INFO] [stdout] test src/bindings/software/texture.rs - bindings::software::texture::sample_bilinear (line 849) ... ok
[INFO] [stdout] test src/bindings/software/texture.rs - bindings::software::texture::Texel (line 171) ... ok
[INFO] [stdout] test src/images/render_pass.rs - images::render_pass::DrawCommand (line 135) ... ok
[INFO] [stdout] test src/images/shader.rs - images::shader::FragmentShader (line 42) ... ok
[INFO] [stdout] test src/images/render_pass.rs - images::render_pass::DrawCommand (line 143) ... ok
[INFO] [stdout] test src/images/shader.rs - images::shader::VertexShader (line 81) ... ok
[INFO] [stdout] test src/images/projection.rs - images::projection::Projection::new (line 196) ... ok
[INFO] [stdout] test src/images/index_algorithms.rs - images::index_algorithms::IndexGenerator::new (line 159) ... ok
[INFO] [stdout] test src/images/port.rs - images::port::PortReporter (line 273) ... ok
[INFO] [stdout] test src/images/index_algorithms.rs - images::index_algorithms::IndexGenerator::default (line 301) ... ok
[INFO] [stdout] test src/images/vertex_layout.rs - images::vertex_layout::VertexLayout::add_field (line 137) ... ok
[INFO] [stdout] test src/images/projection.rs - images::projection::Projection::project (line 231) ... ok
[INFO] [stdout] test src/images/view.rs - images::view (line 24) - compile ... ok
[INFO] [stdout] test src/images/projection.rs - images::projection::Projection (line 164) ... ok
[INFO] [stdout] test src/images/projection.rs - images::projection::WorldCoord (line 339) ... ok
[INFO] [stdout] test src/images/view.rs - images::view (line 15) ... ok
[INFO] [stdout] test src/images/vertex_layout.rs - images::vertex_layout (line 16) ... ok
[INFO] [stdout] test src/lib.rs - (line 203) ... ok
[INFO] [stdout] test src/lib.rs - (line 116) ... ok
[INFO] [stdout] test src/images/view.rs - images::view::View::for_testing (line 257) ... ok
[INFO] [stdout] test src/images/vertex_layout.rs - images::vertex_layout::VertexLayout::new (line 111) ... ok
[INFO] [stdout] test src/images/vertex_layout.rs - images::vertex_layout::VertexFieldType (line 78) ... ok
[INFO] [stdout] test src/images/shader.rs - images::shader::VertexShader::new (line 190) ... ok
[INFO] [stdout] test src/images/shader.rs - images::shader::FragmentShader::new (line 151) ... ok
[INFO] [stdout] test src/images/render_pass.rs - images::render_pass (line 15) ... ok
[INFO] [stdout] test src/images/projection.rs - images::projection::ScreenCoord (line 316) ... ok
[INFO] [stdout] test src/images/shader.rs - images::shader::FragmentShader (line 26) ... ok
[INFO] [stdout] test src/images/vertex_layout.rs - images::vertex_layout::VertexLayout (line 42) ... ok
[INFO] [stdout] test src/images/shader.rs - images::shader::VertexShader (line 99) ... ok
[INFO] [stdout] test src/lib.rs - (line 348) ... ok
[INFO] [stdout] test src/lib.rs - (line 427) ... ok
[INFO] [stdout] test src/images/vertex_layout.rs - images::vertex_layout::VertexLayout::default (line 168) ... ok
[INFO] [stdout] test src/pixel_formats.rs - pixel_formats::R8UNorm (line 165) ... ok
[INFO] [stdout] test src/lib.rs - pixel_formats (line 530) ... ok
[INFO] [stdout] test src/pixel_formats.rs - pixel_formats::Unorm4 (line 369) ... ok
[INFO] [stdout] test src/lib.rs - images (line 508) ... ok
[INFO] [stdout] test src/lib.rs - pixel_formats (line 538) ... ok
[INFO] [stdout] test src/pixel_formats.rs - pixel_formats::RGBA8UNorm (line 418) ... ok
[INFO] [stdout] test src/pixel_formats.rs - pixel_formats::Float4 (line 601) ... ok
[INFO] [stdout] test src/pixel_formats.rs - pixel_formats::BGRA8UnormPixelSRGB (line 522) ... ok
[INFO] [stdout] test src/lib.rs - (line 310) ... ok
[INFO] [stdout] test src/lib.rs - (line 378) ... ok
[INFO] [stdout] test src/lib.rs - (line 293) ... ok
[INFO] [stdout] test src/lib.rs - (line 80) ... ok
[INFO] [stdout] test src/images/view.rs - images::view (line 36) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 119 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.63s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 3.45s; merged doctests compilation took 2.77s
[INFO] running `Command { std: "docker" "inspect" "36b3eaf0f4321aede9bae4a9a95d8e3a6664ad62d5903791df8ae568a9896593", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "36b3eaf0f4321aede9bae4a9a95d8e3a6664ad62d5903791df8ae568a9896593", kill_on_drop: false }`
[INFO] [stdout] 36b3eaf0f4321aede9bae4a9a95d8e3a6664ad62d5903791df8ae568a9896593