[INFO] fetching crate rsnip 0.6.1... [INFO] testing rsnip-0.6.1 against 1.91.0 for beta-1.92-2 [INFO] extracting crate rsnip 0.6.1 into /workspace/builds/worker-5-tc1/source [INFO] started tweaking crates.io crate rsnip 0.6.1 [INFO] removed 0 missing tests [INFO] finished tweaking crates.io crate rsnip 0.6.1 [INFO] tweaked toml for crates.io crate rsnip 0.6.1 written to /workspace/builds/worker-5-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate rsnip 0.6.1 on toolchain 1.91.0 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.91.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate crates.io crate rsnip 0.6.1 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" "+1.91.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded tempfile v3.15.0 [INFO] [stderr] Downloaded env_home v0.1.0 [INFO] [stderr] Downloaded which v7.0.1 [INFO] [stderr] Downloaded trim-in-place v0.1.7 [INFO] [stderr] Downloaded shell-quote v0.7.2 [INFO] [stderr] Downloaded ordered-multimap v0.7.3 [INFO] [stderr] Downloaded objc2-encode v4.0.3 [INFO] [stderr] Downloaded rust-ini v0.21.1 [INFO] [stderr] Downloaded mockall v0.13.1 [INFO] [stderr] Downloaded convert_case v0.6.0 [INFO] [stderr] Downloaded clap_derive v4.5.24 [INFO] [stderr] Downloaded bytemuck v1.21.0 [INFO] [stderr] Downloaded clap_complete v4.5.40 [INFO] [stderr] Downloaded ron v0.8.1 [INFO] [stderr] Downloaded flate2 v1.0.35 [INFO] [stderr] Downloaded toml_edit v0.22.23 [INFO] [stderr] Downloaded yaml-rust2 v0.9.0 [INFO] [stderr] Downloaded winnow v0.6.22 [INFO] [stderr] Downloaded minijinja v2.6.0 [INFO] [stderr] Downloaded serde_json v1.0.135 [INFO] [stderr] Downloaded winnow v0.7.1 [INFO] [stderr] Downloaded image v0.25.5 [INFO] [stderr] Downloaded config v0.15.4 [INFO] [stderr] Downloaded clap v4.5.27 [INFO] [stderr] Downloaded env_logger v0.11.6 [INFO] [stderr] Downloaded bstr v1.11.3 [INFO] [stderr] Downloaded skim v0.16.0 [INFO] [stderr] Downloaded float-cmp v0.10.0 [INFO] [stderr] Downloaded tuikit v0.5.0 [INFO] [stderr] Downloaded rustix v0.38.42 [INFO] [stderr] Downloaded dlv-list v0.5.2 [INFO] [stderr] Downloaded arraydeque v0.5.1 [INFO] [stderr] Downloaded mockall_derive v0.13.1 [INFO] [stderr] Downloaded defer-drop v1.3.0 [INFO] [stderr] Downloaded beef v0.5.2 [INFO] [stderr] Downloaded winsafe v0.0.19 [INFO] [stderr] Downloaded timer v0.2.0 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+1.91.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 9d122bd55d4e74e0b3cf1b805fd8e60145bbd4c8e3188043182878a19e12721f [INFO] running `Command { std: "docker" "start" "-a" "9d122bd55d4e74e0b3cf1b805fd8e60145bbd4c8e3188043182878a19e12721f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "9d122bd55d4e74e0b3cf1b805fd8e60145bbd4c8e3188043182878a19e12721f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "9d122bd55d4e74e0b3cf1b805fd8e60145bbd4c8e3188043182878a19e12721f", kill_on_drop: false }` [INFO] [stdout] 9d122bd55d4e74e0b3cf1b805fd8e60145bbd4c8e3188043182878a19e12721f [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+1.91.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 6d02fdcf19b785d2774c5e5214d596caa48ebed67cb119ed98af6b149a1865d6 [INFO] running `Command { std: "docker" "start" "-a" "6d02fdcf19b785d2774c5e5214d596caa48ebed67cb119ed98af6b149a1865d6", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.92 [INFO] [stderr] Compiling unicode-ident v1.0.14 [INFO] [stderr] Compiling libc v0.2.169 [INFO] [stderr] Compiling serde v1.0.217 [INFO] [stderr] Compiling num-traits v0.2.19 [INFO] [stderr] Compiling regex-automata v0.4.9 [INFO] [stderr] Compiling log v0.4.25 [INFO] [stderr] Compiling crunchy v0.2.2 [INFO] [stderr] Compiling anstyle v1.0.10 [INFO] [stderr] Compiling rustix v0.38.42 [INFO] [stderr] Compiling ahash v0.8.11 [INFO] [stderr] Compiling thiserror v2.0.9 [INFO] [stderr] Compiling linux-raw-sys v0.4.14 [INFO] [stderr] Compiling tiny-keccak v2.0.2 [INFO] [stderr] Compiling anstyle-parse v0.2.6 [INFO] [stderr] Compiling quote v1.0.38 [INFO] [stderr] Compiling anstyle-query v1.1.2 [INFO] [stderr] Compiling colorchoice v1.0.3 [INFO] [stderr] Compiling ucd-trie v0.1.7 [INFO] [stderr] Compiling syn v2.0.95 [INFO] [stderr] Compiling simd-adler32 v0.3.7 [INFO] [stderr] Compiling anstream v0.6.18 [INFO] [stderr] Compiling indexmap v2.7.1 [INFO] [stderr] Compiling getrandom v0.2.15 [INFO] [stderr] Compiling miniz_oxide v0.8.2 [INFO] [stderr] Compiling crc32fast v1.4.2 [INFO] [stderr] Compiling const-random-macro v0.1.16 [INFO] [stderr] Compiling clap_lex v0.7.4 [INFO] [stderr] Compiling const-random v0.1.18 [INFO] [stderr] Compiling predicates-core v1.0.9 [INFO] [stderr] Compiling dirs-sys-next v0.1.2 [INFO] [stderr] Compiling either v1.13.0 [INFO] [stderr] Compiling rayon-core v1.12.1 [INFO] [stderr] Compiling flate2 v1.0.35 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling parking_lot_core v0.9.10 [INFO] [stderr] Compiling dirs-next v2.0.0 [INFO] [stderr] Compiling signal-hook-registry v1.4.2 [INFO] [stderr] Compiling nix v0.29.0 [INFO] [stderr] Compiling dlv-list v0.5.2 [INFO] [stderr] Compiling clap_builder v4.5.27 [INFO] [stderr] Compiling float-cmp v0.10.0 [INFO] [stderr] Compiling fdeflate v0.3.7 [INFO] [stderr] Compiling crossbeam-channel v0.5.14 [INFO] [stderr] Compiling thread_local v1.1.8 [INFO] [stderr] Compiling tracing-core v0.1.33 [INFO] [stderr] Compiling mockall_derive v0.13.1 [INFO] [stderr] Compiling powerfmt v0.2.0 [INFO] [stderr] Compiling termtree v0.5.1 [INFO] [stderr] Compiling winnow v0.7.1 [INFO] [stderr] Compiling unicode-width v0.2.0 [INFO] [stderr] Compiling serde_json v1.0.135 [INFO] [stderr] Compiling iana-time-zone v0.1.61 [INFO] [stderr] Compiling deranged v0.3.11 [INFO] [stderr] Compiling regex v1.11.1 [INFO] [stderr] Compiling bstr v1.11.3 [INFO] [stderr] Compiling predicates-tree v1.0.12 [INFO] [stderr] Compiling png v0.17.16 [INFO] [stderr] Compiling env_filter v0.1.3 [INFO] [stderr] Compiling predicates v3.1.3 [INFO] [stderr] Compiling signal-hook v0.3.17 [INFO] [stderr] Compiling parking_lot v0.12.3 [INFO] [stderr] Compiling darling_core v0.20.10 [INFO] [stderr] Compiling term v0.7.0 [INFO] [stderr] Compiling nix v0.24.3 [INFO] [stderr] Compiling mio v1.0.3 [INFO] [stderr] Compiling crossbeam-queue v0.3.12 [INFO] [stderr] Compiling encoding_rs v0.8.35 [INFO] [stderr] Compiling time-core v0.1.2 [INFO] [stderr] Compiling env_home v0.1.0 [INFO] [stderr] Compiling pin-project-lite v0.2.15 [INFO] [stderr] Compiling x11rb-protocol v0.13.1 [INFO] [stderr] Compiling trim-in-place v0.1.7 [INFO] [stderr] Compiling arrayvec v0.7.6 [INFO] [stderr] Compiling byteorder-lite v0.1.0 [INFO] [stderr] Compiling num-conv v0.1.0 [INFO] [stderr] Compiling unicode-segmentation v1.12.0 [INFO] [stderr] Compiling serde_derive v1.0.217 [INFO] [stderr] Compiling zerocopy-derive v0.7.35 [INFO] [stderr] Compiling thiserror-impl v2.0.9 [INFO] [stderr] Compiling clap_derive v4.5.24 [INFO] [stderr] Compiling zerocopy v0.7.35 [INFO] [stderr] Compiling darling_macro v0.20.10 [INFO] [stderr] Compiling tracing-attributes v0.1.28 [INFO] [stderr] Compiling darling v0.20.10 [INFO] [stderr] Compiling thiserror-impl v1.0.69 [INFO] [stderr] Compiling derive_builder_core v0.20.2 [INFO] [stderr] Compiling pest v2.7.15 [INFO] [stderr] Compiling hashbrown v0.14.5 [INFO] [stderr] Compiling ppv-lite86 v0.2.20 [INFO] [stderr] Compiling clap v4.5.27 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling anyhow v1.0.95 [INFO] [stderr] Compiling derive_builder_macro v0.20.2 [INFO] [stderr] Compiling itoa v1.0.14 [INFO] [stderr] Compiling ordered-multimap v0.7.3 [INFO] [stderr] Compiling hashlink v0.9.1 [INFO] [stderr] Compiling arraydeque v0.5.1 [INFO] [stderr] Compiling pest_meta v2.7.15 [INFO] [stderr] Compiling bytemuck v1.21.0 [INFO] [stderr] Compiling humantime v2.1.0 [INFO] [stderr] Compiling dirs-sys v0.4.1 [INFO] [stderr] Compiling env_logger v0.11.6 [INFO] [stderr] Compiling yaml-rust2 v0.9.0 [INFO] [stderr] Compiling image v0.25.5 [INFO] [stderr] Compiling rust-ini v0.21.1 [INFO] [stderr] Compiling derive_builder v0.20.2 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling thiserror v1.0.69 [INFO] [stderr] Compiling convert_case v0.6.0 [INFO] [stderr] Compiling async-trait v0.1.84 [INFO] [stderr] Compiling pest_generator v2.7.15 [INFO] [stderr] Compiling tuikit v0.5.0 [INFO] [stderr] Compiling time v0.3.37 [INFO] [stderr] Compiling vte v0.14.1 [INFO] [stderr] Compiling pest_derive v2.7.15 [INFO] [stderr] Compiling signal-hook-mio v0.2.4 [INFO] [stderr] Compiling crossbeam v0.8.4 [INFO] [stderr] Compiling rayon v1.10.0 [INFO] [stderr] Compiling shell-quote v0.7.2 [INFO] [stderr] Compiling defer-drop v1.3.0 [INFO] [stderr] Compiling tracing-log v0.2.0 [INFO] [stderr] Compiling console v0.15.10 [INFO] [stderr] Compiling fuzzy-matcher v0.3.7 [INFO] [stderr] Compiling wait-timeout v0.2.0 [INFO] [stderr] Compiling downcast v0.11.0 [INFO] [stderr] Compiling shlex v1.3.0 [INFO] [stderr] Compiling beef v0.5.2 [INFO] [stderr] Compiling shell-words v1.1.0 [INFO] [stderr] Compiling winnow v0.6.22 [INFO] [stderr] Compiling fragile v2.0.0 [INFO] [stderr] Compiling pathdiff v0.2.3 [INFO] [stderr] Compiling assert_cmd v2.0.16 [INFO] [stderr] Compiling tracing-subscriber v0.3.19 [INFO] [stderr] Compiling bitflags v2.6.0 [INFO] [stderr] Compiling serde_spanned v0.6.8 [INFO] [stderr] Compiling toml_datetime v0.6.8 [INFO] [stderr] Compiling chrono v0.4.39 [INFO] [stderr] Compiling toml_edit v0.22.23 [INFO] [stderr] Compiling ron v0.8.1 [INFO] [stderr] Compiling timer v0.2.0 [INFO] [stderr] Compiling json5 v0.4.1 [INFO] [stderr] Compiling mockall v0.13.1 [INFO] [stderr] Compiling minijinja v2.6.0 [INFO] [stderr] Compiling dirs v5.0.1 [INFO] [stderr] Compiling clap_complete v4.5.40 [INFO] [stderr] Compiling ctor v0.2.9 [INFO] [stderr] Compiling itertools v0.14.0 [INFO] [stderr] Compiling toml v0.8.20 [INFO] [stderr] Compiling config v0.15.4 [INFO] [stderr] Compiling tempfile v3.15.0 [INFO] [stderr] Compiling which v7.0.1 [INFO] [stderr] Compiling x11rb v0.13.1 [INFO] [stderr] Compiling crossterm v0.28.1 [INFO] [stderr] Compiling skim v0.16.0 [INFO] [stderr] Compiling dialoguer v0.11.0 [INFO] [stderr] Compiling arboard v3.4.1 [INFO] [stderr] Compiling rsnip v0.6.1 (/opt/rustwide/workdir) [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/infrastructure/fuzzy.rs:22:13 [INFO] [stdout] | [INFO] [stdout] 22 | fn text(&self) -> Cow { [INFO] [stdout] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 22 | fn text(&self) -> Cow<'_, str> { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/infrastructure/fuzzy.rs:30:15 [INFO] [stdout] | [INFO] [stdout] 30 | fn output(&self) -> Cow { [INFO] [stdout] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 30 | fn output(&self) -> Cow<'_, str> { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 2m 49s [INFO] running `Command { std: "docker" "inspect" "6d02fdcf19b785d2774c5e5214d596caa48ebed67cb119ed98af6b149a1865d6", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6d02fdcf19b785d2774c5e5214d596caa48ebed67cb119ed98af6b149a1865d6", kill_on_drop: false }` [INFO] [stdout] 6d02fdcf19b785d2774c5e5214d596caa48ebed67cb119ed98af6b149a1865d6 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+1.91.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 6d5c7c4a1baf4a23ec6cc65e40686774aff4c50e59a1f7baf18252b6bc638205 [INFO] running `Command { std: "docker" "start" "-a" "6d5c7c4a1baf4a23ec6cc65e40686774aff4c50e59a1f7baf18252b6bc638205", kill_on_drop: false }` [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/infrastructure/fuzzy.rs:22:13 [INFO] [stdout] | [INFO] [stdout] 22 | fn text(&self) -> Cow { [INFO] [stdout] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 22 | fn text(&self) -> Cow<'_, str> { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/infrastructure/fuzzy.rs:30:15 [INFO] [stdout] | [INFO] [stdout] 30 | fn output(&self) -> Cow { [INFO] [stdout] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 30 | fn output(&self) -> Cow<'_, str> { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Compiling rsnip v0.6.1 (/opt/rustwide/workdir) [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/infrastructure/fuzzy.rs:22:13 [INFO] [stdout] | [INFO] [stdout] 22 | fn text(&self) -> Cow { [INFO] [stdout] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 22 | fn text(&self) -> Cow<'_, str> { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/infrastructure/fuzzy.rs:30:15 [INFO] [stdout] | [INFO] [stdout] 30 | fn output(&self) -> Cow { [INFO] [stdout] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 30 | fn output(&self) -> Cow<'_, str> { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 7.66s [INFO] running `Command { std: "docker" "inspect" "6d5c7c4a1baf4a23ec6cc65e40686774aff4c50e59a1f7baf18252b6bc638205", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6d5c7c4a1baf4a23ec6cc65e40686774aff4c50e59a1f7baf18252b6bc638205", kill_on_drop: false }` [INFO] [stdout] 6d5c7c4a1baf4a23ec6cc65e40686774aff4c50e59a1f7baf18252b6bc638205 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+1.91.0" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 6c1e04f926a778ca6d477b6067f274b88553a868b048f70a513b8e94d30896c3 [INFO] running `Command { std: "docker" "start" "-a" "6c1e04f926a778ca6d477b6067f274b88553a868b048f70a513b8e94d30896c3", kill_on_drop: false }` [INFO] [stderr] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stderr] --> src/infrastructure/fuzzy.rs:22:13 [INFO] [stderr] | [INFO] [stderr] 22 | fn text(&self) -> Cow { [INFO] [stderr] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stderr] | | [INFO] [stderr] | the lifetime is elided here [INFO] [stderr] | [INFO] [stderr] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stderr] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stderr] help: use `'_` for type paths [INFO] [stderr] | [INFO] [stderr] 22 | fn text(&self) -> Cow<'_, str> { [INFO] [stderr] | +++ [INFO] [stderr] [INFO] [stderr] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stderr] --> src/infrastructure/fuzzy.rs:30:15 [INFO] [stderr] | [INFO] [stderr] 30 | fn output(&self) -> Cow { [INFO] [stderr] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stderr] | | [INFO] [stderr] | the lifetime is elided here [INFO] [stderr] | [INFO] [stderr] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stderr] help: use `'_` for type paths [INFO] [stderr] | [INFO] [stderr] 30 | fn output(&self) -> Cow<'_, str> { [INFO] [stderr] | +++ [INFO] [stderr] [INFO] [stderr] warning: `rsnip` (lib) generated 2 warnings (run `cargo fix --lib -p rsnip` to apply 2 suggestions) [INFO] [stderr] warning: `rsnip` (lib test) generated 2 warnings (2 duplicates) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.28s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rsnip-86315152cf2170fa) [INFO] [stderr] 2025-11-05T05:15:23.403630Z  INFO rsnip::util::testing: Test Setup complete [INFO] [stderr] 2025-11-05T05:15:23.403752Z DEBUG rsnip::util::testing: INIT: Attempting logger init from testing.rs [INFO] [stderr] 2025-11-05T05:15:23.403969Z DEBUG rsnip::util::testing: Tracing subscriber already set [INFO] [stdout] [INFO] [stderr] 2025-11-05T05:15:23.404032Z  INFO rsnip::util::testing: Test Setup complete [INFO] [stdout] running 14 tests [INFO] [stderr] 2025-11-05T05:15:23.407123Z DEBUG find_snippet_line_number{content="some content\n--- test\ncontent\n---" snippet_name="test"}: rsnip::infrastructure::edit_snippets: close time.busy=16.2µs time.idle=18.4µs [INFO] [stdout] test config::tests::given_combined_type_when_getting_snippet_type_then_returns_none ... ok [INFO] [stdout] test config::tests::given_combined_type_when_getting_sources_then_returns_source_list ... ok [INFO] [stdout] test cli::args::tests::given_generate_config_flag_when_parsing_then_sets_flag ... ok [INFO] [stdout] test config::tests::given_concrete_type_when_getting_sources_then_returns_none ... ok [INFO] [stdout] test config::tests::given_concrete_type_when_getting_snippet_type_then_returns_correct_type ... ok [INFO] [stdout] test infrastructure::edit_snippets::tests::given_valid_content_when_finding_snippet_then_returns_correct_line ... ok [INFO] [stdout] test util::path_utils::tests::given_absolute_path_when_expanding_then_returns_unchanged ... ok [INFO] [stdout] test util::path_utils::tests::given_path_with_tilde_when_expanding_then_replaces_with_home ... ok [INFO] [stderr] 2025-11-05T05:15:23.408188Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.97µs time.idle=15.3µs [INFO] [stdout] test util::path_utils::tests::given_invalid_tilde_path_when_expanding_then_returns_error ... ok [INFO] [stderr] 2025-11-05T05:15:23.408257Z DEBUG create{format=Scls}: rsnip::infrastructure::parsers: close time.busy=5.53µs time.idle=9.53µs [INFO] [stderr] 2025-11-05T05:15:23.408315Z DEBUG parse{path="dummy"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "dummy" [INFO] [stderr] 2025-11-05T05:15:23.408425Z DEBUG parse{path="dummy"}: rsnip::infrastructure::parsers::default: close time.busy=72.9µs time.idle=45.4µs [INFO] [stderr] 2025-11-05T05:15:23.408644Z DEBUG parse{path="dummy"}: rsnip::infrastructure::parsers::scls: Parsing SCLS format snippets from: "dummy" [INFO] [stderr] 2025-11-05T05:15:23.408699Z DEBUG parse{path="dummy"}: rsnip::infrastructure::parsers::scls: close time.busy=55.9µs time.idle=10.8µs [INFO] [stdout] test infrastructure::parsers::tests::given_format_when_creating_parser_then_returns_correct_implementation ... ok [INFO] [stderr] 2025-11-05T05:15:23.412103Z DEBUG create{format=Scls}: rsnip::infrastructure::parsers: close time.busy=7.54µs time.idle=20.2µs [INFO] [stderr] 2025-11-05T05:15:23.412181Z DEBUG parse{path="/tmp/.tmpD7LVrN"}: rsnip::infrastructure::parsers::scls: Parsing SCLS format snippets from: "/tmp/.tmpD7LVrN" [INFO] [stderr] 2025-11-05T05:15:23.417034Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=9.51µs time.idle=16.2µs [INFO] [stderr] 2025-11-05T05:15:23.419944Z DEBUG parse{path="/tmp/.tmpAxSpMs"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpAxSpMs" [INFO] [stderr] 2025-11-05T05:15:23.420179Z TRACE parse{path="/tmp/.tmpAxSpMs"}:new{content="content"}: rsnip::domain::content: SnippetContent::new called with: content [INFO] [stderr] 2025-11-05T05:15:23.420265Z TRACE parse{path="/tmp/.tmpAxSpMs"}:new{content="content"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.420363Z DEBUG parse{path="/tmp/.tmpAxSpMs"}:new{content="content"}: rsnip::domain::content: close time.busy=189µs time.idle=12.2µs [INFO] [stderr] 2025-11-05T05:15:23.420498Z DEBUG parse{path="/tmp/.tmpAxSpMs"}: rsnip::infrastructure::parsers::default: close time.busy=555µs time.idle=12.5µs [INFO] [stdout] test infrastructure::parsers::tests::given_default_format_file_when_parsing_then_succeeds ... ok [INFO] [stdout] test infrastructure::parsers::tests::given_scls_format_file_when_parsing_then_succeeds ... ok [INFO] [stderr] 2025-11-05T05:15:23.423369Z TRACE parse{path="/tmp/.tmpD7LVrN"}:new{content="content"}: rsnip::domain::content: SnippetContent::new called with: content [INFO] [stderr] 2025-11-05T05:15:23.423401Z TRACE parse{path="/tmp/.tmpD7LVrN"}:new{content="content"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.423432Z DEBUG parse{path="/tmp/.tmpD7LVrN"}:new{content="content"}: rsnip::domain::content: close time.busy=62.6µs time.idle=12.5µs [INFO] [stderr] 2025-11-05T05:15:23.423472Z DEBUG parse{path="/tmp/.tmpD7LVrN"}: rsnip::infrastructure::parsers::scls: close time.busy=11.3ms time.idle=9.00µs [INFO] [stderr] 2025-11-05T05:15:23.427009Z DEBUG find_snippet_line_number{content="some content\n--- test\ncontent\n---" snippet_name="nonexistent"}: rsnip::infrastructure::edit_snippets: close time.busy=17.4µs time.idle=12.0µs [INFO] [stdout] test infrastructure::edit_snippets::tests::given_nonexistent_snippet_when_finding_line_then_returns_none ... ok [INFO] [stdout] test util::path_utils::tests::given_relative_path_when_expanding_then_returns_unchanged ... ok [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/rsnip-cd58af9b93b26ed5) [INFO] [stdout] [INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stderr] Running tests/test_commands.rs (/opt/rustwide/target/debug/deps/test_commands-984a6a495d26511d) [INFO] [stdout] test tests::verify_cli ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stderr] Running tests/test_fuzzy.rs (/opt/rustwide/target/debug/deps/test_fuzzy-89121e4e2a82fc09) [INFO] [stdout] test given_types_list_flag_when_execute_then_outputs_space_separated ... 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 8 tests [INFO] [stdout] test given_empty_query_when_fuzzy_finder_then_shows_all ... ignored, todo fix [INFO] [stdout] test given_multiple_matches_when_fuzzy_finder_then_shows_interface ... ignored, This test is interactive via Makefile [INFO] [stdout] test given_no_matches_when_fuzzy_finder_then_shows_interface ... ignored, Interactive test requires terminal [INFO] [stdout] test test_fuzzy_finder_output_is_clean ... ignored, Interactive test requires terminal [INFO] [stdout] test given_snippets_when_creating_skim_items_then_returns_formatted_items ... ok [INFO] [stdout] test given_empty_items_when_fuzzy_finder_then_returns_none ... ok [INFO] [stdout] test given_single_partial_match_when_fuzzy_finder_then_auto_selects ... ok [INFO] [stdout] test given_exact_match_when_fuzzy_finder_then_returns_immediately ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 4 passed; 0 failed; 4 ignored; 0 measured; 0 filtered out; finished in 0.01s [INFO] [stdout] [INFO] [stderr] Running tests/test_lib.rs (/opt/rustwide/target/debug/deps/test_lib-889d701f3e956014) [INFO] [stdout] [INFO] [stderr] 2025-11-05T05:15:23.476559Z  INFO rsnip::util::testing: Test Setup complete [INFO] [stdout] running 35 tests [INFO] [stderr] 2025-11-05T05:15:23.477981Z DEBUG copy_snippet_to_clipboard{completion_type="test" input="nonexistent" exact=true}:find_completion_exact{completion_type="test" user_input="nonexistent"}:get_snippets{snippet_type="test"}: rsnip::application::snippet_service: Loading snippets from /tmp/.tmpOMLguD [INFO] [stderr] 2025-11-05T05:15:23.478059Z DEBUG copy_snippet_to_clipboard{completion_type="test" input="nonexistent" exact=true}:find_completion_exact{completion_type="test" user_input="nonexistent"}:get_snippets{snippet_type="test"}:create{format=Default}: rsnip::infrastructure::parsers: close time.busy=7.60µs time.idle=9.02µs [INFO] [stderr] 2025-11-05T05:15:23.478125Z DEBUG copy_snippet_to_clipboard{completion_type="test" input="nonexistent" exact=true}:find_completion_exact{completion_type="test" user_input="nonexistent"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpOMLguD"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpOMLguD" [INFO] [stderr] 2025-11-05T05:15:23.478259Z TRACE copy_snippet_to_clipboard{completion_type="test" input="nonexistent" exact=true}:find_completion_exact{completion_type="test" user_input="nonexistent"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpOMLguD"}:new{content="A red fruit"}: rsnip::domain::content: SnippetContent::new called with: A red fruit [INFO] [stderr] 2025-11-05T05:15:23.478299Z TRACE copy_snippet_to_clipboard{completion_type="test" input="nonexistent" exact=true}:find_completion_exact{completion_type="test" user_input="nonexistent"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpOMLguD"}:new{content="A red fruit"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.478334Z DEBUG copy_snippet_to_clipboard{completion_type="test" input="nonexistent" exact=true}:find_completion_exact{completion_type="test" user_input="nonexistent"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpOMLguD"}:new{content="A red fruit"}: rsnip::domain::content: close time.busy=78.3µs time.idle=10.1µs [INFO] [stderr] 2025-11-05T05:15:23.478377Z DEBUG copy_snippet_to_clipboard{completion_type="test" input="nonexistent" exact=true}:find_completion_exact{completion_type="test" user_input="nonexistent"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpOMLguD"}: rsnip::infrastructure::parsers::default: close time.busy=253µs time.idle=7.37µs [INFO] [stderr] 2025-11-05T05:15:23.478411Z DEBUG copy_snippet_to_clipboard{completion_type="test" input="nonexistent" exact=true}:find_completion_exact{completion_type="test" user_input="nonexistent"}:get_snippets{snippet_type="test"}: rsnip::application::snippet_service: close time.busy=447µs time.idle=6.11µs [INFO] [stderr] 2025-11-05T05:15:23.478440Z DEBUG copy_snippet_to_clipboard{completion_type="test" input="nonexistent" exact=true}:find_completion_exact{completion_type="test" user_input="nonexistent"}: rsnip::application::snippet_service: close time.busy=493µs time.idle=8.16µs [INFO] [stderr] 2025-11-05T05:15:23.478466Z DEBUG copy_snippet_to_clipboard{completion_type="test" input="nonexistent" exact=true}: rsnip::application::snippet_service: close time.busy=600µs time.idle=18.3µs [INFO] [stderr] 2025-11-05T05:15:23.478623Z DEBUG find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}: rsnip::application::snippet_service: Loading snippets from /tmp/.tmp7bZZrB [INFO] [stdout] test application::test_snippet_service::given_nonexistent_snippet_when_copying_then_returns_none ... ok [INFO] [stdout] test application::test_snippet_service::given_exact_match_when_finding_completion_then_returns_snippet ... ok [INFO] [stderr] 2025-11-05T05:15:23.479183Z DEBUG find_completion_exact{completion_type="test" user_input="test"}:get_snippets{snippet_type="test"}: rsnip::application::snippet_service: Loading snippets from /tmp/.tmp8tl0JE [INFO] [stderr] 2025-11-05T05:15:23.479247Z DEBUG find_completion_exact{completion_type="test" user_input="test"}:get_snippets{snippet_type="test"}:create{format=Default}: rsnip::infrastructure::parsers: close time.busy=5.98µs time.idle=8.75µs [INFO] [stderr] 2025-11-05T05:15:23.479294Z DEBUG find_completion_exact{completion_type="test" user_input="test"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmp8tl0JE"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmp8tl0JE" [INFO] [stderr] 2025-11-05T05:15:23.479387Z TRACE find_completion_exact{completion_type="test" user_input="test"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmp8tl0JE"}:new{content="Content"}: rsnip::domain::content: SnippetContent::new called with: Content [INFO] [stderr] 2025-11-05T05:15:23.479420Z TRACE find_completion_exact{completion_type="test" user_input="test"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmp8tl0JE"}:new{content="Content"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.479451Z DEBUG find_completion_exact{completion_type="test" user_input="test"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmp8tl0JE"}:new{content="Content"}: rsnip::domain::content: close time.busy=63.5µs time.idle=9.44µs [INFO] [stderr] 2025-11-05T05:15:23.479489Z DEBUG find_completion_exact{completion_type="test" user_input="test"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmp8tl0JE"}: rsnip::infrastructure::parsers::default: close time.busy=194µs time.idle=7.42µs [INFO] [stderr] 2025-11-05T05:15:23.479519Z DEBUG find_completion_exact{completion_type="test" user_input="test"}:get_snippets{snippet_type="test"}: rsnip::application::snippet_service: close time.busy=341µs time.idle=7.42µs [INFO] [stderr] 2025-11-05T05:15:23.479546Z DEBUG find_completion_exact{completion_type="test" user_input="test"}: rsnip::application::snippet_service: close time.busy=393µs time.idle=10.0µs [INFO] [stderr] 2025-11-05T05:15:23.479985Z DEBUG find_completion_exact{completion_type="test" user_input=""}: rsnip::application::snippet_service: close time.busy=7.39µs time.idle=10.9µs [INFO] [stderr] 2025-11-05T05:15:23.480047Z DEBUG find_completion_fuzzy{completion_type="test" user_input=""}: rsnip::application::snippet_service: close time.busy=5.38µs time.idle=8.82µs [INFO] [stderr] 2025-11-05T05:15:23.480068Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.15µs time.idle=13.2µs [INFO] [stderr] 2025-11-05T05:15:23.480114Z DEBUG parse{path="/tmp/.tmpiV7MqP"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpiV7MqP" [INFO] [stderr] 2025-11-05T05:15:23.480250Z DEBUG copy_snippet_to_clipboard{completion_type="test" input="date" exact=true}:find_completion_exact{completion_type="test" user_input="date"}:get_snippets{snippet_type="test"}: rsnip::application::snippet_service: Loading snippets from /tmp/.tmplKDkWI [INFO] [stderr] 2025-11-05T05:15:23.480307Z DEBUG copy_snippet_to_clipboard{completion_type="test" input="date" exact=true}:find_completion_exact{completion_type="test" user_input="date"}:get_snippets{snippet_type="test"}:create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.30µs time.idle=9.58µs [INFO] [stderr] 2025-11-05T05:15:23.480362Z DEBUG parse{path="/tmp/.tmpiV7MqP"}: rsnip::infrastructure::parsers::default: close time.busy=244µs time.idle=8.64µs [INFO] [stderr] 2025-11-05T05:15:23.480359Z DEBUG copy_snippet_to_clipboard{completion_type="test" input="date" exact=true}:find_completion_exact{completion_type="test" user_input="date"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmplKDkWI"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmplKDkWI" [INFO] [stderr] 2025-11-05T05:15:23.480440Z TRACE copy_snippet_to_clipboard{completion_type="test" input="date" exact=true}:find_completion_exact{completion_type="test" user_input="date"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmplKDkWI"}:new{content="{{current_date|strftime('%Y-%m-%d')}}"}: rsnip::domain::content: SnippetContent::new called with: {{current_date|strftime('%Y-%m-%d')}} [INFO] [stderr] 2025-11-05T05:15:23.480488Z TRACE copy_snippet_to_clipboard{completion_type="test" input="date" exact=true}:find_completion_exact{completion_type="test" user_input="date"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmplKDkWI"}:new{content="{{current_date|strftime('%Y-%m-%d')}}"}: rsnip::domain::content: Detected as template [INFO] [stderr] 2025-11-05T05:15:23.480525Z DEBUG copy_snippet_to_clipboard{completion_type="test" input="date" exact=true}:find_completion_exact{completion_type="test" user_input="date"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmplKDkWI"}:new{content="{{current_date|strftime('%Y-%m-%d')}}"}: rsnip::domain::content: close time.busy=83.6µs time.idle=12.4µs [INFO] [stderr] 2025-11-05T05:15:23.480578Z DEBUG copy_snippet_to_clipboard{completion_type="test" input="date" exact=true}:find_completion_exact{completion_type="test" user_input="date"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmplKDkWI"}: rsnip::infrastructure::parsers::default: close time.busy=217µs time.idle=8.85µs [INFO] [stdout] test application::test_snippet_service::given_empty_input_when_finding_completion_then_returns_none ... ok [INFO] [stderr] 2025-11-05T05:15:23.480619Z DEBUG copy_snippet_to_clipboard{completion_type="test" input="date" exact=true}:find_completion_exact{completion_type="test" user_input="date"}:get_snippets{snippet_type="test"}: rsnip::application::snippet_service: close time.busy=378µs time.idle=6.54µs [INFO] [stderr] 2025-11-05T05:15:23.480648Z DEBUG copy_snippet_to_clipboard{completion_type="test" input="date" exact=true}:find_completion_exact{completion_type="test" user_input="date"}: rsnip::application::snippet_service: close time.busy=419µs time.idle=7.21µs [INFO] [stderr] 2025-11-05T05:15:23.481189Z TRACE copy_snippet_to_clipboard{completion_type="test" input="date" exact=true}:copy_to_clipboard{text="2025-11-05"}: rsnip::infrastructure::clipboard: close time.busy=69.7µs time.idle=9.88µs [INFO] [stderr] 2025-11-05T05:15:23.481224Z DEBUG copy_snippet_to_clipboard{completion_type="test" input="date" exact=true}: rsnip::application::snippet_service: close time.busy=1.01ms time.idle=9.40µs [INFO] [stderr] 2025-11-05T05:15:23.483969Z DEBUG get_snippets{snippet_type="combined"}: rsnip::application::snippet_service: Loading combined snippets from sources: ["source1", "source2"] [INFO] [stderr] 2025-11-05T05:15:23.484014Z DEBUG get_snippets{snippet_type="combined"}: rsnip::application::snippet_service: Loading snippets from /tmp/.tmpRFoGeb [INFO] [stderr] 2025-11-05T05:15:23.484059Z DEBUG get_snippets{snippet_type="combined"}:create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.61µs time.idle=9.29µs [INFO] [stdout] test infrastructure::parsers::test_default::given_empty_snippet_when_parse_then_returns_error ... ok [INFO] [stderr] 2025-11-05T05:15:23.478687Z DEBUG find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}:create{format=Default}: rsnip::infrastructure::parsers: close time.busy=5.55µs time.idle=8.32µs [INFO] [stderr] 2025-11-05T05:15:23.485218Z DEBUG get_snippets{snippet_type="test"}: rsnip::application::snippet_service: Loading snippets from /tmp/.tmpCIvYVX [INFO] [stdout] test application::test_snippet_service::given_valid_snippet_file_when_getting_snippets_then_returns_snippets ... ok [INFO] [stderr] 2025-11-05T05:15:23.485619Z DEBUG get_snippets{snippet_type="test"}:create{format=Default}: rsnip::infrastructure::parsers: close time.busy=4.09µs time.idle=6.86µs [INFO] [stdout] test infrastructure::parsers::test_default::given_empty_file_when_parse_then_returns_empty_vec ... ok [INFO] [stderr] 2025-11-05T05:15:23.485670Z DEBUG get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpCIvYVX"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpCIvYVX" [INFO] [stdout] test infrastructure::parsers::test_default::given_extra_text_outside_snippets_when_parse_then_ignores_it ... ok [INFO] [stderr] 2025-11-05T05:15:23.485753Z TRACE get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpCIvYVX"}:new{content="Content"}: rsnip::domain::content: SnippetContent::new called with: Content [INFO] [stdout] test application::test_snippet_service::given_fuzzy_match_when_finding_completion_then_returns_best_match ... ok [INFO] [stderr] 2025-11-05T05:15:23.485784Z TRACE get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpCIvYVX"}:new{content="Content"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.485812Z DEBUG get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpCIvYVX"}:new{content="Content"}: rsnip::domain::content: close time.busy=57.4µs time.idle=8.88µs [INFO] [stderr] 2025-11-05T05:15:23.485846Z DEBUG get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpCIvYVX"}: rsnip::infrastructure::parsers::default: close time.busy=176µs time.idle=8.12µs [INFO] [stderr] 2025-11-05T05:15:23.485891Z DEBUG get_snippets{snippet_type="test"}: rsnip::application::snippet_service: close time.busy=684µs time.idle=11.2µs [INFO] [stderr] 2025-11-05T05:15:23.485404Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.47µs time.idle=14.9µs [INFO] [stderr] 2025-11-05T05:15:23.486116Z DEBUG parse{path="/tmp/.tmpDjtIuv"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpDjtIuv" [INFO] [stderr] 2025-11-05T05:15:23.486179Z DEBUG parse{path="/tmp/.tmpDjtIuv"}: rsnip::infrastructure::parsers::default: close time.busy=63.1µs time.idle=11.6µs [INFO] [stderr] 2025-11-05T05:15:23.486243Z DEBUG get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmpRFoGeb"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpRFoGeb" [INFO] [stderr] 2025-11-05T05:15:23.485565Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.30µs time.idle=15.7µs [INFO] [stderr] 2025-11-05T05:15:23.486334Z DEBUG parse{path="/tmp/.tmpeR7vFE"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpeR7vFE" [INFO] [stderr] 2025-11-05T05:15:23.486345Z TRACE get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmpRFoGeb"}:new{content="Content1"}: rsnip::domain::content: SnippetContent::new called with: Content1 [INFO] [stderr] 2025-11-05T05:15:23.486375Z TRACE get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmpRFoGeb"}:new{content="Content1"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.486392Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.62µs time.idle=11.1µs [INFO] [stderr] 2025-11-05T05:15:23.486402Z DEBUG get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmpRFoGeb"}:new{content="Content1"}: rsnip::domain::content: close time.busy=56.7µs time.idle=9.83µs [INFO] [stderr] 2025-11-05T05:15:23.486419Z TRACE parse{path="/tmp/.tmpeR7vFE"}:new{content="line"}: rsnip::domain::content: SnippetContent::new called with: line [INFO] [stderr] 2025-11-05T05:15:23.486435Z DEBUG get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmpRFoGeb"}: rsnip::infrastructure::parsers::default: close time.busy=191µs time.idle=8.63µs [INFO] [stderr] 2025-11-05T05:15:23.486437Z DEBUG parse{path="/nonexistent/file"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/nonexistent/file" [INFO] [stderr] 2025-11-05T05:15:23.486446Z TRACE parse{path="/tmp/.tmpeR7vFE"}:new{content="line"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.486461Z DEBUG get_snippets{snippet_type="combined"}: rsnip::application::snippet_service: Loading snippets from /tmp/.tmp28KXKD [INFO] [stderr] 2025-11-05T05:15:23.486471Z DEBUG parse{path="/tmp/.tmpeR7vFE"}:new{content="line"}: rsnip::domain::content: close time.busy=51.8µs time.idle=9.11µs [INFO] [stderr] 2025-11-05T05:15:23.486495Z DEBUG get_snippets{snippet_type="combined"}:create{format=Default}: rsnip::infrastructure::parsers: close time.busy=4.21µs time.idle=7.78µs [INFO] [stderr] 2025-11-05T05:15:23.486506Z DEBUG parse{path="/tmp/.tmpeR7vFE"}: rsnip::infrastructure::parsers::default: close time.busy=173µs time.idle=10.1µs [INFO] [stderr] 2025-11-05T05:15:23.486531Z DEBUG get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmp28KXKD"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmp28KXKD" [INFO] [stderr] 2025-11-05T05:15:23.486610Z TRACE get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmp28KXKD"}:new{content="Content2"}: rsnip::domain::content: SnippetContent::new called with: Content2 [INFO] [stderr] 2025-11-05T05:15:23.486637Z TRACE get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmp28KXKD"}:new{content="Content2"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.488036Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=7.21µs time.idle=11.1µs [INFO] [stderr] 2025-11-05T05:15:23.488115Z DEBUG parse{path="/tmp/.tmpbI6Zc8"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpbI6Zc8" [INFO] [stderr] 2025-11-05T05:15:23.488195Z TRACE parse{path="/tmp/.tmpbI6Zc8"}:new{content="content"}: rsnip::domain::content: SnippetContent::new called with: content [INFO] [stderr] 2025-11-05T05:15:23.488223Z TRACE parse{path="/tmp/.tmpbI6Zc8"}:new{content="content"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.488250Z DEBUG parse{path="/tmp/.tmpbI6Zc8"}:new{content="content"}: rsnip::domain::content: close time.busy=54.4µs time.idle=10.7µs [INFO] [stderr] 2025-11-05T05:15:23.486166Z DEBUG find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmp7bZZrB"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmp7bZZrB" [INFO] [stderr] 2025-11-05T05:15:23.488355Z TRACE find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmp7bZZrB"}:new{content="Content"}: rsnip::domain::content: SnippetContent::new called with: Content [INFO] [stderr] 2025-11-05T05:15:23.488385Z TRACE find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmp7bZZrB"}:new{content="Content"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.488416Z DEBUG find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmp7bZZrB"}:new{content="Content"}: rsnip::domain::content: close time.busy=60.6µs time.idle=9.70µs [INFO] [stderr] 2025-11-05T05:15:23.488479Z TRACE find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmp7bZZrB"}:new{content="Content2"}: rsnip::domain::content: SnippetContent::new called with: Content2 [INFO] [stderr] 2025-11-05T05:15:23.488508Z TRACE find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmp7bZZrB"}:new{content="Content2"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.488538Z DEBUG find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmp7bZZrB"}:new{content="Content2"}: rsnip::domain::content: close time.busy=58.7µs time.idle=9.46µs [INFO] [stderr] 2025-11-05T05:15:23.488575Z DEBUG find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmp7bZZrB"}: rsnip::infrastructure::parsers::default: close time.busy=2.41ms time.idle=15.1µs [INFO] [stderr] 2025-11-05T05:15:23.488610Z DEBUG find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}: rsnip::application::snippet_service: close time.busy=10.00ms time.idle=7.28µs [INFO] [stderr] 2025-11-05T05:15:23.488697Z DEBUG find_completion_fuzzy{completion_type="test" user_input="tst"}: rsnip::application::snippet_service: close time.busy=10.1ms time.idle=12.6µs [INFO] [stdout] test infrastructure::parsers::test_default::given_snippet_with_comments_when_parse_then_preserves_comments ... ok [INFO] [stderr] 2025-11-05T05:15:23.489059Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.79µs time.idle=11.7µs [INFO] [stdout] test infrastructure::parsers::test_default::given_valid_snippet_file_when_parse_then_returns_correct_snippets ... ok [INFO] [stderr] 2025-11-05T05:15:23.489098Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.62µs time.idle=11.9µs [INFO] [stderr] 2025-11-05T05:15:23.489128Z DEBUG parse{path="/tmp/.tmpzfutpo"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpzfutpo" [INFO] [stderr] 2025-11-05T05:15:23.489148Z DEBUG parse{path="/tmp/.tmpOXCxQ0"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpOXCxQ0" [INFO] [stderr] 2025-11-05T05:15:23.489219Z TRACE parse{path="/tmp/.tmpzfutpo"}:new{content="content"}: rsnip::domain::content: SnippetContent::new called with: content [INFO] [stderr] 2025-11-05T05:15:23.489235Z TRACE parse{path="/tmp/.tmpOXCxQ0"}:new{content="this is green\nand nothing else"}: rsnip::domain::content: SnippetContent::new called with: this is green [INFO] [stderr] and nothing else [INFO] [stderr] 2025-11-05T05:15:23.489251Z TRACE parse{path="/tmp/.tmpzfutpo"}:new{content="content"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.489265Z TRACE parse{path="/tmp/.tmpOXCxQ0"}:new{content="this is green\nand nothing else"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.489281Z DEBUG parse{path="/tmp/.tmpzfutpo"}:new{content="content"}: rsnip::domain::content: close time.busy=61.5µs time.idle=10.4µs [INFO] [stderr] 2025-11-05T05:15:23.489289Z DEBUG parse{path="/tmp/.tmpOXCxQ0"}:new{content="this is green\nand nothing else"}: rsnip::domain::content: close time.busy=53.4µs time.idle=10.4µs [INFO] [stderr] 2025-11-05T05:15:23.489319Z DEBUG parse{path="/tmp/.tmpOXCxQ0"}: rsnip::infrastructure::parsers::default: close time.busy=171µs time.idle=9.19µs [INFO] [stderr] 2025-11-05T05:15:23.489674Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=7.12µs time.idle=12.4µs [INFO] [stderr] 2025-11-05T05:15:23.489724Z DEBUG parse{path="/tmp/.tmpylLLHO"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpylLLHO" [INFO] [stderr] 2025-11-05T05:15:23.489810Z TRACE parse{path="/tmp/.tmpylLLHO"}:new{content="this is green\nand nothing else"}: rsnip::domain::content: SnippetContent::new called with: this is green [INFO] [stderr] and nothing else [INFO] [stderr] 2025-11-05T05:15:23.489842Z TRACE parse{path="/tmp/.tmpylLLHO"}:new{content="this is green\nand nothing else"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.489890Z DEBUG parse{path="/tmp/.tmpylLLHO"}:new{content="this is green\nand nothing else"}: rsnip::domain::content: close time.busy=78.4µs time.idle=10.6µs [INFO] [stderr] 2025-11-05T05:15:23.489949Z TRACE parse{path="/tmp/.tmpylLLHO"}:new{content="this is green2"}: rsnip::domain::content: SnippetContent::new called with: this is green2 [INFO] [stderr] 2025-11-05T05:15:23.489972Z TRACE parse{path="/tmp/.tmpylLLHO"}:new{content="this is green2"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.489996Z DEBUG parse{path="/tmp/.tmpylLLHO"}:new{content="this is green2"}: rsnip::domain::content: close time.busy=45.7µs time.idle=10.1µs [INFO] [stderr] 2025-11-05T05:15:23.490041Z TRACE parse{path="/tmp/.tmpylLLHO"}:new{content="this is yellow"}: rsnip::domain::content: SnippetContent::new called with: this is yellow [INFO] [stderr] 2025-11-05T05:15:23.490062Z TRACE parse{path="/tmp/.tmpylLLHO"}:new{content="this is yellow"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.490092Z DEBUG parse{path="/tmp/.tmpylLLHO"}:new{content="this is yellow"}: rsnip::domain::content: close time.busy=49.3µs time.idle=7.99µs [INFO] [stderr] 2025-11-05T05:15:23.490134Z TRACE parse{path="/tmp/.tmpylLLHO"}:new{content="this is other"}: rsnip::domain::content: SnippetContent::new called with: this is other [INFO] [stderr] 2025-11-05T05:15:23.490151Z TRACE parse{path="/tmp/.tmpylLLHO"}:new{content="this is other"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.490170Z DEBUG parse{path="/tmp/.tmpylLLHO"}:new{content="this is other"}: rsnip::domain::content: close time.busy=34.2µs time.idle=7.29µs [INFO] [stderr] 2025-11-05T05:15:23.490195Z DEBUG parse{path="/tmp/.tmpylLLHO"}: rsnip::infrastructure::parsers::default: close time.busy=471µs time.idle=8.41µs [INFO] [stdout] test application::test_snippet_service::given_combined_type_when_getting_snippets_then_returns_all_snippets ... ok [INFO] [stderr] 2025-11-05T05:15:23.490934Z DEBUG get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmp28KXKD"}:new{content="Content2"}: rsnip::domain::content: close time.busy=4.32ms time.idle=18.9µs [INFO] [stderr] 2025-11-05T05:15:23.490985Z DEBUG get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmp28KXKD"}: rsnip::infrastructure::parsers::default: close time.busy=4.45ms time.idle=7.28µs [INFO] [stderr] 2025-11-05T05:15:23.491016Z DEBUG get_snippets{snippet_type="combined"}: rsnip::application::snippet_service: close time.busy=7.06ms time.idle=11.3µs [INFO] [stderr] 2025-11-05T05:15:23.497121Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=9.04µs time.idle=14.0µs [INFO] [stderr] 2025-11-05T05:15:23.497189Z DEBUG parse{path="/tmp/.tmp89UIAy"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmp89UIAy" [INFO] [stderr] 2025-11-05T05:15:23.497284Z TRACE parse{path="/tmp/.tmp89UIAy"}:new{content="content"}: rsnip::domain::content: SnippetContent::new called with: content [INFO] [stderr] 2025-11-05T05:15:23.497326Z TRACE parse{path="/tmp/.tmp89UIAy"}:new{content="content"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.497353Z DEBUG parse{path="/tmp/.tmp89UIAy"}:new{content="content"}: rsnip::domain::content: close time.busy=68.3µs time.idle=9.12µs [INFO] [stderr] 2025-11-05T05:15:23.497538Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=5.94µs time.idle=10.4µs [INFO] [stderr] 2025-11-05T05:15:23.497592Z DEBUG parse{path="/tmp/.tmpMFioL1"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpMFioL1" [INFO] [stderr] 2025-11-05T05:15:23.497817Z DEBUG create{format=Scls}: rsnip::infrastructure::parsers: close time.busy=6.46µs time.idle=14.8µs [INFO] [stderr] 2025-11-05T05:15:23.497906Z DEBUG parse{path="/tmp/.tmpuCoiRK"}: rsnip::infrastructure::parsers::scls: Parsing SCLS format snippets from: "/tmp/.tmpuCoiRK" [INFO] [stderr] 2025-11-05T05:15:23.498047Z DEBUG parse{path="/tmp/.tmpuCoiRK"}: rsnip::infrastructure::parsers::scls: close time.busy=142µs time.idle=8.82µs [INFO] [stdout] test infrastructure::parsers::test_scls::given_empty_scls_file_when_parse_then_returns_empty_vec ... ok [INFO] [stderr] 2025-11-05T05:15:23.498611Z DEBUG create{format=Scls}: rsnip::infrastructure::parsers: close time.busy=6.08µs time.idle=11.1µs [INFO] [stderr] 2025-11-05T05:15:23.502122Z DEBUG create{format=Scls}: rsnip::infrastructure::parsers: close time.busy=8.29µs time.idle=17.2µs [INFO] [stderr] 2025-11-05T05:15:23.502226Z DEBUG parse{path="/tmp/.tmpSXTbtn"}: rsnip::infrastructure::parsers::scls: Parsing SCLS format snippets from: "/tmp/.tmpSXTbtn" [INFO] [stderr] 2025-11-05T05:15:23.498844Z DEBUG parse{path="/tmp/.tmptN3ZFg"}: rsnip::infrastructure::parsers::scls: Parsing SCLS format snippets from: "/tmp/.tmptN3ZFg" [INFO] [stderr] 2025-11-05T05:15:23.519595Z TRACE parse{path="/tmp/.tmptN3ZFg"}:new{content="class {{ ClassName }}:\n def __init__(self):\n {{ pass }}\n"}: rsnip::domain::content: SnippetContent::new called with: class {{ ClassName }}: [INFO] [stderr] def __init__(self): [INFO] [stderr] {{ pass }} [INFO] [stderr] [INFO] [stderr] 2025-11-05T05:15:23.519731Z TRACE parse{path="/tmp/.tmptN3ZFg"}:new{content="class {{ ClassName }}:\n def __init__(self):\n {{ pass }}\n"}: rsnip::domain::content: Detected as template [INFO] [stdout] test infrastructure::parsers::test_scls::given_multiline_array_body_when_parse_then_handles_correctly ... ok [INFO] [stderr] 2025-11-05T05:15:23.519827Z DEBUG parse{path="/tmp/.tmptN3ZFg"}:new{content="class {{ ClassName }}:\n def __init__(self):\n {{ pass }}\n"}: rsnip::domain::content: close time.busy=234µs time.idle=14.9µs [INFO] [stderr] 2025-11-05T05:15:23.519933Z DEBUG parse{path="/tmp/.tmptN3ZFg"}: rsnip::infrastructure::parsers::scls: close time.busy=21.1ms time.idle=9.90µs [INFO] [stderr] 2025-11-05T05:15:23.535039Z DEBUG create{format=Scls}: rsnip::infrastructure::parsers: close time.busy=10.7µs time.idle=16.7µs [INFO] [stderr] 2025-11-05T05:15:23.535124Z DEBUG parse{path="/nonexistent/file"}: rsnip::infrastructure::parsers::scls: Parsing SCLS format snippets from: "/nonexistent/file" [INFO] [stderr] 2025-11-05T05:15:23.747849Z DEBUG parse{path="/nonexistent/file"}: rsnip::infrastructure::parsers::default: close time.busy=261ms time.idle=10.4µs [INFO] [stderr] 2025-11-05T05:15:23.754014Z DEBUG parse{path="/tmp/.tmpbI6Zc8"}: rsnip::infrastructure::parsers::default: close time.busy=266ms time.idle=11.1µs [INFO] [stderr] 2025-11-05T05:15:23.754014Z DEBUG parse{path="/tmp/.tmpzfutpo"}: rsnip::infrastructure::parsers::default: close time.busy=265ms time.idle=11.6µs [INFO] [stderr] 2025-11-05T05:15:23.755937Z DEBUG parse{path="/tmp/.tmp89UIAy"}: rsnip::infrastructure::parsers::default: close time.busy=259ms time.idle=9.75µs [INFO] [stderr] 2025-11-05T05:15:23.756157Z DEBUG parse{path="/tmp/.tmpMFioL1"}: rsnip::infrastructure::parsers::default: close time.busy=259ms time.idle=9.55µs [INFO] [stderr] 2025-11-05T05:15:23.756942Z DEBUG parse{path="/tmp/.tmpSXTbtn"}: rsnip::infrastructure::parsers::scls: close time.busy=255ms time.idle=10.7µs [INFO] [stderr] 2025-11-05T05:15:23.756965Z DEBUG parse{path="/nonexistent/file"}: rsnip::infrastructure::parsers::scls: close time.busy=222ms time.idle=9.88µs [INFO] [stdout] test application::test_snippet_service::given_template_snippet_when_copying_then_returns_rendered_content ... FAILED [INFO] [stdout] test infrastructure::parsers::test_default::given_nonexistent_file_when_parse_then_returns_error ... ok [INFO] [stdout] test infrastructure::parsers::test_default::given_snippet_with_closing_without_opening_when_parse_then_returns_error ... ok [INFO] [stdout] test infrastructure::parsers::test_default::given_malformed_snippet_without_closing_delimiter_when_parse_then_returns_error ... ok [INFO] [stderr] 2025-11-05T05:15:23.764773Z DEBUG create{format=Scls}: rsnip::infrastructure::parsers: close time.busy=9.13µs time.idle=15.4µs [INFO] [stderr] 2025-11-05T05:15:23.764853Z DEBUG parse{path="/tmp/.tmpTfMzBW"}: rsnip::infrastructure::parsers::scls: Parsing SCLS format snippets from: "/tmp/.tmpTfMzBW" [INFO] [stderr] 2025-11-05T05:15:23.765164Z DEBUG create{format=Scls}: rsnip::infrastructure::parsers: close time.busy=7.35µs time.idle=12.1µs [INFO] [stderr] 2025-11-05T05:15:23.765332Z DEBUG parse{path="/tmp/.tmpGo5sJV"}: rsnip::infrastructure::parsers::scls: Parsing SCLS format snippets from: "/tmp/.tmpGo5sJV" [INFO] [stderr] 2025-11-05T05:15:23.766069Z TRACE parse{path="/tmp/.tmpGo5sJV"}:new{content="print({{ param1 }})"}: rsnip::domain::content: SnippetContent::new called with: print({{ param1 }}) [INFO] [stderr] 2025-11-05T05:15:23.766110Z TRACE parse{path="/tmp/.tmpGo5sJV"}:new{content="print({{ param1 }})"}: rsnip::domain::content: Detected as template [INFO] [stderr] 2025-11-05T05:15:23.766142Z DEBUG parse{path="/tmp/.tmpGo5sJV"}:new{content="print({{ param1 }})"}: rsnip::domain::content: close time.busy=72.3µs time.idle=11.1µs [INFO] [stderr] 2025-11-05T05:15:23.766193Z TRACE parse{path="/tmp/.tmpTfMzBW"}:new{content="{{ first }} {{ second }} {{ param3 }}"}: rsnip::domain::content: SnippetContent::new called with: {{ first }} {{ second }} {{ param3 }} [INFO] [stderr] 2025-11-05T05:15:23.766221Z TRACE parse{path="/tmp/.tmpTfMzBW"}:new{content="{{ first }} {{ second }} {{ param3 }}"}: rsnip::domain::content: Detected as template [INFO] [stderr] 2025-11-05T05:15:23.766245Z DEBUG parse{path="/tmp/.tmpTfMzBW"}:new{content="{{ first }} {{ second }} {{ param3 }}"}: rsnip::domain::content: close time.busy=51.7µs time.idle=10.1µs [INFO] [stderr] 2025-11-05T05:15:23.766277Z DEBUG parse{path="/tmp/.tmpTfMzBW"}: rsnip::infrastructure::parsers::scls: close time.busy=1.43ms time.idle=9.70µs [INFO] [stderr] 2025-11-05T05:15:23.766665Z TRACE parse{path="/tmp/.tmpGo5sJV"}:new{content="def {{ name }}({{ args }}):\n {{ pass }}"}: rsnip::domain::content: SnippetContent::new called with: def {{ name }}({{ args }}): [INFO] [stderr] {{ pass }} [INFO] [stderr] 2025-11-05T05:15:23.766695Z TRACE parse{path="/tmp/.tmpGo5sJV"}:new{content="def {{ name }}({{ args }}):\n {{ pass }}"}: rsnip::domain::content: Detected as template [INFO] [stderr] 2025-11-05T05:15:23.766725Z DEBUG parse{path="/tmp/.tmpGo5sJV"}:new{content="def {{ name }}({{ args }}):\n {{ pass }}"}: rsnip::domain::content: close time.busy=60.3µs time.idle=11.1µs [INFO] [stderr] 2025-11-05T05:15:23.766764Z DEBUG parse{path="/tmp/.tmpGo5sJV"}: rsnip::infrastructure::parsers::scls: close time.busy=1.43ms time.idle=11.0µs [INFO] [stdout] test infrastructure::parsers::test_default::given_malformed_snippet_with_extra_start_when_parse_then_returns_error ... ok [INFO] [stdout] test infrastructure::parsers::test_default::given_missing_trailing_delimiter_when_parse_then_returns_error ... ok [INFO] [stderr] 2025-11-05T05:15:23.769970Z DEBUG parse{path="/tmp/.tmp1c6Uvk"}: rsnip::infrastructure::parsers::vcode: Parsing VSCode format snippets from: "/tmp/.tmp1c6Uvk" [INFO] [stdout] test infrastructure::parsers::test_scls::given_nonexistent_file_when_parse_then_returns_error ... ok [INFO] [stderr] 2025-11-05T05:15:23.774350Z DEBUG parse{path="/tmp/.tmp1Udh3H"}: rsnip::infrastructure::parsers::vcode: Parsing VSCode format snippets from: "/tmp/.tmp1Udh3H" [INFO] [stderr] 2025-11-05T05:15:23.774369Z DEBUG parse{path="/tmp/.tmp1c6Uvk"}: rsnip::infrastructure::parsers::vcode: close time.busy=4.40ms time.idle=11.4µs [INFO] [stderr] 2025-11-05T05:15:23.774507Z TRACE parse{path="/tmp/.tmp1Udh3H"}:new{content="fn ${1:function_name}(${2:params}) -> ${3:ReturnType} {\n ${4:// function body}\n}"}: rsnip::domain::content: SnippetContent::new called with: fn ${1:function_name}(${2:params}) -> ${3:ReturnType} { [INFO] [stderr] ${4:// function body} [INFO] [stderr] } [INFO] [stderr] 2025-11-05T05:15:23.774540Z TRACE parse{path="/tmp/.tmp1Udh3H"}:new{content="fn ${1:function_name}(${2:params}) -> ${3:ReturnType} {\n ${4:// function body}\n}"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.774567Z DEBUG parse{path="/tmp/.tmp1Udh3H"}:new{content="fn ${1:function_name}(${2:params}) -> ${3:ReturnType} {\n ${4:// function body}\n}"}: rsnip::domain::content: close time.busy=60.5µs time.idle=10.7µs [INFO] [stderr] 2025-11-05T05:15:23.774618Z TRACE parse{path="/tmp/.tmp1Udh3H"}:new{content="fn main() {\n println!(\"Hello, world!\");\n}"}: rsnip::domain::content: SnippetContent::new called with: fn main() { [INFO] [stderr] println!("Hello, world!"); [INFO] [stderr] } [INFO] [stderr] 2025-11-05T05:15:23.774641Z TRACE parse{path="/tmp/.tmp1Udh3H"}:new{content="fn main() {\n println!(\"Hello, world!\");\n}"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.774665Z DEBUG parse{path="/tmp/.tmp1Udh3H"}:new{content="fn main() {\n println!(\"Hello, world!\");\n}"}: rsnip::domain::content: close time.busy=45.8µs time.idle=9.20µs [INFO] [stderr] 2025-11-05T05:15:23.774697Z DEBUG parse{path="/tmp/.tmp1Udh3H"}: rsnip::infrastructure::parsers::vcode: close time.busy=349µs time.idle=11.6µs [INFO] [stdout] test infrastructure::parsers::test_scls::given_malformed_toml_when_parse_then_returns_error ... ok [INFO] [stdout] test infrastructure::parsers::test_scls::given_snippet_with_named_placeholders_when_parse_then_converts_correctly ... ok [INFO] [stdout] test infrastructure::parsers::test_scls::given_valid_scls_file_when_parse_then_returns_snippets ... ok [INFO] [stdout] test infrastructure::parsers::test_vcode::given_malformed_json_when_parse_then_returns_error ... ok [INFO] [stdout] test infrastructure::test_minijinja::given_static_content_when_rendering_then_returns_unchanged ... ok [INFO] [stdout] test infrastructure::parsers::test_vcode::given_valid_vscode_file_when_parse_then_returns_snippets ... ok [INFO] [stdout] test infrastructure::test_minijinja::given_template_with_date_arithmetic_when_rendering_then_calculates_correctly ... ok [INFO] [stdout] test infrastructure::test_minijinja::given_template_with_env_var_when_rendering_then_substitutes_value ... ok [INFO] [stdout] test infrastructure::test_minijinja::given_template_with_add_days_when_rendering_then_calculates_correctly ... ok [INFO] [stderr] 2025-11-05T05:15:23.776402Z  INFO rsnip::infrastructure::minijinja: Executing shell command: nonexistent_command [INFO] [stderr] 2025-11-05T05:15:23.779235Z  INFO rsnip::infrastructure::minijinja: Executing shell command: echo Hello [INFO] [stderr] 2025-11-05T05:15:23.781217Z  INFO rsnip::infrastructure::minijinja: Executing shell command: echo hello; rm -rf / [INFO] [stdout] test infrastructure::test_minijinja::given_template_with_shell_command_when_rendering_then_executes ... ok [INFO] [stderr] 2025-11-05T05:15:23.781502Z  INFO rsnip::infrastructure::minijinja: Executing shell command: echo hello | rm -rf / [INFO] [stderr] 2025-11-05T05:15:23.781764Z  INFO rsnip::infrastructure::minijinja: Executing shell command: echo hello && rm -rf / [INFO] [stderr] 2025-11-05T05:15:23.782065Z  INFO rsnip::infrastructure::minijinja: Executing shell command: sudo echo hello [INFO] [stderr] 2025-11-05T05:15:23.782356Z  INFO rsnip::infrastructure::minijinja: Executing shell command: `echo hello` [INFO] [stderr] 2025-11-05T05:15:23.782633Z  INFO rsnip::infrastructure::minijinja: Executing shell command: $(echo hello) [INFO] [stdout] test infrastructure::test_minijinja::given_template_with_dangerous_shell_commands_then_returns_error ... ok [INFO] [stdout] test infrastructure::test_minijinja::given_template_with_invalid_shell_command_when_rendering_then_returns_error ... ok [INFO] [stderr] 2025-11-05T05:15:23.793120Z DEBUG parse{path="/tmp/.tmpPngoOm"}: rsnip::infrastructure::parsers::vcode: Parsing VSCode format snippets from: "/tmp/.tmpPngoOm" [INFO] [stdout] test infrastructure::parsers::test_vcode::given_single_line_body_when_parse_then_handles_correctly ... ok [INFO] [stderr] 2025-11-05T05:15:23.793264Z TRACE parse{path="/tmp/.tmpPngoOm"}:new{content="println!(\"${1:message}\");"}: rsnip::domain::content: SnippetContent::new called with: println!("${1:message}"); [INFO] [stderr] 2025-11-05T05:15:23.793296Z TRACE parse{path="/tmp/.tmpPngoOm"}:new{content="println!(\"${1:message}\");"}: rsnip::domain::content: Detected as static [INFO] [stderr] 2025-11-05T05:15:23.793323Z DEBUG parse{path="/tmp/.tmpPngoOm"}:new{content="println!(\"${1:message}\");"}: rsnip::domain::content: close time.busy=58.4µs time.idle=10.8µs [INFO] [stderr] 2025-11-05T05:15:23.793374Z DEBUG parse{path="/tmp/.tmpPngoOm"}: rsnip::infrastructure::parsers::vcode: close time.busy=261µs time.idle=16.9µs [INFO] [stderr] 2025-11-05T05:15:23.857159Z DEBUG parse{path="/tmp/.tmp3c8mxk"}: rsnip::infrastructure::parsers::vcode: Parsing VSCode format snippets from: "/tmp/.tmp3c8mxk" [INFO] [stderr] 2025-11-05T05:15:23.857440Z DEBUG parse{path="/tmp/.tmp3c8mxk"}: rsnip::infrastructure::parsers::vcode: close time.busy=284µs time.idle=17.0µs [INFO] [stdout] test infrastructure::parsers::test_vcode::given_empty_vscode_file_when_parse_then_returns_empty_vec ... ok [INFO] [stdout] test infrastructure::test_minijinja::given_template_with_date_when_rendering_then_formats_date ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- application::test_snippet_service::given_template_snippet_when_copying_then_returns_rendered_content stdout ---- [INFO] [stdout] Error: Failed to initialize clipboard [INFO] [stdout] [INFO] [stdout] Caused by: [INFO] [stdout] Unknown error while interacting with the clipboard: X11 server connection timed out because it was unreachable [INFO] [stdout] [INFO] [stdout] Stack backtrace: [INFO] [stdout] 0: ::ext_context [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.95/src/backtrace.rs:27:14 [INFO] [stdout] 1: anyhow::context:: for core::result::Result>::context [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.95/src/context.rs:54:37 [INFO] [stdout] 2: rsnip::infrastructure::clipboard::copy_to_clipboard [INFO] [stdout] at ./src/infrastructure/clipboard.rs:7:42 [INFO] [stdout] 3: rsnip::application::snippet_service::SnippetService::copy_snippet_to_clipboard [INFO] [stdout] at ./src/application/snippet_service.rs:151:13 [INFO] [stdout] 4: test_lib::application::test_snippet_service::given_template_snippet_when_copying_then_returns_rendered_content [INFO] [stdout] at ./tests/application/test_snippet_service.rs:167:26 [INFO] [stdout] 5: test_lib::application::test_snippet_service::given_template_snippet_when_copying_then_returns_rendered_content::{{closure}} [INFO] [stdout] at ./tests/application/test_snippet_service.rs:157:75 [INFO] [stdout] 6: core::ops::function::FnOnce::call_once [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 7: core::ops::function::FnOnce::call_once [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 8: test::__rust_begin_short_backtrace [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/test/src/lib.rs:663:18 [INFO] [stdout] 9: test::run_test_in_process::{{closure}} [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/test/src/lib.rs:686:74 [INFO] [stdout] 10: as core::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 11: std::panicking::catch_unwind::do_call [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/std/src/panicking.rs:590:40 [INFO] [stdout] 12: std::panicking::catch_unwind [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/std/src/panicking.rs:553:19 [INFO] [stdout] 13: std::panic::catch_unwind [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/std/src/panic.rs:359:14 [INFO] [stdout] 14: test::run_test_in_process [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/test/src/lib.rs:686:27 [INFO] [stdout] 15: test::run_test::{{closure}} [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/test/src/lib.rs:607:43 [INFO] [stdout] 16: test::run_test::{{closure}} [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/test/src/lib.rs:637:41 [INFO] [stdout] 17: std::sys::backtrace::__rust_begin_short_backtrace [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 18: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}} [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 19: as core::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 20: std::panicking::catch_unwind::do_call [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/std/src/panicking.rs:590:40 [INFO] [stdout] 21: std::panicking::catch_unwind [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/std/src/panicking.rs:553:19 [INFO] [stdout] 22: std::panic::catch_unwind [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/std/src/panic.rs:359:14 [INFO] [stdout] 23: std::thread::Builder::spawn_unchecked_::{{closure}} [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 24: core::ops::function::FnOnce::call_once{{vtable.shim}} [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 25: as core::ops::function::FnOnce>::call_once [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 26: std::sys::thread::unix::Thread::new::thread_start [INFO] [stdout] at /rustc/f8297e351a40c1439a467bbbb6879088047f50b3/library/std/src/sys/thread/unix.rs:126:17 [INFO] [stdout] 27: [INFO] [stdout] 28: clone [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] application::test_snippet_service::given_template_snippet_when_copying_then_returns_rendered_content [INFO] [stdout] [INFO] [stdout] test result: FAILED. 34 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.41s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--test test_lib` [INFO] running `Command { std: "docker" "inspect" "6c1e04f926a778ca6d477b6067f274b88553a868b048f70a513b8e94d30896c3", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6c1e04f926a778ca6d477b6067f274b88553a868b048f70a513b8e94d30896c3", kill_on_drop: false }` [INFO] [stdout] 6c1e04f926a778ca6d477b6067f274b88553a868b048f70a513b8e94d30896c3