[INFO] cloning repository https://github.com/blaksmatic/vcli
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/blaksmatic/vcli" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fblaksmatic%2Fvcli", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fblaksmatic%2Fvcli'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 9ac40b4fa72400ab7aeb849f43760902ccd2e533
[INFO] testing blaksmatic/vcli against beta-2026-04-21 for beta-1.96-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fblaksmatic%2Fvcli" "/workspace/builds/worker-4-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc2/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-4-tc2/source/rust-toolchain.toml
[INFO] started tweaking git repo https://github.com/blaksmatic/vcli
[INFO] finished tweaking git repo https://github.com/blaksmatic/vcli
[INFO] tweaked toml for git repo https://github.com/blaksmatic/vcli written to /workspace/builds/worker-4-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/blaksmatic/vcli on toolchain beta-2026-04-21
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2026-04-21" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/blaksmatic/vcli 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" "+beta-2026-04-21" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-04-21" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] d628b36a3720570d4f27baf7b917d496aaa3bfd0842c7b6199fc4fe7fdd2eec8
[INFO] running `Command { std: "docker" "start" "-a" "d628b36a3720570d4f27baf7b917d496aaa3bfd0842c7b6199fc4fe7fdd2eec8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d628b36a3720570d4f27baf7b917d496aaa3bfd0842c7b6199fc4fe7fdd2eec8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d628b36a3720570d4f27baf7b917d496aaa3bfd0842c7b6199fc4fe7fdd2eec8", kill_on_drop: false }`
[INFO] [stdout] d628b36a3720570d4f27baf7b917d496aaa3bfd0842c7b6199fc4fe7fdd2eec8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-04-21" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] e962e8759ba76761666d6e85d3dd330759fac29c04e5c94ba5c8e488d4e74c0c
[INFO] running `Command { std: "docker" "start" "-a" "e962e8759ba76761666d6e85d3dd330759fac29c04e5c94ba5c8e488d4e74c0c", kill_on_drop: false }`
[INFO] [stderr]    Compiling unicode-ident v1.0.24
[INFO] [stderr]    Compiling libc v0.2.185
[INFO] [stderr]    Compiling libm v0.2.16
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling zerocopy v0.8.48
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling itoa v1.0.18
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling hashbrown v0.17.0
[INFO] [stderr]    Compiling bitflags v2.11.1
[INFO] [stderr]    Compiling quote v1.0.45
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling tinyvec v1.11.0
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling indexmap v2.14.0
[INFO] [stderr]    Compiling pin-project-lite v0.2.17
[INFO] [stderr]    Compiling simd-adler32 v0.3.9
[INFO] [stderr]    Compiling ryu v1.0.23
[INFO] [stderr]    Compiling bytemuck v1.25.0
[INFO] [stderr]    Compiling unicode-normalization v0.1.25
[INFO] [stderr]    Compiling stable_deref_trait v1.2.1
[INFO] [stderr]    Compiling arrayvec v0.7.6
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling as-slice v0.2.1
[INFO] [stderr]    Compiling rayon v1.12.0
[INFO] [stderr]    Compiling built v0.8.0
[INFO] [stderr]    Compiling av-scenechange v0.14.1
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling uuid v1.23.1
[INFO] [stderr]    Compiling rav1e v0.8.1
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling aligned v0.4.3
[INFO] [stderr]    Compiling crc32fast v1.5.0
[INFO] [stderr]    Compiling no_std_io2 v0.9.3
[INFO] [stderr]    Compiling nom v8.0.0
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling y4m v0.8.0
[INFO] [stderr]    Compiling num-integer v0.1.46
[INFO] [stderr]    Compiling quick-error v2.0.1
[INFO] [stderr]    Compiling pastey v0.1.1
[INFO] [stderr]    Compiling num-complex v0.4.6
[INFO] [stderr]    Compiling num-bigint v0.4.6
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling bitstream-io v4.10.0
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling safe_arch v0.7.4
[INFO] [stderr]    Compiling itertools v0.14.0
[INFO] [stderr]    Compiling simd_helpers v0.1.0
[INFO] [stderr]    Compiling imgref v1.12.0
[INFO] [stderr]    Compiling zune-core v0.5.1
[INFO] [stderr]    Compiling noop_proc_macro v0.3.0
[INFO] [stderr]    Compiling weezl v0.1.12
[INFO] [stderr]    Compiling num-rational v0.4.2
[INFO] [stderr]    Compiling maybe-rayon v0.1.1
[INFO] [stderr]    Compiling cc v1.2.60
[INFO] [stderr]    Compiling zune-jpeg v0.5.15
[INFO] [stderr]    Compiling loop9 v0.1.5
[INFO] [stderr]    Compiling wide v0.7.33
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling approx v0.5.1
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling avif-serialize v0.8.8
[INFO] [stderr]    Compiling fdeflate v0.3.7
[INFO] [stderr]    Compiling zune-inflate v0.2.54
[INFO] [stderr]    Compiling pxfm v0.1.29
[INFO] [stderr]    Compiling ttf-parser v0.25.1
[INFO] [stderr]    Compiling bit_field v0.10.3
[INFO] [stderr]    Compiling lebe v0.5.3
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling byteorder-lite v0.1.0
[INFO] [stderr]    Compiling pkg-config v0.3.33
[INFO] [stderr]    Compiling bytes v1.11.1
[INFO] [stderr]    Compiling rgb v0.8.53
[INFO] [stderr]    Compiling gif v0.14.2
[INFO] [stderr]    Compiling image-webp v0.2.4
[INFO] [stderr]    Compiling libsqlite3-sys v0.28.0
[INFO] [stderr]    Compiling zerocopy-derive v0.8.48
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling equator-macro v0.4.2
[INFO] [stderr]    Compiling arg_enum_proc_macro v0.3.4
[INFO] [stderr]    Compiling equator v0.4.2
[INFO] [stderr]    Compiling aligned-vec v0.6.4
[INFO] [stderr]    Compiling profiling-procmacros v1.0.17
[INFO] [stderr]    Compiling profiling v1.0.17
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling v_frame v0.3.9
[INFO] [stderr]    Compiling fax_derive v0.2.0
[INFO] [stderr]    Compiling num-derive v0.4.2
[INFO] [stderr]    Compiling tokio-macros v2.7.0
[INFO] [stderr]    Compiling av1-grain v0.2.5
[INFO] [stderr]    Compiling fax v0.2.6
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tokio v1.52.1
[INFO] [stderr]    Compiling moxcms v0.8.1
[INFO] [stderr]    Compiling owned_ttf_parser v0.25.1
[INFO] [stderr]    Compiling simba v0.8.1
[INFO] [stderr]    Compiling png v0.18.1
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling num-iter v0.1.45
[INFO] [stderr]    Compiling qoi v0.4.1
[INFO] [stderr]    Compiling ab_glyph_rasterizer v0.1.10
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling vcli-core v0.0.1 (/opt/rustwide/workdir/crates/vcli-core)
[INFO] [stderr]    Compiling ab_glyph v0.2.32
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling exr v1.74.0
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling tiff v0.11.3
[INFO] [stderr]    Compiling rand v0.8.6
[INFO] [stderr]    Compiling ravif v0.13.0
[INFO] [stderr]    Compiling rand_distr v0.4.3
[INFO] [stderr]    Compiling nalgebra v0.32.6
[INFO] [stderr]    Compiling num v0.4.3
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling image v0.25.10
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling itertools v0.12.1
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling vcli-capture v0.0.1 (/opt/rustwide/workdir/crates/vcli-capture)
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling deranged v0.5.8
[INFO] [stderr]    Compiling vcli-ipc v0.0.1 (/opt/rustwide/workdir/crates/vcli-ipc)
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling terminal_size v0.4.4
[INFO] [stderr]    Compiling dashmap v6.1.0
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling hashlink v0.9.1
[INFO] [stderr]    Compiling vcli-dsl v0.0.1 (/opt/rustwide/workdir/crates/vcli-dsl)
[INFO] [stderr]    Compiling tracing-serde v0.2.0
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling num-conv v0.2.1
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling clap_lex v1.1.0
[INFO] [stderr]    Compiling vcli-runtime v0.0.1 (/opt/rustwide/workdir/crates/vcli-runtime)
[INFO] [stderr]    Compiling time-core v0.1.8
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling time v0.3.47
[INFO] [stderr]    Compiling clap_derive v4.6.1
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling dirs-sys v0.4.1
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling signal-hook v0.3.18
[INFO] [stderr]    Compiling vcli-input v0.0.1 (/opt/rustwide/workdir/crates/vcli-input)
[INFO] [stderr]    Compiling futures-macro v0.3.32
[INFO] [stderr]    Compiling vcli-daemon v0.0.1 (/opt/rustwide/workdir/crates/vcli-daemon)
[INFO] [stderr]    Compiling imageproc v0.25.0
[INFO] [stderr]    Compiling symlink v0.1.0
[INFO] [stderr]    Compiling slab v0.4.12
[INFO] [stderr]    Compiling tracing-appender v0.2.5
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling signal-hook-tokio v0.3.1
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling dirs v5.0.1
[INFO] [stderr]    Compiling fs2 v0.4.3
[INFO] [stderr]    Compiling vcli-cli v0.0.1 (/opt/rustwide/workdir/crates/vcli-cli)
[INFO] [stderr]    Compiling vcli-perception v0.0.1 (/opt/rustwide/workdir/crates/vcli-perception)
[INFO] [stderr]    Compiling rusqlite v0.31.0
[INFO] [stderr]    Compiling vcli-store v0.0.1 (/opt/rustwide/workdir/crates/vcli-store)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 3m 33s
[INFO] running `Command { std: "docker" "inspect" "e962e8759ba76761666d6e85d3dd330759fac29c04e5c94ba5c8e488d4e74c0c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e962e8759ba76761666d6e85d3dd330759fac29c04e5c94ba5c8e488d4e74c0c", kill_on_drop: false }`
[INFO] [stdout] e962e8759ba76761666d6e85d3dd330759fac29c04e5c94ba5c8e488d4e74c0c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-04-21" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 295f1e5b2d60172cdd7f1f366d57f7587d846cac41c4f8350cbc6dbb0f5de1d4
[INFO] running `Command { std: "docker" "start" "-a" "295f1e5b2d60172cdd7f1f366d57f7587d846cac41c4f8350cbc6dbb0f5de1d4", kill_on_drop: false }`
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling fastrand v2.4.1
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling bit-vec v0.8.0
[INFO] [stderr]    Compiling quick-error v1.2.3
[INFO] [stderr]    Compiling unarray v0.1.4
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling tokio v1.52.1
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling predicates-core v1.0.10
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling rand v0.9.4
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling float-cmp v0.10.0
[INFO] [stderr]    Compiling assert_cmd v2.2.1
[INFO] [stderr]    Compiling termtree v0.5.1
[INFO] [stderr]    Compiling normalize-line-endings v0.3.0
[INFO] [stderr]    Compiling difflib v0.4.0
[INFO] [stderr]    Compiling predicates-tree v1.0.13
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling terminal_size v0.4.4
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling rusty-fork v0.3.1
[INFO] [stderr]    Compiling vcli-capture v0.0.1 (/opt/rustwide/workdir/crates/vcli-capture)
[INFO] [stderr]    Compiling proptest v1.11.0
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling bstr v1.12.1
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling vcli-ipc v0.0.1 (/opt/rustwide/workdir/crates/vcli-ipc)
[INFO] [stderr]    Compiling tokio-stream v0.1.18
[INFO] [stderr]    Compiling signal-hook-tokio v0.3.1
[INFO] [stderr]    Compiling predicates v3.1.4
[INFO] [stderr]    Compiling tokio-test v0.4.5
[INFO] [stderr]    Compiling tracing-appender v0.2.5
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling vcli-daemon v0.0.1 (/opt/rustwide/workdir/crates/vcli-daemon)
[INFO] [stderr]    Compiling vcli-cli v0.0.1 (/opt/rustwide/workdir/crates/vcli-cli)
[INFO] [stderr]    Compiling vcli-runtime v0.0.1 (/opt/rustwide/workdir/crates/vcli-runtime)
[INFO] [stderr]    Compiling vcli-store v0.0.1 (/opt/rustwide/workdir/crates/vcli-store)
[INFO] [stderr]    Compiling vcli-core v0.0.1 (/opt/rustwide/workdir/crates/vcli-core)
[INFO] [stderr]    Compiling vcli-input v0.0.1 (/opt/rustwide/workdir/crates/vcli-input)
[INFO] [stderr]    Compiling vcli-dsl v0.0.1 (/opt/rustwide/workdir/crates/vcli-dsl)
[INFO] [stderr]    Compiling vcli-perception v0.0.1 (/opt/rustwide/workdir/crates/vcli-perception)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 43s
[INFO] running `Command { std: "docker" "inspect" "295f1e5b2d60172cdd7f1f366d57f7587d846cac41c4f8350cbc6dbb0f5de1d4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "295f1e5b2d60172cdd7f1f366d57f7587d846cac41c4f8350cbc6dbb0f5de1d4", kill_on_drop: false }`
[INFO] [stdout] 295f1e5b2d60172cdd7f1f366d57f7587d846cac41c4f8350cbc6dbb0f5de1d4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-04-21" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 63df2df078c66259cc124eaa54167810f65be4b51759b059a1c66d3b54e33bca
[INFO] running `Command { std: "docker" "start" "-a" "63df2df078c66259cc124eaa54167810f65be4b51759b059a1c66d3b54e33bca", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.47s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/vcli_capture-fcd100aaffa5cce1)
[INFO] [stdout] 
[INFO] [stdout] running 17 tests
[INFO] [stdout] test capture::tests::capture_trait_is_object_safe ... ok
[INFO] [stdout] test capture::tests::display_id_primary_is_zero ... ok
[INFO] [stdout] test error::tests::codes_are_stable ... ok
[INFO] [stdout] test capture::tests::window_descriptor_is_clone_eq ... ok
[INFO] [stdout] test error::tests::display_messages_are_informative ... ok
[INFO] [stdout] test mock::tests::armed_error_is_returned_once ... ok
[INFO] [stdout] test mock::tests::grab_window_unknown_id_errors ... ok
[INFO] [stdout] test mock::tests::grab_window_uses_configured_frames ... ok
[INFO] [stdout] test mock::tests::mock_is_send_sync ... ok
[INFO] [stdout] test mock::tests::screen_frames_cycle_in_order ... ok
[INFO] [stdout] test mock::tests::empty_mock_returns_empty_windows ... ok
[INFO] [stdout] test mock::tests::mock_works_through_dyn_capture ... ok
[INFO] [stdout] test mock::tests::empty_mock_screen_grab_errors ... ok
[INFO] [stdout] test permission::tests::non_macos_always_granted ... ok
[INFO] [stdout] test permission::tests::non_macos_request_is_no_op ... ok
[INFO] [stdout] test permission::tests::permission_status_is_copy_eq ... ok
[INFO] [stdout] test mock::tests::enumerate_returns_configured_windows ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/macos_sck_smoke.rs (/opt/rustwide/target/debug/deps/macos_sck_smoke-fe29280f1d34c188)
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/vcli_cli-709149cb6158468b)
[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] [stdout] 
[INFO] [stdout] running 78 tests
[INFO] [stdout] test cli::tests::default_output_mode_is_pretty ... ok
[INFO] [stdout] test cli::tests::global_socket_override_parses ... ok
[INFO] [stdout] test cli::tests::cancel_rejects_garbage_id ... ok
[INFO] [stdout] test cli::tests::list_rejects_unknown_state ... ok
[INFO] [stdout] test cli::tests::cancel_requires_program_id ... ok
[INFO] [stdout] test cli::tests::cancel_parses_id_and_reason ... ok
[INFO] [stdout] test cli::tests::health_and_gc_parse ... ok
[INFO] [stdout] test cli::tests::daemon_subcommands_all_parse ... ok
[INFO] [stdout] test cli::tests::resume_from_start_flag ... ok
[INFO] [stdout] test cli::tests::logs_parses_follow_and_since ... ok
[INFO] [stdout] test cli::tests::resume_without_flag_defaults_false ... ok
[INFO] [stdout] test cli::tests::submit_without_file_errors ... ok
[INFO] [stdout] test cli::tests::list_with_each_state_value ... ok
[INFO] [stdout] test cli::tests::submit_watch_and_json_flags_parse ... ok
[INFO] [stdout] test commands::daemon::tests::pid_file_candidate_is_alongside_socket ... ok
[INFO] [stdout] test commands::cancel::tests::unknown_program_payload_maps_to_not_found ... ok
[INFO] [stdout] test cli::tests::submit_with_file_parses ... ok
[INFO] [stdout] test commands::cancel::tests::summary_with_reason ... ok
[INFO] [stdout] test commands::cancel::tests::summary_without_reason ... ok
[INFO] [stdout] test commands::daemon::tests::start_is_idempotent_when_socket_already_exists ... ok
[INFO] [stdout] test commands::daemon::tests::status_json_mode_emits_running_field ... ok
[INFO] [stdout] test client::tests::connect_to_missing_socket_returns_daemon_down ... ok
[INFO] [stdout] test commands::daemon::tests::status_reports_not_running_when_socket_missing ... ok
[INFO] [stdout] test commands::daemon::tests::stop_with_no_socket_is_not_running ... ok
[INFO] [stdout] test commands::gc::tests::summary_with_counts ... ok
[INFO] [stdout] test commands::gc::tests::summary_with_no_fields_is_still_ok ... ok
[INFO] [stdout] test commands::health::tests::pretty_shows_last_error_when_present ... ok
[INFO] [stdout] test commands::health::tests::pretty_includes_known_fields ... ok
[INFO] [stdout] test commands::list::tests::empty_list_shows_header_only ... ok
[INFO] [stdout] test commands::list::tests::list_with_two_items_prints_both_rows ... ok
[INFO] [stdout] test commands::list::tests::missing_fields_do_not_panic ... ok
[INFO] [stdout] test commands::logs::tests::parse_since_accepts_our_own_iso_format ... ok
[INFO] [stdout] test commands::logs::tests::format_event_line_has_timestamp_and_kind ... ok
[INFO] [stdout] test commands::logs::tests::parse_since_none_is_ok_none ... ok
[INFO] [stdout] test commands::logs::tests::parse_since_rejects_missing_z ... ok
[INFO] [stdout] test commands::resume::tests::bad_state_transition_becomes_validation_exit_2 ... ok
[INFO] [stdout] test commands::resume::tests::not_resumable_becomes_validation_exit_2 ... ok
[INFO] [stdout] test commands::resume::tests::resume_precondition_failed_becomes_validation_exit_2 ... ok
[INFO] [stdout] test commands::resume::tests::summary_missing_step_defaults_to_zero ... ok
[INFO] [stdout] test commands::resume::tests::summary_reports_step ... ok
[INFO] [stdout] test commands::resume::tests::unknown_program_still_maps_to_not_found ... ok
[INFO] [stdout] test commands::submit::tests::completed_event_is_terminal ... ok
[INFO] [stdout] test commands::submit::tests::extract_program_id_happy_path ... ok
[INFO] [stdout] test commands::logs::tests::parse_since_rejects_garbage ... ok
[INFO] [stdout] test commands::submit::tests::extract_program_id_missing_field_is_generic_error ... ok
[INFO] [stdout] test commands::submit::tests::extract_program_id_malformed_is_generic_error ... ok
[INFO] [stdout] test commands::logs::tests::parse_since_without_millis_also_works ... ok
[INFO] [stdout] test commands::submit::tests::failed_event_is_terminal ... ok
[INFO] [stdout] test commands::submit::tests::state_changed_to_cancelled_is_terminal ... ok
[INFO] [stdout] test commands::submit::tests::state_changed_to_running_is_not_terminal ... ok
[INFO] [stdout] test commands::submit::tests::watch_fired_is_not_terminal ... ok
[INFO] [stdout] test error::tests::daemon_down_maps_to_exit_4 ... ok
[INFO] [stdout] test error::tests::from_payload_includes_path_line_col_hint ... ok
[INFO] [stdout] test error::tests::from_payload_internal_is_generic ... ok
[INFO] [stdout] test error::tests::from_payload_invalid_program_is_validation ... ok
[INFO] [stdout] test error::tests::exit_code_integers_match_spec ... ok
[INFO] [stdout] test error::tests::from_payload_unknown_program_is_not_found ... ok
[INFO] [stdout] test error::tests::ipc_connection_refused_maps_to_daemon_down ... ok
[INFO] [stdout] test error::tests::ipc_other_io_maps_to_generic ... ok
[INFO] [stdout] test error::tests::ipc_not_found_maps_to_daemon_down ... ok
[INFO] [stdout] test error::tests::generic_falls_through_to_exit_1 ... ok
[INFO] [stdout] test error::tests::validation_error_maps_to_exit_2 ... ok
[INFO] [stdout] test error::tests::not_found_error_maps_to_exit_3 ... ok
[INFO] [stdout] test cli::tests::list_without_state_filter ... ok
[INFO] [stdout] test format::tests::render_value_json_serializes_value ... ok
[INFO] [stdout] test format::tests::render_value_pretty_returns_pretty_arg ... ok
[INFO] [stdout] test format::tests::empty_table_renders_just_header ... ok
[INFO] [stdout] test format::tests::short_rows_get_padded ... ok
[INFO] [stdout] test util::tests::format_unix_ms_epoch_is_1970_01_01 ... ok
[INFO] [stdout] test format::tests::table_columns_align_to_widest_cell ... ok
[INFO] [stdout] test util::tests::format_unix_ms_known_instant ... ok
[INFO] [stdout] test util::tests::resolve_socket_honours_explicit_override ... ok
[INFO] [stdout] test util::tests::read_program_file_reports_missing_as_generic ... ok
[INFO] [stdout] test util::tests::read_program_file_reports_bad_json_as_validation ... ok
[INFO] [stdout] test util::tests::read_program_file_roundtrips_small_json ... ok
[INFO] [stdout] test commands::daemon::tests::wait_for_socket_returns_ok_when_file_appears ... ok
[INFO] [stdout] test commands::daemon::tests::start_reports_timeout_when_daemon_binary_absent ... ok
[INFO] [stdout] test commands::daemon::tests::wait_for_socket_gone_times_out_when_file_stays ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 78 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.17s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/vcli.rs (/opt/rustwide/target/debug/deps/vcli-429a3b90a52f771e)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/bin.rs (/opt/rustwide/target/debug/deps/bin-a9b5d71d18b3a868)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test version_exits_zero ... ok
[INFO] [stdout] test cancel_with_garbage_id_is_nonzero ... ok
[INFO] [stdout] test help_exits_zero ... ok
[INFO] [stdout] test health_without_daemon_exits_4 ... ok
[INFO] [stdout] test gc_without_daemon_exits_4 ... ok
[INFO] [stdout] test daemon_status_without_socket_exits_zero ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/e2e.rs (/opt/rustwide/target/debug/deps/e2e-d2c5d1f0fcd3de64)
[INFO] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stdout] test daemon_status_without_socket_reports_not_running ... ok
[INFO] [stdout] test health_json_mode_returns_valid_json ... ok
[INFO] [stdout] test daemon_status_reports_running_against_fake_daemon ... ok
[INFO] [stdout] test list_command_renders_empty_table ... ok
[INFO] [stdout] test health_command_prints_ok ... ok
[INFO] [stdout] test missing_daemon_yields_daemon_down_error ... ok
[INFO] [stdout] test gc_command_reports_ok ... ok
[INFO] [stdout] test logs_command_json_mode_emits_one_json_per_line ... ok
[INFO] [stdout] test logs_command_streams_events_and_exits ... ok
[INFO] [stdout] test cancel_command_roundtrips ... ok
[INFO] [stdout] test resume_command_roundtrips ... ok
[INFO] [stdout] test submit_with_invalid_json_file_is_validation_error ... ok
[INFO] [stdout] test submit_with_dsl_rejected_program_is_validation_error ... ok
[INFO] [stdout] test submit_command_writes_program_id ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/parse.rs (/opt/rustwide/target/debug/deps/parse-c41eec9146207c6c)
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test cancel_rejects_non_uuid ... ok
[INFO] [stdout] test daemon_without_subcommand_errors ... ok
[INFO] [stdout] test list_state_filter_accepts_each_known_value ... ok
[INFO] [stdout] test json_flag_is_global ... ok
[INFO] [stdout] test socket_flag_is_global ... ok
[INFO] [stdout] test resume_from_start_flag_can_be_before_or_after_id ... ok
[INFO] [stdout] test list_state_filter_rejects_unknown_value ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/vcli_core-274b19ed4defb796)
[INFO] [stdout] test submit_without_file_errors ... ok
[INFO] [stdout] test daemon_status_parses ... ok
[INFO] [stdout] test top_level_help_does_not_require_subcommand_to_show ... ok
[INFO] [stdout] test every_v0_command_parses_minimally ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 85 tests
[INFO] [stdout] test action::tests::click_roundtrip ... ok
[INFO] [stdout] test action::tests::key_chord_roundtrip ... ok
[INFO] [stdout] test action::tests::move_roundtrip ... ok
[INFO] [stdout] test action::tests::type_roundtrip ... ok
[INFO] [stdout] test action::tests::key_without_modifiers_omits_field ... ok
[INFO] [stdout] test action::tests::scroll_uses_default_zero_axes ... ok
[INFO] [stdout] test canonical::tests::different_values_different_hash ... ok
[INFO] [stdout] test canonical::tests::empty_containers_emit_correctly ... ok
[INFO] [stdout] test canonical::tests::predicate_hash_hex_is_64_chars ... ok
[INFO] [stdout] test canonical::tests::floats_via_ryu ... ok
[INFO] [stdout] test canonical::tests::integers_as_plain_decimal ... ok
[INFO] [stdout] test canonical::tests::keys_sorted_lexicographically ... ok
[INFO] [stdout] test canonical::tests::nfc_normalization_applied_to_strings ... ok
[INFO] [stdout] test canonical::tests::same_semantic_value_same_hash ... ok
[INFO] [stdout] test canonical::tests::sha256_matches_known_vector_abc ... ok
[INFO] [stdout] test canonical::tests::sha256_matches_known_vector_empty ... ok
[INFO] [stdout] test canonical::tests::strings_escape_minimally ... ok
[INFO] [stdout] test clock::now_unix_ms_tests::now_unix_ms_is_positive_and_recent ... ok
[INFO] [stdout] test clock::tests::test_clock_advance_moves_both_readings ... ok
[INFO] [stdout] test clock::tests::test_clock_is_send_sync ... ok
[INFO] [stdout] test clock::tests::test_clock_starts_at_zero_monotonic ... ok
[INFO] [stdout] test error::tests::code_serializes_snake_case ... ok
[INFO] [stdout] test error::tests::full_payload_roundtrip ... ok
[INFO] [stdout] test error::tests::simple_payload_omits_optional_fields ... ok
[INFO] [stdout] test events::tests::program_failed_carries_optional_step_and_emit ... ok
[INFO] [stdout] test events::tests::daemon_pressure_and_frame_skipped_roundtrip ... ok
[INFO] [stdout] test events::tests::daemon_started_stopped_roundtrip ... ok
[INFO] [stdout] test events::tests::program_completed_omits_emit_when_none ... ok
[INFO] [stdout] test events::tests::program_submitted_serializes_with_typed_tag ... ok
[INFO] [stdout] test events::tests::stream_dropped_roundtrip ... ok
[INFO] [stdout] test events::tests::watch_fired_roundtrip ... ok
[INFO] [stdout] test frame::tests::bytes_per_pixel_matches_format ... ok
[INFO] [stdout] test frame::tests::new_stores_inputs_verbatim ... ok
[INFO] [stdout] test geom::tests::rect_center_is_correct_for_even_dims ... ok
[INFO] [stdout] test geom::tests::rect_center_rounds_toward_zero_for_odd_dims ... ok
[INFO] [stdout] test geom::tests::point_serde_roundtrip ... ok
[INFO] [stdout] test geom::tests::rect_contains_is_inclusive_top_left_exclusive_bottom_right ... ok
[INFO] [stdout] test ids::tests::fresh_ids_are_unique ... ok
[INFO] [stdout] test geom::tests::rect_top_left_reports_origin ... ok
[INFO] [stdout] test geom::tests::rect_serde_roundtrip ... ok
[INFO] [stdout] test clock::tests::system_clock_monotonic_advance ... ok
[INFO] [stdout] test ids::tests::display_parse_roundtrip ... ok
[INFO] [stdout] test ids::tests::parse_rejects_garbage ... ok
[INFO] [stdout] test predicate::tests::color_at_roundtrip ... ok
[INFO] [stdout] test ids::tests::serde_transparent_wraps_uuid ... ok
[INFO] [stdout] test predicate::tests::all_of_and_any_of_and_not_roundtrips ... ok
[INFO] [stdout] test predicate::tests::elapsed_ms_since_true_roundtrip ... ok
[INFO] [stdout] test predicate::tests::match_data_center_and_top_left ... ok
[INFO] [stdout] test predicate::tests::pixel_diff_roundtrip ... ok
[INFO] [stdout] test predicate::tests::predicate_result_no_match_serializes_without_match_field ... ok
[INFO] [stdout] test predicate::tests::template_kind_roundtrip_with_default_throttle ... ok
[INFO] [stdout] test program::tests::dsl_version_major ... ok
[INFO] [stdout] test program::tests::priority_default_is_omitted_from_serialization ... ok
[INFO] [stdout] test program::tests::priority_nonzero_serializes ... ok
[INFO] [stdout] test program::tests::yt_ad_skipper_fixture_parses ... ok
[INFO] [stdout] test region::tests::absolute_roundtrip ... ok
[INFO] [stdout] test program::tests::yt_ad_skipper_fixture_roundtrips ... ok
[INFO] [stdout] test region::tests::relative_to_full_form_roundtrip ... ok
[INFO] [stdout] test region::tests::relative_to_with_defaults ... ok
[INFO] [stdout] test region::tests::unknown_kind_fails_to_parse ... ok
[INFO] [stdout] test region::tests::window_index_roundtrip ... ok
[INFO] [stdout] test state::tests::active_classification ... ok
[INFO] [stdout] test state::tests::display_parse_roundtrip ... ok
[INFO] [stdout] test region::tests::window_minimal_roundtrip ... ok
[INFO] [stdout] test region::tests::window_omits_none_fields ... ok
[INFO] [stdout] test state::tests::parse_rejects_unknown ... ok
[INFO] [stdout] test state::tests::serde_snake_case_matches_as_str ... ok
[INFO] [stdout] test step::tests::click_button_roundtrip ... ok
[INFO] [stdout] test state::tests::terminal_classification ... ok
[INFO] [stdout] test step::tests::assert_defaults_to_fail_on_fail ... ok
[INFO] [stdout] test step::tests::click_with_absolute_target ... ok
[INFO] [stdout] test step::tests::click_with_expression_target ... ok
[INFO] [stdout] test step::tests::sleep_ms_roundtrip ... ok
[INFO] [stdout] test step::tests::type_and_key_and_scroll_roundtrips ... ok
[INFO] [stdout] test trigger::tests::on_predicate_roundtrip ... ok
[INFO] [stdout] test trigger::tests::on_submit_roundtrip ... ok
[INFO] [stdout] test step::tests::wait_for_defaults_to_fail_on_timeout ... ok
[INFO] [stdout] test watch::tests::by_name_watch_roundtrip ... ok
[INFO] [stdout] test watch::tests::inline_predicate_watch_parses ... ok
[INFO] [stdout] test watch::tests::lifetime_variants_roundtrip ... ok
[INFO] [stdout] test trigger::tests::manual_roundtrip ... ok
[INFO] [stdout] test trigger::tests::on_schedule_is_rejected ... ok
[INFO] [stdout] test frame::tests::new_panics_on_too_small_buffer - should panic ... ok
[INFO] [stdout] test canonical::tests::hash_is_stable_across_whitespace_variants ... ok
[INFO] [stdout] test canonical::tests::canonical_invariant_under_key_shuffle ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 85 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.43s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/fixture_canonical.rs (/opt/rustwide/target/debug/deps/fixture_canonical-8519af9304546500)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test fixture_canonical_bytes_stable_across_parse_reserialize ... ok
[INFO] [stdout] test fixture_hash_is_stable ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/vcli_daemon-c5039725f0cdb335)
[INFO] [stdout] 
[INFO] [stdout] running 36 tests
[INFO] [stdout] test error::tests::paths_maps_to_internal ... ok
[INFO] [stdout] test error::tests::invalid_program_maps_to_invalid_program_code ... ok
[INFO] [stdout] test config::tests::ensure_dirs_creates_nested_paths ... ok
[INFO] [stdout] test error::tests::backend_init_renders_message_and_maps_to_internal_code ... ok
[INFO] [stdout] test error::tests::already_running_display_contains_pid_and_path ... ok
[INFO] [stdout] test factory_mock::tests::build_returns_ok_with_no_shutdown_guard ... ok
[INFO] [stdout] test bridge::tests::broadcast_fanout_reaches_multiple_subscribers ... ok
[INFO] [stdout] test config::tests::with_roots_builds_a_usable_config ... ok
[INFO] [stdout] test bridge::tests::command_channel_is_cloneable_and_reaches_scheduler ... ok
[INFO] [stdout] test handler::tests::cancel_sends_command_and_returns_ok ... ok
[INFO] [stdout] test handler::tests::health_returns_version_and_uptime ... ok
[INFO] [stdout] test handler::tests::shutdown_triggers_oneshot_and_cmd ... ok
[INFO] [stdout] test handler::tests::resume_rejects_non_resumable_program ... ok
[INFO] [stdout] test handler::tests::resume_transitions_store_and_sends_command ... ok
[INFO] [stdout] test handler::tests::events_stream_drains_history_then_closes_when_not_following ... ok
[INFO] [stdout] test handler::tests::list_returns_all_programs_when_no_filter ... ok
[INFO] [stdout] test handler::tests::gc_returns_report_shape ... ok
[INFO] [stdout] test persist::tests::daemon_started_has_no_program_id ... ok
[INFO] [stdout] test persist::tests::state_changed_has_program_id ... ok
[INFO] [stdout] test pidfile::tests::acquire_creates_pidfile_with_current_pid ... ok
[INFO] [stdout] test pidfile::tests::drop_unlinks_pidfile ... ok
[INFO] [stdout] test pidfile::tests::release_unlinks_pidfile ... ok
[INFO] [stdout] test pidfile::tests::parent_dir_is_created_on_acquire ... ok
[INFO] [stdout] test pidfile::tests::second_acquire_in_same_process_returns_already_running ... ok
[INFO] [stdout] test shutdown::tests::emit_daemon_stopped_sends_one_event ... ok
[INFO] [stdout] test shutdown::tests::install_handler_is_idempotent_without_signal ... ok
[INFO] [stdout] test startup::tests::emit_daemon_started_sends_one_event ... ok
[INFO] [stdout] test startup::tests::emits_two_events_per_recovered_program ... ok
[INFO] [stdout] test handler::tests::start_sends_command_and_returns_ok ... ok
[INFO] [stdout] test run::tests::dropping_runtime_backends_runs_shutdown_guard_drop ... ok
[INFO] [stdout] test handler::tests::submit_validates_and_enqueues ... ok
[INFO] [stdout] test handler::tests::status_returns_row_for_known_id ... ok
[INFO] [stdout] test handler::tests::status_returns_unknown_program_for_missing_id ... ok
[INFO] [stdout] test startup::tests::reload_waiting_does_nothing_when_none_exist ... ok
[INFO] [stdout] test persist::tests::program_event_is_appended_before_broadcast ... ok
[INFO] [stdout] test logging::tests::daily_appender_writes_to_log_dir ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 36 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.45s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/vcli-daemon.rs (/opt/rustwide/target/debug/deps/vcli_daemon-e06e7e5075d3c891)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/binary_smoke.rs (/opt/rustwide/target/debug/deps/binary_smoke-98c2c3a35f992710)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test vcli_daemon_help_or_starts_clean_on_linux ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.55s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/graceful_shutdown.rs (/opt/rustwide/target/debug/deps/graceful_shutdown-34994df326b535aa)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test shutdown_unlinks_socket_file ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.19s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/real_backends_macos.rs (/opt/rustwide/target/debug/deps/real_backends_macos-79fb48c76dce977f)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/startup_orphan_recovery.rs (/opt/rustwide/target/debug/deps/startup_orphan_recovery-39aa2617149c92d8)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test running_programs_transition_to_failed_on_startup ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.17s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/submit_and_run.rs (/opt/rustwide/target/debug/deps/submit_and_run-12a5c9801cfd55e8)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/vcli_dsl-79ad8ee024a29e07)
[INFO] [stdout] test submit_creates_program_row_and_returns_id ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.25s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 75 tests
[INFO] [stdout] test error::tests::payload_serializes_to_wire_shape ... ok
[INFO] [stdout] test error::tests::hint_surfaces_for_name_errors ... ok
[INFO] [stdout] test error::tests::to_payload_sets_invalid_program_code_and_path ... ok
[INFO] [stdout] test expression::tests::parses_center ... ok
[INFO] [stdout] test expression::tests::path_in_error_is_preserved ... ok
[INFO] [stdout] test expression::tests::rejects_missing_dollar ... ok
[INFO] [stdout] test expression::tests::rejects_missing_match_keyword ... ok
[INFO] [stdout] test expression::tests::rejects_non_identifier_name ... ok
[INFO] [stdout] test expression::tests::rejects_trailing_segments ... ok
[INFO] [stdout] test expression::tests::rejects_unknown_accessor ... ok
[INFO] [stdout] test hash::tests::empty_in_empty_out ... ok
[INFO] [stdout] test hash::tests::structurally_equal_predicates_share_hash ... ok
[INFO] [stdout] test hash::tests::hash_stable_under_key_order_within_predicate ... ok
[INFO] [stdout] test hint::tests::exact_match_returns_none ... ok
[INFO] [stdout] test hint::tests::first_match_wins ... ok
[INFO] [stdout] test hint::tests::one_insertion_detected ... ok
[INFO] [stdout] test hint::tests::two_edits_rejected ... ok
[INFO] [stdout] test hash::tests::one_hash_per_name ... ok
[INFO] [stdout] test hint::tests::one_deletion_detected ... ok
[INFO] [stdout] test hint::tests::one_substitution_detected ... ok
[INFO] [stdout] test expression::tests::parses_all_accessors ... ok
[INFO] [stdout] test error::tests::canonical_error_conversion_preserves_kind ... ok
[INFO] [stdout] test expression::tests::rejects_empty_name ... ok
[INFO] [stdout] test error::tests::hint_absent_for_non_name_errors ... ok
[INFO] [stdout] test lib_tests::validate_str_rejects_cycle ... ok
[INFO] [stdout] test lib_tests::validate_str_happy_path_returns_program_and_hashes ... ok
[INFO] [stdout] test parse::tests::bad_json_reports_parse_error ... ok
[INFO] [stdout] test parse::tests::minimal_valid_envelope_parses ... ok
[INFO] [stdout] test lib_tests::validate_str_rejects_unknown_trigger_name ... ok
[INFO] [stdout] test parse::tests::serde_shape_error_surfaces_as_json_parse ... ok
[INFO] [stdout] test parse::tests::wrong_version_type_reports_wrong_type ... ok
[INFO] [stdout] test path::tests::cloning_keeps_segments_independent ... ok
[INFO] [stdout] test lib_tests::validate_value_and_validate_str_agree ... ok
[INFO] [stdout] test parse::tests::missing_version_reports_missing_field ... ok
[INFO] [stdout] test parse::tests::non_object_root_rejected ... ok
[INFO] [stdout] test path::tests::key_then_index_then_key_roundtrip ... ok
[INFO] [stdout] test path::tests::root_is_empty_string ... ok
[INFO] [stdout] test predicates::tests::acyclic_dag_passes_graph_check ... ok
[INFO] [stdout] test predicates::tests::cycle_through_elapsed_ms_since_true_detected ... ok
[INFO] [stdout] test path::tests::special_chars_in_keys_are_escaped ... ok
[INFO] [stdout] test predicates::tests::cycle_through_relative_to_region_detected ... ok
[INFO] [stdout] test predicates::tests::all_of_with_unknown_reports_path ... ok
[INFO] [stdout] test predicates::tests::all_of_with_known_names_ok ... ok
[INFO] [stdout] test parse::tests::unsupported_major_rejected ... ok
[INFO] [stdout] test predicates::tests::not_with_unknown_reports_at_of ... ok
[INFO] [stdout] test predicates::tests::self_cycle_via_not_is_rejected ... ok
[INFO] [stdout] test predicates::tests::any_of_empty_rejected ... ok
[INFO] [stdout] test predicates::tests::elapsed_with_unknown_reports_distinct_error ... ok
[INFO] [stdout] test predicates::tests::empty_map_is_valid ... ok
[INFO] [stdout] test predicates::tests::template_with_relative_region_resolved ... ok
[INFO] [stdout] test steps::tests::assert_in_watch_rejected ... ok
[INFO] [stdout] test predicates::tests::template_with_unknown_relative_ref_reports ... ok
[INFO] [stdout] test steps::tests::click_with_unknown_predicate_reports_path ... ok
[INFO] [stdout] test steps::tests::negative_absolute_target_rejected ... ok
[INFO] [stdout] test steps::tests::sleep_ms_in_watch_rejected ... ok
[INFO] [stdout] test predicates::tests::did_you_mean_hint_fires_on_single_edit ... ok
[INFO] [stdout] test steps::tests::expression_on_logical_predicate_rejected ... ok
[INFO] [stdout] test steps::tests::type_and_key_never_reference_predicates ... ok
[INFO] [stdout] test steps::tests::valid_click_expression_target_ok ... ok
[INFO] [stdout] test triggers::tests::on_predicate_hint_fires_on_typo ... ok
[INFO] [stdout] test steps::tests::wait_for_in_body_ok ... ok
[INFO] [stdout] test steps::tests::wait_for_in_watch_rejected ... ok
[INFO] [stdout] test triggers::tests::on_predicate_with_unknown_reports_path ... ok
[INFO] [stdout] test watches::tests::inline_predicate_with_bad_ref_reports_rewritten_path ... ok
[INFO] [stdout] test watches::tests::inline_template_with_relative_to_named_predicate_ok ... ok
[INFO] [stdout] test steps::tests::assert_with_unknown_predicate_rejected ... ok
[INFO] [stdout] test watches::tests::lifetime_timeout_ms_ignores_predicates ... ok
[INFO] [stdout] test triggers::tests::manual_always_ok ... ok
[INFO] [stdout] test watches::tests::lifetime_until_predicate_unknown_rejected ... ok
[INFO] [stdout] test triggers::tests::on_submit_always_ok ... ok
[INFO] [stdout] test watches::tests::unknown_when_name_reports_path_and_hint ... ok
[INFO] [stdout] test watches::tests::valid_by_name_watch_ok ... ok
[INFO] [stdout] test predicates::tests::two_node_cycle_all_of_not ... ok
[INFO] [stdout] test triggers::tests::on_predicate_with_known_name_ok ... ok
[INFO] [stdout] test watches::tests::body_only_step_in_watch_reports_under_do ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 75 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/yt_ad_skipper.rs (/opt/rustwide/target/debug/deps/yt_ad_skipper-336bb2e527c8359c)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test yt_ad_skipper_validates_successfully ... ok
[INFO] [stdout] test mutating_confidence_changes_hash ... ok
[INFO] [stdout] test yt_ad_skipper_hash_is_deterministic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/vcli_input-a8f766d69431cef2)
[INFO] [stdout] 
[INFO] [stdout] running 26 tests
[INFO] [stdout] test error::tests::halted_display_is_stable ... ok
[INFO] [stdout] test error::tests::halted_maps_to_internal ... ok
[INFO] [stdout] test error::tests::unknown_key_maps_to_invalid_program ... ok
[INFO] [stdout] test keymap::tests::empty_and_garbage_reject ... ok
[INFO] [stdout] test keymap::tests::function_keys_parse_in_range ... ok
[INFO] [stdout] test keymap::tests::named_keys_parse ... ok
[INFO] [stdout] test keymap::tests::single_chars_parse ... ok
[INFO] [stdout] test kill_switch::tests::disengage_resets_flag ... ok
[INFO] [stdout] test kill_switch::tests::fresh_switch_is_not_engaged ... ok
[INFO] [stdout] test mock::tests::drain_empties_the_log ... ok
[INFO] [stdout] test mock::tests::forced_error_produces_backend_failure ... ok
[INFO] [stdout] test kill_switch::tests::engage_is_idempotent ... ok
[INFO] [stdout] test mock::tests::mock_records_double_click_and_drag ... ok
[INFO] [stdout] test mock::tests::mock_records_type_and_key ... ok
[INFO] [stdout] test mock::tests::mock_rejects_empty_drag ... ok
[INFO] [stdout] test permissions::tests::fully_granted_requires_both ... ok
[INFO] [stdout] test permissions::tests::non_macos_reports_not_applicable ... ok
[INFO] [stdout] test error::tests::permission_maps_to_permission_denied ... ok
[INFO] [stdout] test kill_switch::tests::engage_is_observable_from_clone ... ok
[INFO] [stdout] test mock::tests::mock_records_click_with_modifiers ... ok
[INFO] [stdout] test mock::tests::mock_records_move ... ok
[INFO] [stdout] test permissions::tests::report_roundtrip ... ok
[INFO] [stdout] test sink::tests::drag_segment_is_copy ... ok
[INFO] [stdout] test sink::tests::trait_is_object_safe ... ok
[INFO] [stdout] test kill_switch::tests::observer_returns_true_on_engage_before_timeout ... ok
[INFO] [stderr]      Running tests/kill_switch.rs (/opt/rustwide/target/debug/deps/kill_switch-6ec4c14e170ddb45)
[INFO] [stdout] test kill_switch::tests::observer_returns_false_on_timeout ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 26 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test disengage_reenables_calls ... ok
[INFO] [stdout] test engage_halts_every_subsequent_method ... ok
[INFO] [stderr]      Running tests/macos_real.rs (/opt/rustwide/target/debug/deps/macos_real-56e5b27bfac7f7fa)
[INFO] [stdout] test observer_wakes_immediately_when_switch_engaged_concurrently ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[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] [stderr]      Running tests/mock_contract.rs (/opt/rustwide/target/debug/deps/mock_contract-d107997b820ab2f4)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test forced_error_bubbles_as_backend_failure ... ok
[INFO] [stdout] test click_preserves_modifiers_and_hold ... ok
[INFO] [stdout] test drag_with_multiple_segments_records_all_endpoints ... ok
[INFO] [stdout] test double_click_emits_distinct_variant ... ok
[INFO] [stdout] test empty_drag_is_rejected ... ok
[INFO] [stdout] test key_combo_records_modifiers ... ok
[INFO] [stdout] test type_text_records_input_action ... ok
[INFO] [stdout] test kill_switch_engaged_halts_every_method ... ok
[INFO] [stdout] test mouse_move_records_once ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/vcli_ipc-2a69952c17b97645)
[INFO] [stdout] 
[INFO] [stdout] running 32 tests
[INFO] [stdout] test error::tests::from_io_error ... ok
[INFO] [stdout] test error::tests::display_includes_context ... ok
[INFO] [stdout] test error::tests::unexpected_eof_reports_progress ... ok
[INFO] [stdout] test client::tests::connect_fails_without_server ... ok
[INFO] [stdout] test frame::tests::read_reports_mid_frame_eof ... ok
[INFO] [stdout] test frame::tests::roundtrip_simple_value ... ok
[INFO] [stdout] test frame::tests::read_reassembles_across_partial_writes ... ok
[INFO] [stdout] test frame::tests::read_rejects_invalid_json_body ... ok
[INFO] [stdout] test handler::tests::is_streaming_classifies_v0_ops ... ok
[INFO] [stdout] test frame::tests::read_reports_clean_eof_between_frames ... ok
[INFO] [stdout] test frame::tests::roundtrip_two_frames_back_to_back ... ok
[INFO] [stdout] test frame::tests::read_rejects_oversize_header ... ok
[INFO] [stdout] test socket_path::tests::override_env_wins_over_all_other_branches ... ok
[INFO] [stdout] test server::tests::bind_removes_stale_socket_file ... ok
[INFO] [stdout] test error::tests::from_json_error ... ok
[INFO] [stdout] test server::tests::drop_unlinks_socket ... ok
[INFO] [stdout] test wire::request::tests::resume_from_start_flag_roundtrips ... ok
[INFO] [stdout] test wire::request::tests::list_with_no_filter_omits_params_field ... ok
[INFO] [stdout] test socket_path::tests::tmp_fallback_produces_uid_scoped_name ... ok
[INFO] [stdout] test wire::request::tests::health_gc_shutdown_have_no_params ... ok
[INFO] [stdout] test wire::request::tests::submit_roundtrips_with_program_payload ... ok
[INFO] [stdout] test wire::request::tests::unknown_op_rejected ... ok
[INFO] [stdout] test wire::response::tests::ok_response_with_null_result_is_valid ... ok
[INFO] [stdout] test wire::response::tests::ok_response_serializes_with_ok_true ... ok
[INFO] [stdout] test server::tests::bind_fails_when_parent_missing ... ok
[INFO] [stdout] test wire::request::tests::cancel_requires_program_id ... ok
[INFO] [stdout] test wire::response::tests::err_response_serializes_with_ok_false_and_typed_error ... ok
[INFO] [stdout] test wire::response::tests::response_with_ok_flag_mismatch_rejected ... ok
[INFO] [stdout] test wire::stream::tests::end_of_stream_flag_is_emitted_and_parsed ... ok
[INFO] [stdout] test wire::stream::tests::event_frame_serializes_with_kind_events ... ok
[INFO] [stdout] test wire::stream::tests::trace_frame_carries_opaque_json ... ok
[INFO] [stdout] test frame::tests::write_rejects_oversize_payload ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 32 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.28s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/errors.rs (/opt/rustwide/target/debug/deps/errors-866e0cc2dcc71747)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test raw_peer_clean_disconnect_is_fine ... ok
[INFO] [stdout] test oversize_header_closes_connection_gracefully ... ok
[INFO] [stdout] test malformed_json_body_yields_error_response ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/roundtrip.rs (/opt/rustwide/target/debug/deps/roundtrip-44fc3377ca678f3a)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test submit_returns_ok_with_program_id ... ok
[INFO] [stdout] test multiple_requests_on_one_connection ... ok
[INFO] [stdout] test list_health_and_cancel_roundtrip ... ok
[INFO] [stderr]      Running tests/streaming.rs (/opt/rustwide/target/debug/deps/streaming-d5451215571df706)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test events_stream_receives_frames_then_end_of_stream ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/vcli_perception-7e4902b49fd7b7c5)
[INFO] [stdout] test client_drop_midstream_does_not_crash_server ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 52 tests
[INFO] [stdout] test cache::tests::clear_wipes_all_entries ... ok
[INFO] [stdout] test cache::tests::get_miss_returns_none ... ok
[INFO] [stdout] test cache::tests::insert_then_get_returns_result ... ok
[INFO] [stdout] test color_at::tests::exact_color_match_is_truthy ... ok
[INFO] [stdout] test color_at::tests::off_by_one_within_tolerance_is_truthy ... ok
[INFO] [stdout] test elapsed::tests::after_threshold_elapses_returns_truthy ... ok
[INFO] [stdout] test color_at::tests::out_of_bounds_errors ... ok
[INFO] [stdout] test color_at::tests::beyond_tolerance_is_falsy ... ok
[INFO] [stdout] test cache::tests::insert_overwrites_existing_entry ... ok
[INFO] [stdout] test elapsed::tests::first_tick_truthy_child_not_elapsed_yet ... ok
[INFO] [stdout] test error::tests::display_asset_decode ... ok
[INFO] [stdout] test error::tests::display_cycle ... ok
[INFO] [stdout] test evaluator::tests::trait_is_object_safe_and_send_sync ... ok
[INFO] [stdout] test error::tests::display_unknown_predicate ... ok
[INFO] [stdout] test frame_view::tests::crop_rgb_clips_to_frame_bounds ... ok
[INFO] [stdout] test frame_view::tests::crop_rgb_returns_correct_dimensions ... ok
[INFO] [stdout] test frame_view::tests::crop_rgb_zero_overlap_errors ... ok
[INFO] [stdout] test frame_view::tests::pixel_rgb_bgra_swizzles_to_rgb ... ok
[INFO] [stdout] test frame_view::tests::pixel_rgb_out_of_bounds_errors ... ok
[INFO] [stdout] test frame_view::tests::frame_to_rgb_has_correct_dimensions ... ok
[INFO] [stdout] test logical::tests::all_of_false_if_any_child_false ... ok
[INFO] [stdout] test logical::tests::all_of_true_when_all_children_true ... ok
[INFO] [stdout] test logical::tests::unknown_named_predicate_errors ... ok
[INFO] [stdout] test logical::tests::logical_shares_cache_across_siblings ... ok
[INFO] [stdout] test logical::tests::not_red_when_actually_red_is_false ... ok
[INFO] [stdout] test logical::tests::any_of_true_if_any_child_true ... ok
[INFO] [stdout] test logical::tests::any_of_empty_list_is_false ... ok
[INFO] [stdout] test perception::tests::clear_wipes_cache_but_not_state ... ok
[INFO] [stdout] test perception::tests::second_call_for_same_name_is_a_cache_hit ... ok
[INFO] [stdout] test perception::tests::evaluate_named_returns_truthy_for_matching_color ... ok
[INFO] [stdout] test perception::tests::evaluate_named_unknown_errors ... ok
[INFO] [stdout] test perception::tests::two_programs_with_same_predicate_share_cache ... ok
[INFO] [stdout] test pixel_diff::tests::same_scene_on_second_tick_is_not_truthy ... ok
[INFO] [stdout] test pixel_diff::tests::changed_scene_on_second_tick_is_truthy ... ok
[INFO] [stdout] test state::tests::first_true_cleared_on_falling_edge ... ok
[INFO] [stdout] test state::tests::different_programs_do_not_share_first_true_timestamps ... ok
[INFO] [stdout] test state::tests::first_true_set_preserves_earliest ... ok
[INFO] [stdout] test pixel_diff::tests::first_tick_not_truthy_but_records_snapshot ... ok
[INFO] [stdout] test pixel_diff::tests::dhash_and_hamming_on_identical_images_is_zero ... ok
[INFO] [stdout] test pixel_diff::tests::dhash_differs_for_contrasting_images ... ok
[INFO] [stdout] test state::tests::first_true_set_then_read ... ok
[INFO] [stdout] test state::tests::prior_snapshot_round_trip ... ok
[INFO] [stdout] test state::tests::record_snapshot_overwrites ... ok
[INFO] [stdout] test elapsed::tests::falling_edge_clears_timer ... ok
[INFO] [stdout] test elapsed::tests::no_program_id_errors ... ok
[INFO] [stdout] test template::tests::decode_gray_errors_on_garbage ... ok
[INFO] [stdout] test cache::tests::concurrent_inserts_from_many_threads ... ok
[INFO] [stdout] test template::tests::template_larger_than_haystack_is_falsy ... ok
[INFO] [stdout] test template::tests::decode_gray_parses_valid_png ... ok
[INFO] [stdout] test template::tests::missing_asset_errors ... ok
[INFO] [stdout] test template::tests::template_match_found_in_scene_reports_correct_bbox ... ok
[INFO] [stdout] test template::tests::template_missing_in_scene_is_falsy ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cache_and_state.rs (/opt/rustwide/target/debug/deps/cache_and_state-1b608b8b6e4f01f3)
[INFO] [stdout] test result: ok. 52 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.21s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test elapsed_ms_since_true_persists_across_tick_clears ... ok
[INFO] [stdout] test cache_dedups_across_distinct_program_predicate_names ... ok
[INFO] [stdout] test per_tick_clear_invalidates_results ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/vcli_runtime-3a758f258bfbfe90)
[INFO] [stdout] 
[INFO] [stdout] running 37 tests
[INFO] [stdout] test arbiter::tests::higher_priority_wins ... ok
[INFO] [stdout] test body::tests::absolute_click_advances ... ok
[INFO] [stdout] test arbiter::tests::three_way_drops_two_losers ... ok
[INFO] [stdout] test clock::tests::manual_clock_advance_adds_time ... ok
[INFO] [stdout] test clock::tests::system_runtime_clock_is_monotonic_within_a_tick ... ok
[INFO] [stdout] test clock::tests::manual_clock_sleep_advances_instead_of_blocks ... ok
[INFO] [stdout] test command::tests::cancel_keeps_reason_untouched ... ok
[INFO] [stdout] test command::tests::submit_variant_roundtrips_basic_shape ... ok
[INFO] [stdout] test error::tests::code_strings_are_spec_stable ... ok
[INFO] [stdout] test error::tests::runtime_error_maps_to_code ... ok
[INFO] [stdout] test event::tests::emit_returns_false_when_receiver_dropped ... ok
[INFO] [stdout] test event::tests::emit_stamps_with_clock_now ... ok
[INFO] [stdout] test expr::tests::parse_match_bbox ... ok
[INFO] [stdout] test expr::tests::parse_rejects_missing_dollar ... ok
[INFO] [stdout] test expr::tests::parse_rejects_unknown_accessor ... ok
[INFO] [stdout] test expr::tests::resolve_center_averages_bbox ... ok
[INFO] [stdout] test expr::tests::resolve_without_match_data_errors ... ok
[INFO] [stdout] test program::tests::body_complete_detects_exhausted_cursor ... ok
[INFO] [stdout] test program::tests::pending_is_initial_state ... ok
[INFO] [stdout] test expr::tests::parse_match_center ... ok
[INFO] [stdout] test triggers::tests::manual_never_auto_fires ... ok
[INFO] [stdout] test triggers::tests::on_predicate_defers_to_perception ... ok
[INFO] [stdout] test triggers::tests::on_predicate_unknown_name_errors ... ok
[INFO] [stdout] test triggers::tests::on_submit_always_fires ... ok
[INFO] [stdout] test scheduler::tests::shutdown_exits_cleanly ... ok
[INFO] [stdout] test watches::tests::fires_on_false_to_true_edge ... ok
[INFO] [stdout] test watches::tests::persistent_does_not_retire_after_fire ... ok
[INFO] [stdout] test watches::tests::one_shot_retires_after_fire ... ok
[INFO] [stdout] test watches::tests::retired_watch_is_inert ... ok
[INFO] [stdout] test watches::tests::skips_on_level_high ... ok
[INFO] [stdout] test arbiter::tests::tie_breaks_by_program_id_lex_desc ... ok
[INFO] [stdout] test body::tests::assert_fail_propagates_error ... ok
[INFO] [stdout] test watches::tests::throttle_suppresses_rapid_refires ... ok
[INFO] [stdout] test watches::tests::timeout_ms_retires_when_window_elapsed ... ok
[INFO] [stdout] test body::tests::cursor_past_end_is_body_complete ... ok
[INFO] [stdout] test body::tests::sleep_ms_stalls_then_advances ... ok
[INFO] [stdout] test arbiter::tests::single_candidate_always_dispatches ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 37 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/scenarios/action_conflict.rs (/opt/rustwide/target/debug/deps/action_conflict-28ac5aca0add6cab)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/scenarios/assert_failure.rs (/opt/rustwide/target/debug/deps/assert_failure-0998eca20dfd93fa)
[INFO] [stdout] test two_programs_same_frame_arbitrate_to_one_winner ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.37s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/scenarios/daemon_restart_marker.rs (/opt/rustwide/target/debug/deps/daemon_restart_marker-251ebc12cc194848)
[INFO] [stdout] test assert_fail_on_fail_fails_program ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.32s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test resume_running_starts_body_at_cursor_and_emits_resumed ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.33s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/scenarios/elapsed_ms_since_true.rs (/opt/rustwide/target/debug/deps/elapsed_ms_since_true-574d7d982a4c543e)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/scenarios/input_postcondition.rs (/opt/rustwide/target/debug/deps/input_postcondition-36422f1d5d5dcc34)
[INFO] [stdout] test elapsed_ms_since_true_fires_after_delay ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.51s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test click_then_wait_for_new_state_succeeds ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.41s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/scenarios/novelty_timeout.rs (/opt/rustwide/target/debug/deps/novelty_timeout-7b125a855f8f7c4a)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test wait_for_postcondition_fails_when_never_truthy ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.51s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/scenarios/one_shot_watch.rs (/opt/rustwide/target/debug/deps/one_shot_watch-ced2553527e8cd00)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/scenarios/persistent_watch.rs (/opt/rustwide/target/debug/deps/persistent_watch-89aa9082009a0b6e)
[INFO] [stdout] test one_shot_watch_fires_once_and_completes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.30s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test persistent_watch_fires_on_each_edge ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.50s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/scenarios/predicate_dedup.rs (/opt/rustwide/target/debug/deps/predicate_dedup-8a11eda7baccd427)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/scenarios/until_predicate.rs (/opt/rustwide/target/debug/deps/until_predicate-24607e702749ccd1)
[INFO] [stdout] test two_programs_same_predicate_both_fire ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.31s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test until_predicate_retires_watch ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.41s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/scenarios/wait_for_timeout.rs (/opt/rustwide/target/debug/deps/wait_for_timeout-8f71e1f4c9dbbf08)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test wait_for_fails_on_timeout_when_on_timeout_fail ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.51s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/scenarios/watch_timeout.rs (/opt/rustwide/target/debug/deps/watch_timeout-3199fd96889a46fc)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/scenarios/while_true_retry.rs (/opt/rustwide/target/debug/deps/while_true_retry-97b37539f5b502f5)
[INFO] [stdout] test watch_timeout_retires_after_budget ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.51s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test throttled_persistent_fires_at_most_once_per_window ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.51s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/vcli_store-51a9fd0a79e1da55)
[INFO] [stdout] 
[INFO] [stdout] running 46 tests
[INFO] [stdout] test error::tests::schema_newer_display ... ok
[INFO] [stdout] test error::tests::bad_state_transition_carries_fields ... ok
[INFO] [stdout] test error::tests::unknown_program_display ... ok
[INFO] [stdout] test assets::tests::asset_hash_of_bytes_is_deterministic ... ok
[INFO] [stdout] test assets::tests::get_asset_missing_returns_none ... ok
[INFO] [stdout] test assets::tests::put_then_get_roundtrips_bytes ... ok
[INFO] [stdout] test assets::tests::link_program_asset_and_list ... ok
[INFO] [stdout] test events::tests::append_event_returns_monotonic_ids ... ok
[INFO] [stdout] test assets::tests::blob_lands_under_expected_sharded_path ... ok
[INFO] [stdout] test events::tests::cascade_delete_removes_events ... ok
[INFO] [stdout] test assets::tests::put_asset_dedupes_same_bytes ... ok
[INFO] [stdout] test migrations::tests::all_v0_tables_exist_after_migration ... ok
[INFO] [stdout] test events::tests::stream_events_respects_limit ... ok
[INFO] [stdout] test migrations::tests::fresh_db_starts_at_version_zero ... ok
[INFO] [stdout] test migrations::tests::run_migrations_goes_to_latest ... ok
[INFO] [stdout] test gc::tests::gc_orphan_blobs_removes_files_without_rows ... ok
[INFO] [stdout] test migrations::tests::run_migrations_is_idempotent ... ok
[INFO] [stdout] test gc::tests::gc_all_reports_all_counts ... ok
[INFO] [stdout] test paths::tests::blob_path_uses_first_four_chars_as_two_shards ... ok
[INFO] [stdout] test migrations::tests::schema_newer_error_on_db_ahead_of_binary ... ok
[INFO] [stdout] test paths::tests::assets_root_is_sharded_by_sha256 ... ok
[INFO] [stdout] test paths::tests::blob_path_without_extension ... ok
[INFO] [stdout] test paths::tests::db_path_is_data_root_joined_vcli_db ... ok
[INFO] [stdout] test gc::tests::gc_programs_keeps_recent_and_prunes_old ... ok
[INFO] [stdout] test events::tests::stream_events_returns_since_cursor ... ok
[INFO] [stdout] test pragmas::tests::foreign_keys_on ... ok
[INFO] [stdout] test pragmas::tests::wal_mode_enabled_on_file_backed_db ... ok
[INFO] [stdout] test gc::tests::gc_programs_does_not_touch_active_rows ... ok
[INFO] [stdout] test gc::tests::gc_assets_removes_unreferenced_only ... ok
[INFO] [stdout] test resume::tests::resume_rejects_non_daemon_restart_failure ... ok
[INFO] [stdout] test pragmas::tests::busy_timeout_is_5000ms ... ok
[INFO] [stdout] test resume::tests::resume_from_start_resets_cursor ... ok
[INFO] [stdout] test resume::tests::resume_unknown_program_errors ... ok
[INFO] [stdout] test store::tests::get_program_unknown_id_errors ... ok
[INFO] [stdout] test store::tests::insert_and_get_program_roundtrip ... ok
[INFO] [stdout] test resume::tests::resume_preserves_cursor_by_default ... ok
[INFO] [stdout] test store::tests::list_programs_returns_all_when_no_filter ... ok
[INFO] [stdout] test store::tests::list_programs_filters_by_state ... ok
[INFO] [stdout] test store::tests::open_is_idempotent ... ok
[INFO] [stdout] test store::tests::set_body_cursor_persists ... ok
[INFO] [stdout] test store::tests::open_creates_data_root_and_assets_dir ... ok
[INFO] [stdout] test store::tests::update_state_writes_started_and_finished_at ... ok
[INFO] [stdout] test store::tests::reopen_preserves_rows ... ok
[INFO] [stdout] test traces::tests::empty_flush_is_noop ... ok
[INFO] [stdout] test traces::tests::flush_and_read_roundtrip ... ok
[INFO] [stdout] test paths::tests::blob_path_panics_on_short_hash - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 46 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.16s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/assets_dedup.rs (/opt/rustwide/target/debug/deps/assets_dedup-cd66128d650c75a6)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test two_programs_sharing_an_asset_keep_one_blob ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/gc.rs (/opt/rustwide/target/debug/deps/gc-38d2b2bb2ad25062)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/restart_recovery.rs (/opt/rustwide/target/debug/deps/restart_recovery-ca29650a1781a1ec)
[INFO] [stdout] test seven_day_retention_boundary ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test second_reopen_after_recovery_is_a_no_op ... ok
[INFO] [stderr]      Running tests/wal_concurrent.rs (/opt/rustwide/target/debug/deps/wal_concurrent-bb343de268c95ea8)
[INFO] [stdout] test running_programs_transition_to_failed_on_reopen ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test two_readers_coexist_with_one_writer ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests vcli_capture
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests vcli_cli
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests vcli_core
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests vcli_daemon
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests vcli_dsl
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests vcli_input
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests vcli_ipc
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests vcli_perception
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests vcli_runtime
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests vcli_store
[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" "63df2df078c66259cc124eaa54167810f65be4b51759b059a1c66d3b54e33bca", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "63df2df078c66259cc124eaa54167810f65be4b51759b059a1c66d3b54e33bca", kill_on_drop: false }`
[INFO] [stdout] 63df2df078c66259cc124eaa54167810f65be4b51759b059a1c66d3b54e33bca