[INFO] fetching crate rsnip 0.6.1...
[INFO] testing rsnip-0.6.1 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-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 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "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" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded objc2-core-image v0.2.2
[INFO] [stderr]   Downloaded error-code v3.3.1
[INFO] [stderr]   Downloaded core-graphics v0.23.2
[INFO] [stderr]   Downloaded clipboard-win v5.4.0
[INFO] [stderr]   Downloaded miniz_oxide v0.8.2
[INFO] [stderr]   Downloaded anyhow v1.0.95
[INFO] [stderr]   Downloaded jpeg-decoder v0.3.1
[INFO] [stderr]   Downloaded cc v1.2.7
[INFO] [stderr]   Downloaded minijinja v2.6.0
[INFO] [stderr]   Downloaded x11rb v0.13.1
[INFO] [stderr]   Downloaded objc2-app-kit v0.2.2
[INFO] [stderr]   Downloaded objc2-metal v0.2.2
[INFO] [stderr]   Downloaded objc2 v0.5.2
[INFO] [stderr]   Downloaded yaml-rust2 v0.9.0
[INFO] [stderr]   Downloaded rayon v1.10.0
[INFO] [stderr]   Downloaded skim v0.16.0
[INFO] [stderr]   Downloaded pest v2.7.15
[INFO] [stderr]   Downloaded clap_builder v4.5.27
[INFO] [stderr]   Downloaded crossbeam-channel v0.5.14
[INFO] [stderr]   Downloaded objc2-foundation v0.2.2
[INFO] [stderr]   Downloaded winnow v0.6.22
[INFO] [stderr]   Downloaded winnow v0.7.1
[INFO] [stderr]   Downloaded serde_json v1.0.135
[INFO] [stderr]   Downloaded time v0.3.37
[INFO] [stderr]   Downloaded serde v1.0.217
[INFO] [stderr]   Downloaded image v0.25.5
[INFO] [stderr]   Downloaded libc v0.2.169
[INFO] [stderr]   Downloaded bstr v1.11.3
[INFO] [stderr]   Downloaded rustix v0.38.42
[INFO] [stderr]   Downloaded mio v1.0.3
[INFO] [stderr]   Downloaded indexmap v2.7.1
[INFO] [stderr]   Downloaded wasm-bindgen-shared v0.2.99
[INFO] [stderr]   Downloaded winsafe v0.0.19
[INFO] [stderr]   Downloaded darling_core v0.20.10
[INFO] [stderr]   Downloaded x11rb-protocol v0.13.1
[INFO] [stderr]   Downloaded syn v2.0.95
[INFO] [stderr]   Downloaded weezl v0.1.8
[INFO] [stderr]   Downloaded objc2-encode v4.0.3
[INFO] [stderr]   Downloaded arboard v3.4.1
[INFO] [stderr]   Downloaded async-trait v0.1.84
[INFO] [stderr]   Downloaded config v0.15.4
[INFO] [stderr]   Downloaded redox_syscall v0.5.8
[INFO] [stderr]   Downloaded console v0.15.10
[INFO] [stderr]   Downloaded mockall_derive v0.13.1
[INFO] [stderr]   Downloaded js-sys v0.3.76
[INFO] [stderr]   Downloaded wasm-bindgen-macro-support v0.2.99
[INFO] [stderr]   Downloaded wasm-bindgen v0.2.99
[INFO] [stderr]   Downloaded rustversion v1.0.19
[INFO] [stderr]   Downloaded which v7.0.1
[INFO] [stderr]   Downloaded foreign-types-macros v0.2.3
[INFO] [stderr]   Downloaded foreign-types-shared v0.3.1
[INFO] [stderr]   Downloaded bytemuck v1.21.0
[INFO] [stderr]   Downloaded crc32fast v1.4.2
[INFO] [stderr]   Downloaded objc-sys v0.3.5
[INFO] [stderr]   Downloaded assert_cmd v2.0.16
[INFO] [stderr]   Downloaded thiserror v2.0.9
[INFO] [stderr]   Downloaded pest_meta v2.7.15
[INFO] [stderr]   Downloaded pest_generator v2.7.15
[INFO] [stderr]   Downloaded pest_derive v2.7.15
[INFO] [stderr]   Downloaded rust-ini v0.21.1
[INFO] [stderr]   Downloaded fragile v2.0.0
[INFO] [stderr]   Downloaded clap v4.5.27
[INFO] [stderr]   Downloaded env_filter v0.1.3
[INFO] [stderr]   Downloaded shell-quote v0.7.2
[INFO] [stderr]   Downloaded wasm-bindgen-backend v0.2.99
[INFO] [stderr]   Downloaded either v1.13.0
[INFO] [stderr]   Downloaded env_home v0.1.0
[INFO] [stderr]   Downloaded tempfile v3.15.0
[INFO] [stderr]   Downloaded toml_datetime v0.6.8
[INFO] [stderr]   Downloaded objc2-core-data v0.2.2
[INFO] [stderr]   Downloaded block2 v0.5.1
[INFO] [stderr]   Downloaded gethostname v0.4.3
[INFO] [stderr]   Downloaded cpufeatures v0.2.16
[INFO] [stderr]   Downloaded crunchy v0.2.2
[INFO] [stderr]   Downloaded trim-in-place v0.1.7
[INFO] [stderr]   Downloaded shell-words v1.1.0
[INFO] [stderr]   Downloaded mockall v0.13.1
[INFO] [stderr]   Downloaded itoa v1.0.14
[INFO] [stderr]   Downloaded tiff v0.9.1
[INFO] [stderr]   Downloaded beef v0.5.2
[INFO] [stderr]   Downloaded defer-drop v1.3.0
[INFO] [stderr]   Downloaded darling_macro v0.20.10
[INFO] [stderr]   Downloaded darling v0.20.10
[INFO] [stderr]   Downloaded env_logger v0.11.6
[INFO] [stderr]   Downloaded term v0.7.0
[INFO] [stderr]   Downloaded tuikit v0.5.0
[INFO] [stderr]   Downloaded vte v0.14.1
[INFO] [stderr]   Downloaded toml_edit v0.22.23
[INFO] [stderr]   Downloaded core-graphics-types v0.1.3
[INFO] [stderr]   Downloaded foreign-types v0.5.0
[INFO] [stderr]   Downloaded flate2 v1.0.35
[INFO] [stderr]   Downloaded objc2-quartz-core v0.2.2
[INFO] [stderr]   Downloaded clap_complete v4.5.40
[INFO] [stderr]   Downloaded thiserror-impl v2.0.9
[INFO] [stderr]   Downloaded ctor v0.2.9
[INFO] [stderr]   Downloaded clap_derive v4.5.24
[INFO] [stderr]   Downloaded anstyle-wincon v3.0.6
[INFO] [stderr]   Downloaded wasm-bindgen-macro v0.2.99
[INFO] [stderr]   Downloaded timer v0.2.0
[INFO] [stderr]   Downloaded dirs-next v2.0.0
[INFO] [stderr]   Downloaded errno v0.3.10
[INFO] [stderr]   Downloaded serde_derive v1.0.217
[INFO] [stderr]   Downloaded toml v0.8.20
[INFO] [stderr]   Downloaded pin-project-lite v0.2.15
[INFO] [stderr]   Downloaded proc-macro2 v1.0.92
[INFO] [stderr]   Downloaded unicode-ident v1.0.14
[INFO] [stderr]   Downloaded once_cell v1.20.2
[INFO] [stderr]   Downloaded quote v1.0.38
[INFO] [stderr]   Downloaded log v0.4.25
[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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 5d84d1d4f491e83c44acfae5c8aed23f5a3dcedd3a7e237f37967185549715eb
[INFO] running `Command { std: "docker" "start" "-a" "5d84d1d4f491e83c44acfae5c8aed23f5a3dcedd3a7e237f37967185549715eb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "5d84d1d4f491e83c44acfae5c8aed23f5a3dcedd3a7e237f37967185549715eb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5d84d1d4f491e83c44acfae5c8aed23f5a3dcedd3a7e237f37967185549715eb", kill_on_drop: false }`
[INFO] [stdout] 5d84d1d4f491e83c44acfae5c8aed23f5a3dcedd3a7e237f37967185549715eb
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7f3b81dd16126608e8b2ae205f8ec5193763ffbf8422885fa3eacf6e3a7af715
[INFO] running `Command { std: "docker" "start" "-a" "7f3b81dd16126608e8b2ae205f8ec5193763ffbf8422885fa3eacf6e3a7af715", 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 once_cell v1.20.2
[INFO] [stderr]    Compiling serde v1.0.217
[INFO] [stderr]    Compiling memchr v2.7.4
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling strsim v0.11.1
[INFO] [stderr]    Compiling log v0.4.25
[INFO] [stderr]    Compiling anstyle v1.0.10
[INFO] [stderr]    Compiling rustix v0.38.42
[INFO] [stderr]    Compiling crunchy v0.2.2
[INFO] [stderr]    Compiling ahash v0.8.11
[INFO] [stderr]    Compiling linux-raw-sys v0.4.14
[INFO] [stderr]    Compiling tiny-keccak v2.0.2
[INFO] [stderr]    Compiling thiserror v2.0.9
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling anstyle-parse v0.2.6
[INFO] [stderr]    Compiling aho-corasick v1.1.3
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.1
[INFO] [stderr]    Compiling ucd-trie v0.1.7
[INFO] [stderr]    Compiling quote v1.0.38
[INFO] [stderr]    Compiling anstyle-query v1.1.2
[INFO] [stderr]    Compiling getrandom v0.2.15
[INFO] [stderr]    Compiling simd-adler32 v0.3.7
[INFO] [stderr]    Compiling colorchoice v1.0.3
[INFO] [stderr]    Compiling syn v2.0.95
[INFO] [stderr]    Compiling anstream v0.6.18
[INFO] [stderr]    Compiling const-random-macro v0.1.16
[INFO] [stderr]    Compiling lock_api v0.4.12
[INFO] [stderr]    Compiling hashbrown v0.15.2
[INFO] [stderr]    Compiling equivalent v1.0.1
[INFO] [stderr]    Compiling adler2 v2.0.0
[INFO] [stderr]    Compiling smallvec v1.13.2
[INFO] [stderr]    Compiling parking_lot_core v0.9.10
[INFO] [stderr]    Compiling miniz_oxide v0.8.2
[INFO] [stderr]    Compiling const-random v0.1.18
[INFO] [stderr]    Compiling indexmap v2.7.1
[INFO] [stderr]    Compiling crc32fast v1.4.2
[INFO] [stderr]    Compiling signal-hook v0.3.17
[INFO] [stderr]    Compiling rayon-core v1.12.1
[INFO] [stderr]    Compiling dirs-sys-next v0.1.2
[INFO] [stderr]    Compiling either v1.13.0
[INFO] [stderr]    Compiling predicates-core v1.0.9
[INFO] [stderr]    Compiling clap_lex v0.7.4
[INFO] [stderr]    Compiling regex-automata v0.4.9
[INFO] [stderr]    Compiling dirs-next v2.0.0
[INFO] [stderr]    Compiling clap_builder v4.5.27
[INFO] [stderr]    Compiling flate2 v1.0.35
[INFO] [stderr]    Compiling rand_core v0.6.4
[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 float-cmp v0.10.0
[INFO] [stderr]    Compiling fdeflate v0.3.7
[INFO] [stderr]    Compiling thread_local v1.1.8
[INFO] [stderr]    Compiling tracing-core v0.1.33
[INFO] [stderr]    Compiling crossbeam-channel v0.5.14
[INFO] [stderr]    Compiling serde_json v1.0.135
[INFO] [stderr]    Compiling normalize-line-endings v0.3.0
[INFO] [stderr]    Compiling iana-time-zone v0.1.61
[INFO] [stderr]    Compiling winnow v0.7.1
[INFO] [stderr]    Compiling regex-syntax v0.6.29
[INFO] [stderr]    Compiling mockall_derive v0.13.1
[INFO] [stderr]    Compiling termtree v0.5.1
[INFO] [stderr]    Compiling powerfmt v0.2.0
[INFO] [stderr]    Compiling difflib v0.4.0
[INFO] [stderr]    Compiling predicates-tree v1.0.12
[INFO] [stderr]    Compiling deranged v0.3.11
[INFO] [stderr]    Compiling png v0.17.16
[INFO] [stderr]    Compiling parking_lot v0.12.3
[INFO] [stderr]    Compiling darling_core v0.20.10
[INFO] [stderr]    Compiling regex v1.11.1
[INFO] [stderr]    Compiling bstr v1.11.3
[INFO] [stderr]    Compiling regex-automata v0.1.10
[INFO] [stderr]    Compiling env_filter v0.1.3
[INFO] [stderr]    Compiling predicates v3.1.3
[INFO] [stderr]    Compiling term v0.7.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 thiserror-impl v1.0.69
[INFO] [stderr]    Compiling tracing-attributes v0.1.28
[INFO] [stderr]    Compiling nix v0.24.3
[INFO] [stderr]    Compiling zerocopy v0.7.35
[INFO] [stderr]    Compiling darling_macro v0.20.10
[INFO] [stderr]    Compiling darling v0.20.10
[INFO] [stderr]    Compiling pest v2.7.15
[INFO] [stderr]    Compiling derive_builder_core v0.20.2
[INFO] [stderr]    Compiling mio v1.0.3
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling ppv-lite86 v0.2.20
[INFO] [stderr]    Compiling crossbeam-queue v0.3.12
[INFO] [stderr]    Compiling encoding_rs v0.8.35
[INFO] [stderr]    Compiling clap v4.5.27
[INFO] [stderr]    Compiling byteorder-lite v0.1.0
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling derive_builder_macro v0.20.2
[INFO] [stderr]    Compiling x11rb-protocol v0.13.1
[INFO] [stderr]    Compiling itoa v1.0.14
[INFO] [stderr]    Compiling trim-in-place v0.1.7
[INFO] [stderr]    Compiling pin-project-lite v0.2.15
[INFO] [stderr]    Compiling ordered-multimap v0.7.3
[INFO] [stderr]    Compiling hashlink v0.9.1
[INFO] [stderr]    Compiling time-core v0.1.2
[INFO] [stderr]    Compiling fastrand v2.3.0
[INFO] [stderr]    Compiling pest_meta v2.7.15
[INFO] [stderr]    Compiling env_home v0.1.0
[INFO] [stderr]    Compiling num-conv v0.1.0
[INFO] [stderr]    Compiling bytemuck v1.21.0
[INFO] [stderr]    Compiling overload v0.1.1
[INFO] [stderr]    Compiling arraydeque v0.5.1
[INFO] [stderr]    Compiling assert_cmd v2.0.16
[INFO] [stderr]    Compiling anyhow v1.0.95
[INFO] [stderr]    Compiling arrayvec v0.7.6
[INFO] [stderr]    Compiling humantime v2.1.0
[INFO] [stderr]    Compiling tuikit v0.5.0
[INFO] [stderr]    Compiling vte v0.14.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 pest_generator v2.7.15
[INFO] [stderr]    Compiling nu-ansi-term v0.46.0
[INFO] [stderr]    Compiling time v0.3.37
[INFO] [stderr]    Compiling rust-ini v0.21.1
[INFO] [stderr]    Compiling tracing v0.1.41
[INFO] [stderr]    Compiling dirs-sys v0.4.1
[INFO] [stderr]    Compiling pest_derive v2.7.15
[INFO] [stderr]    Compiling derive_builder v0.20.2
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling crossbeam v0.8.4
[INFO] [stderr]    Compiling signal-hook-mio v0.2.4
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling async-trait v0.1.84
[INFO] [stderr]    Compiling rayon v1.10.0
[INFO] [stderr]    Compiling matchers v0.1.0
[INFO] [stderr]    Compiling shell-quote v0.7.2
[INFO] [stderr]    Compiling console v0.15.10
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling defer-drop v1.3.0
[INFO] [stderr]    Compiling fuzzy-matcher v0.3.7
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling wait-timeout v0.2.0
[INFO] [stderr]    Compiling shlex v1.3.0
[INFO] [stderr]    Compiling downcast v0.11.0
[INFO] [stderr]    Compiling shell-words v1.1.0
[INFO] [stderr]    Compiling winnow v0.6.22
[INFO] [stderr]    Compiling zeroize v1.8.1
[INFO] [stderr]    Compiling fragile v2.0.0
[INFO] [stderr]    Compiling beef v0.5.2
[INFO] [stderr]    Compiling tracing-subscriber v0.3.19
[INFO] [stderr]    Compiling dirs v5.0.1
[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 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 x11rb v0.13.1
[INFO] [stderr]    Compiling which v7.0.1
[INFO] [stderr]    Compiling skim v0.16.0
[INFO] [stderr]    Compiling crossterm v0.28.1
[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<str> {
[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<str> {
[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 45s
[INFO] running `Command { std: "docker" "inspect" "7f3b81dd16126608e8b2ae205f8ec5193763ffbf8422885fa3eacf6e3a7af715", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7f3b81dd16126608e8b2ae205f8ec5193763ffbf8422885fa3eacf6e3a7af715", kill_on_drop: false }`
[INFO] [stdout] 7f3b81dd16126608e8b2ae205f8ec5193763ffbf8422885fa3eacf6e3a7af715
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 92bc97366a7878730c70dad4085842b4088700303f74c122f3911e880598290e
[INFO] running `Command { std: "docker" "start" "-a" "92bc97366a7878730c70dad4085842b4088700303f74c122f3911e880598290e", 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<str> {
[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<str> {
[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<str> {
[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<str> {
[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.73s
[INFO] running `Command { std: "docker" "inspect" "92bc97366a7878730c70dad4085842b4088700303f74c122f3911e880598290e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "92bc97366a7878730c70dad4085842b4088700303f74c122f3911e880598290e", kill_on_drop: false }`
[INFO] [stdout] 92bc97366a7878730c70dad4085842b4088700303f74c122f3911e880598290e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] e062d761d0c7bdeeab3ae92e4816da68b855b650fcccf9986227bd72463170e7
[INFO] running `Command { std: "docker" "start" "-a" "e062d761d0c7bdeeab3ae92e4816da68b855b650fcccf9986227bd72463170e7", 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<str> {
[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<str> {
[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.33s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rsnip-7ed6233ec09cb236)
[INFO] [stderr] 2026-04-19T23:26:54.010506Z  INFO rsnip::util::testing: Test Setup complete
[INFO] [stderr] 2026-04-19T23:26:54.010630Z DEBUG rsnip::util::testing: INIT: Attempting logger init from testing.rs
[INFO] [stderr] 2026-04-19T23:26:54.010847Z DEBUG rsnip::util::testing: Tracing subscriber already set
[INFO] [stderr] 2026-04-19T23:26:54.010921Z  INFO rsnip::util::testing: Test Setup complete
[INFO] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stderr] 2026-04-19T23:26:54.012540Z DEBUG find_snippet_line_number{content="some content\n--- test\ncontent\n---" snippet_name="nonexistent"}: rsnip::infrastructure::edit_snippets: close time.busy=16.6µs time.idle=15.4µs
[INFO] [stderr] 2026-04-19T23:26:54.013299Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.38µs time.idle=10.8µs
[INFO] [stdout] test cli::args::tests::given_generate_config_flag_when_parsing_then_sets_flag ... ok
[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] [stderr] 2026-04-19T23:26:54.012722Z DEBUG find_snippet_line_number{content="some content\n--- test\ncontent\n---" snippet_name="test"}: rsnip::infrastructure::edit_snippets: close time.busy=9.90µs time.idle=10.4µs
[INFO] [stdout] test config::tests::given_concrete_type_when_getting_snippet_type_then_returns_correct_type ... ok
[INFO] [stdout] test config::tests::given_concrete_type_when_getting_sources_then_returns_none ... ok
[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_absolute_path_when_expanding_then_returns_unchanged ... ok
[INFO] [stderr] 2026-04-19T23:26:54.014587Z DEBUG create{format=Scls}: rsnip::infrastructure::parsers: close time.busy=6.51µs time.idle=10.3µs
[INFO] [stdout] test util::path_utils::tests::given_invalid_tilde_path_when_expanding_then_returns_error ... ok
[INFO] [stderr] 2026-04-19T23:26:54.014907Z DEBUG parse{path="dummy"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "dummy"
[INFO] [stdout] test infrastructure::edit_snippets::tests::given_valid_content_when_finding_snippet_then_returns_correct_line ... ok
[INFO] [stderr] 2026-04-19T23:26:54.014990Z DEBUG parse{path="dummy"}: rsnip::infrastructure::parsers::default: close time.busy=82.6µs time.idle=10.0µs
[INFO] [stdout] test util::path_utils::tests::given_path_with_tilde_when_expanding_then_replaces_with_home ... ok
[INFO] [stderr] 2026-04-19T23:26:54.015050Z DEBUG parse{path="dummy"}: rsnip::infrastructure::parsers::scls: Parsing SCLS format snippets from: "dummy"
[INFO] [stderr] 2026-04-19T23:26:54.014773Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=5.23µs time.idle=9.28µs
[INFO] [stderr] 2026-04-19T23:26:54.015500Z DEBUG parse{path="/tmp/.tmpGdiAma"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpGdiAma"
[INFO] [stderr] 2026-04-19T23:26:54.015838Z TRACE parse{path="/tmp/.tmpGdiAma"}:new{content="content"}: rsnip::domain::content: SnippetContent::new called with: content
[INFO] [stderr] 2026-04-19T23:26:54.015704Z DEBUG create{format=Scls}: rsnip::infrastructure::parsers: close time.busy=6.31µs time.idle=13.2µs
[INFO] [stderr] 2026-04-19T23:26:54.016298Z DEBUG parse{path="/tmp/.tmpfK4HKu"}: rsnip::infrastructure::parsers::scls: Parsing SCLS format snippets from: "/tmp/.tmpfK4HKu"
[INFO] [stderr] 2026-04-19T23:26:54.015095Z DEBUG parse{path="dummy"}: rsnip::infrastructure::parsers::scls: close time.busy=45.8µs time.idle=8.63µs
[INFO] [stderr] 2026-04-19T23:26:54.016548Z TRACE parse{path="/tmp/.tmpGdiAma"}:new{content="content"}: rsnip::domain::content: Detected as static
[INFO] [stderr] 2026-04-19T23:26:54.016591Z DEBUG parse{path="/tmp/.tmpGdiAma"}:new{content="content"}: rsnip::domain::content: close time.busy=757µs time.idle=9.21µs
[INFO] [stderr] 2026-04-19T23:26:54.016634Z DEBUG parse{path="/tmp/.tmpGdiAma"}: rsnip::infrastructure::parsers::default: close time.busy=1.13ms time.idle=13.7µs
[INFO] [stdout] test infrastructure::parsers::tests::given_format_when_creating_parser_then_returns_correct_implementation ... ok
[INFO] [stdout] test infrastructure::parsers::tests::given_default_format_file_when_parsing_then_succeeds ... ok
[INFO] [stderr] 2026-04-19T23:26:54.020117Z TRACE parse{path="/tmp/.tmpfK4HKu"}:new{content="content"}: rsnip::domain::content: SnippetContent::new called with: content
[INFO] [stdout] test infrastructure::parsers::tests::given_scls_format_file_when_parsing_then_succeeds ... ok
[INFO] [stderr] 2026-04-19T23:26:54.020188Z TRACE parse{path="/tmp/.tmpfK4HKu"}:new{content="content"}: rsnip::domain::content: Detected as static
[INFO] [stderr] 2026-04-19T23:26:54.020224Z DEBUG parse{path="/tmp/.tmpfK4HKu"}:new{content="content"}: rsnip::domain::content: close time.busy=107µs time.idle=10.0µs
[INFO] [stderr] 2026-04-19T23:26:54.020269Z DEBUG parse{path="/tmp/.tmpfK4HKu"}: rsnip::infrastructure::parsers::scls: close time.busy=3.97ms time.idle=10.1µs
[INFO] [stdout] test util::path_utils::tests::given_relative_path_when_expanding_then_returns_unchanged ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.20s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/rsnip-03ef8c23b5f86dfe)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[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] [stderr]      Running tests/test_commands.rs (/opt/rustwide/target/debug/deps/test_commands-297253b8522999e7)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/test_fuzzy.rs (/opt/rustwide/target/debug/deps/test_fuzzy-96c7e4e64ad57fba)
[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 given_exact_match_when_fuzzy_finder_then_returns_immediately ... ok
[INFO] [stdout] test given_empty_items_when_fuzzy_finder_then_returns_none ... ok
[INFO] [stdout] test test_fuzzy_finder_output_is_clean ... ignored, Interactive test requires terminal
[INFO] [stdout] test given_single_partial_match_when_fuzzy_finder_then_auto_selects ... ok
[INFO] [stdout] test given_snippets_when_creating_skim_items_then_returns_formatted_items ... 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-dd9753d2ec96498a)
[INFO] [stderr] 2026-04-19T23:26:54.278364Z  INFO rsnip::util::testing: Test Setup complete
[INFO] [stdout] 
[INFO] [stdout] running 35 tests
[INFO] [stderr] 2026-04-19T23:26:54.281212Z DEBUG find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}: rsnip::application::snippet_service: Loading snippets from /tmp/.tmpm0EcMh
[INFO] [stdout] test application::test_snippet_service::given_empty_input_when_finding_completion_then_returns_none ... ok
[INFO] [stderr] 2026-04-19T23:26:54.281340Z DEBUG find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}:create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.68µs time.idle=9.63µs
[INFO] [stderr] 2026-04-19T23:26:54.281605Z DEBUG find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpm0EcMh"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpm0EcMh"
[INFO] [stderr] 2026-04-19T23:26:54.281794Z TRACE find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpm0EcMh"}:new{content="Content"}: rsnip::domain::content: SnippetContent::new called with: Content
[INFO] [stderr] 2026-04-19T23:26:54.281904Z TRACE find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpm0EcMh"}:new{content="Content"}: rsnip::domain::content: Detected as static
[INFO] [stderr] 2026-04-19T23:26:54.282057Z DEBUG find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpm0EcMh"}:new{content="Content"}: rsnip::domain::content: close time.busy=270µs time.idle=10.4µs
[INFO] [stderr] 2026-04-19T23:26:54.282191Z DEBUG find_completion_exact{completion_type="test" user_input=""}: rsnip::application::snippet_service: close time.busy=6.78µs time.idle=11.9µs
[INFO] [stderr] 2026-04-19T23:26:54.282209Z TRACE find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpm0EcMh"}:new{content="Content2"}: rsnip::domain::content: SnippetContent::new called with: Content2
[INFO] [stderr] 2026-04-19T23:26:54.282267Z DEBUG find_completion_fuzzy{completion_type="test" user_input=""}: rsnip::application::snippet_service: close time.busy=6.19µs time.idle=9.20µs
[INFO] [stderr] 2026-04-19T23:26:54.282274Z TRACE find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpm0EcMh"}:new{content="Content2"}: rsnip::domain::content: Detected as static
[INFO] [stderr] 2026-04-19T23:26:54.282311Z DEBUG find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpm0EcMh"}:new{content="Content2"}: rsnip::domain::content: close time.busy=102µs time.idle=8.14µs
[INFO] [stderr] 2026-04-19T23:26:54.282351Z DEBUG find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpm0EcMh"}: rsnip::infrastructure::parsers::default: close time.busy=748µs time.idle=9.02µs
[INFO] [stderr] 2026-04-19T23:26:54.282391Z DEBUG find_completion_fuzzy{completion_type="test" user_input="tst"}:get_snippets{snippet_type="test"}: rsnip::application::snippet_service: close time.busy=1.19ms time.idle=7.39µs
[INFO] [stderr] 2026-04-19T23:26:54.282503Z DEBUG find_completion_fuzzy{completion_type="test" user_input="tst"}: rsnip::application::snippet_service: close time.busy=1.32ms time.idle=15.7µs
[INFO] [stderr] 2026-04-19T23:26:54.284429Z DEBUG get_snippets{snippet_type="combined"}: rsnip::application::snippet_service: Loading combined snippets from sources: ["source1", "source2"]
[INFO] [stderr] 2026-04-19T23:26:54.284499Z DEBUG get_snippets{snippet_type="combined"}: rsnip::application::snippet_service: Loading snippets from /tmp/.tmpLtOOGW
[INFO] [stderr] 2026-04-19T23:26:54.284565Z DEBUG get_snippets{snippet_type="combined"}:create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.49µs time.idle=9.25µs
[INFO] [stderr] 2026-04-19T23:26:54.284623Z DEBUG get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmpLtOOGW"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpLtOOGW"
[INFO] [stderr] 2026-04-19T23:26:54.284696Z TRACE get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmpLtOOGW"}:new{content="Content1"}: rsnip::domain::content: SnippetContent::new called with: Content1
[INFO] [stderr] 2026-04-19T23:26:54.284730Z TRACE get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmpLtOOGW"}:new{content="Content1"}: rsnip::domain::content: Detected as static
[INFO] [stderr] 2026-04-19T23:26:54.284765Z DEBUG get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmpLtOOGW"}:new{content="Content1"}: rsnip::domain::content: close time.busy=66.8µs time.idle=8.49µs
[INFO] [stderr] 2026-04-19T23:26:54.284813Z DEBUG get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmpLtOOGW"}: rsnip::infrastructure::parsers::default: close time.busy=189µs time.idle=7.97µs
[INFO] [stderr] 2026-04-19T23:26:54.284853Z DEBUG get_snippets{snippet_type="combined"}: rsnip::application::snippet_service: Loading snippets from /tmp/.tmpZRjDGB
[INFO] [stderr] 2026-04-19T23:26:54.284924Z DEBUG get_snippets{snippet_type="combined"}:create{format=Default}: rsnip::infrastructure::parsers: close time.busy=4.97µs time.idle=8.34µs
[INFO] [stderr] 2026-04-19T23:26:54.284979Z DEBUG get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmpZRjDGB"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpZRjDGB"
[INFO] [stderr] 2026-04-19T23:26:54.285063Z TRACE get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmpZRjDGB"}:new{content="Content2"}: rsnip::domain::content: SnippetContent::new called with: Content2
[INFO] [stderr] 2026-04-19T23:26:54.285101Z TRACE get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmpZRjDGB"}:new{content="Content2"}: rsnip::domain::content: Detected as static
[INFO] [stderr] 2026-04-19T23:26:54.285136Z DEBUG get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmpZRjDGB"}:new{content="Content2"}: rsnip::domain::content: close time.busy=71.3µs time.idle=9.09µs
[INFO] [stderr] 2026-04-19T23:26:54.285187Z DEBUG get_snippets{snippet_type="combined"}:parse{path="/tmp/.tmpZRjDGB"}: rsnip::infrastructure::parsers::default: close time.busy=206µs time.idle=7.59µs
[INFO] [stderr] 2026-04-19T23:26:54.285233Z DEBUG get_snippets{snippet_type="combined"}: rsnip::application::snippet_service: close time.busy=813µs time.idle=9.94µs
[INFO] [stdout] test application::test_snippet_service::given_fuzzy_match_when_finding_completion_then_returns_best_match ... ok
[INFO] [stderr] 2026-04-19T23:26:54.285459Z DEBUG get_snippets{snippet_type="test"}: rsnip::application::snippet_service: Loading snippets from /tmp/.tmp8Pvcq9
[INFO] [stdout] test infrastructure::parsers::test_default::given_empty_file_when_parse_then_returns_empty_vec ... ok
[INFO] [stderr] 2026-04-19T23:26:54.285608Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.52µs time.idle=12.3µs
[INFO] [stdout] test application::test_snippet_service::given_valid_snippet_file_when_getting_snippets_then_returns_snippets ... ok
[INFO] [stderr] 2026-04-19T23:26:54.285735Z DEBUG parse{path="/tmp/.tmprIOriO"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmprIOriO"
[INFO] [stderr] 2026-04-19T23:26:54.285789Z DEBUG parse{path="/tmp/.tmprIOriO"}: rsnip::infrastructure::parsers::default: close time.busy=53.3µs time.idle=9.40µs
[INFO] [stderr] 2026-04-19T23:26:54.286015Z DEBUG get_snippets{snippet_type="test"}:create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.74µs time.idle=10.9µs
[INFO] [stdout] test infrastructure::parsers::test_default::given_extra_text_outside_snippets_when_parse_then_ignores_it ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286074Z DEBUG get_snippets{snippet_type="test"}:parse{path="/tmp/.tmp8Pvcq9"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmp8Pvcq9"
[INFO] [stdout] test application::test_snippet_service::given_combined_type_when_getting_snippets_then_returns_all_snippets ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286156Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=5.71µs time.idle=10.8µs
[INFO] [stdout] test application::test_snippet_service::given_exact_match_when_finding_completion_then_returns_snippet ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286152Z TRACE get_snippets{snippet_type="test"}:parse{path="/tmp/.tmp8Pvcq9"}:new{content="Content"}: rsnip::domain::content: SnippetContent::new called with: Content
[INFO] [stdout] test infrastructure::parsers::test_default::given_malformed_snippet_with_extra_start_when_parse_then_returns_error ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286185Z TRACE get_snippets{snippet_type="test"}:parse{path="/tmp/.tmp8Pvcq9"}:new{content="Content"}: rsnip::domain::content: Detected as static
[INFO] [stdout] test infrastructure::parsers::test_default::given_empty_snippet_when_parse_then_returns_error ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286201Z DEBUG parse{path="/tmp/.tmp7DwxaK"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmp7DwxaK"
[INFO] [stdout] test infrastructure::parsers::test_default::given_nonexistent_file_when_parse_then_returns_error ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286190Z DEBUG find_completion_exact{completion_type="test" user_input="test"}:get_snippets{snippet_type="test"}: rsnip::application::snippet_service: Loading snippets from /tmp/.tmpy76fgQ
[INFO] [stdout] test infrastructure::parsers::test_default::given_snippet_with_comments_when_parse_then_preserves_comments ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286218Z DEBUG get_snippets{snippet_type="test"}:parse{path="/tmp/.tmp8Pvcq9"}:new{content="Content"}: rsnip::domain::content: close time.busy=65.6µs time.idle=8.32µs
[INFO] [stdout] test infrastructure::parsers::test_default::given_missing_trailing_delimiter_when_parse_then_returns_error ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286261Z DEBUG find_completion_exact{completion_type="test" user_input="test"}:get_snippets{snippet_type="test"}:create{format=Default}: rsnip::infrastructure::parsers: close time.busy=12.4µs time.idle=8.11µs
[INFO] [stdout] test infrastructure::parsers::test_default::given_valid_snippet_file_when_parse_then_returns_correct_snippets ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286273Z TRACE parse{path="/tmp/.tmp7DwxaK"}:new{content="line"}: rsnip::domain::content: SnippetContent::new called with: line
[INFO] [stdout] test infrastructure::parsers::test_scls::given_malformed_toml_when_parse_then_returns_error ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286274Z DEBUG get_snippets{snippet_type="test"}:parse{path="/tmp/.tmp8Pvcq9"}: rsnip::infrastructure::parsers::default: close time.busy=199µs time.idle=8.86µs
[INFO] [stdout] test infrastructure::parsers::test_scls::given_empty_scls_file_when_parse_then_returns_empty_vec ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286297Z TRACE parse{path="/tmp/.tmp7DwxaK"}:new{content="line"}: rsnip::domain::content: Detected as static
[INFO] [stdout] test infrastructure::parsers::test_scls::given_nonexistent_file_when_parse_then_returns_error ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286309Z DEBUG get_snippets{snippet_type="test"}: rsnip::application::snippet_service: close time.busy=857µs time.idle=10.4µs
[INFO] [stdout] test application::test_snippet_service::given_nonexistent_snippet_when_copying_then_returns_none ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286310Z DEBUG find_completion_exact{completion_type="test" user_input="test"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpy76fgQ"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpy76fgQ"
[INFO] [stdout] test infrastructure::parsers::test_default::given_snippet_with_closing_without_opening_when_parse_then_returns_error ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286324Z DEBUG parse{path="/tmp/.tmp7DwxaK"}:new{content="line"}: rsnip::domain::content: close time.busy=50.7µs time.idle=8.43µs
[INFO] [stdout] test infrastructure::parsers::test_vcode::given_malformed_json_when_parse_then_returns_error ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286362Z DEBUG parse{path="/tmp/.tmp7DwxaK"}: rsnip::infrastructure::parsers::default: close time.busy=160µs time.idle=7.68µs
[INFO] [stdout] test infrastructure::parsers::test_scls::given_multiline_array_body_when_parse_then_handles_correctly ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286382Z TRACE find_completion_exact{completion_type="test" user_input="test"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpy76fgQ"}:new{content="Content"}: rsnip::domain::content: SnippetContent::new called with: Content
[INFO] [stdout] test infrastructure::parsers::test_vcode::given_single_line_body_when_parse_then_handles_correctly ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286416Z TRACE find_completion_exact{completion_type="test" user_input="test"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpy76fgQ"}:new{content="Content"}: rsnip::domain::content: Detected as static
[INFO] [stdout] test infrastructure::test_minijinja::given_static_content_when_rendering_then_returns_unchanged ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286453Z DEBUG find_completion_exact{completion_type="test" user_input="test"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpy76fgQ"}:new{content="Content"}: rsnip::domain::content: close time.busy=65.5µs time.idle=8.28µs
[INFO] [stdout] test infrastructure::parsers::test_vcode::given_empty_vscode_file_when_parse_then_returns_empty_vec ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286464Z 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/.tmpvcAUHT
[INFO] [stdout] test infrastructure::parsers::test_scls::given_valid_scls_file_when_parse_then_returns_snippets ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286498Z DEBUG find_completion_exact{completion_type="test" user_input="test"}:get_snippets{snippet_type="test"}:parse{path="/tmp/.tmpy76fgQ"}: rsnip::infrastructure::parsers::default: close time.busy=188µs time.idle=6.63µs
[INFO] [stdout] test infrastructure::parsers::test_vcode::given_valid_vscode_file_when_parse_then_returns_snippets ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286531Z DEBUG find_completion_exact{completion_type="test" user_input="test"}:get_snippets{snippet_type="test"}: rsnip::application::snippet_service: close time.busy=351µs time.idle=6.84µs
[INFO] [stderr] 2026-04-19T23:26:54.286558Z DEBUG find_completion_exact{completion_type="test" user_input="test"}: rsnip::application::snippet_service: close time.busy=395µs time.idle=10.8µs
[INFO] [stderr] 2026-04-19T23:26:54.286542Z 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=8.26µs time.idle=8.87µs
[INFO] [stderr] 2026-04-19T23:26:54.286616Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.52µs time.idle=11.4µs
[INFO] [stderr] 2026-04-19T23:26:54.286618Z 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/.tmpvcAUHT"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpvcAUHT"
[INFO] [stdout] test infrastructure::test_minijinja::given_template_with_add_days_when_rendering_then_calculates_correctly ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286668Z DEBUG parse{path="/tmp/.tmpCkTTqH"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpCkTTqH"
[INFO] [stdout] test infrastructure::parsers::test_scls::given_snippet_with_named_placeholders_when_parse_then_converts_correctly ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286719Z 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/.tmpvcAUHT"}:new{content="A red fruit"}: rsnip::domain::content: SnippetContent::new called with: A red fruit
[INFO] [stdout] test infrastructure::test_minijinja::given_template_with_env_var_when_rendering_then_substitutes_value ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286753Z TRACE parse{path="/tmp/.tmpCkTTqH"}:new{content="content"}: rsnip::domain::content: SnippetContent::new called with: content
[INFO] [stdout] test infrastructure::test_minijinja::given_template_with_date_arithmetic_when_rendering_then_calculates_correctly ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286770Z 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/.tmpvcAUHT"}:new{content="A red fruit"}: rsnip::domain::content: Detected as static
[INFO] [stdout] test infrastructure::test_minijinja::given_template_with_dangerous_shell_commands_then_returns_error ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286783Z TRACE parse{path="/tmp/.tmpCkTTqH"}:new{content="content"}: rsnip::domain::content: Detected as static
[INFO] [stderr] 2026-04-19T23:26:54.286826Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.67µs time.idle=11.1µs
[INFO] [stderr] 2026-04-19T23:26:54.286831Z 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/.tmpvcAUHT"}:new{content="A red fruit"}: rsnip::domain::content: close time.busy=111µs time.idle=10.5µs
[INFO] [stderr] 2026-04-19T23:26:54.286866Z DEBUG parse{path="/tmp/.tmpCkTTqH"}:new{content="content"}: rsnip::domain::content: close time.busy=112µs time.idle=9.62µs
[INFO] [stderr] 2026-04-19T23:26:54.286905Z DEBUG parse{path="/tmp/.tmpDwQ5YW"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpDwQ5YW"
[INFO] [stderr] 2026-04-19T23:26:54.287027Z DEBUG parse{path="/tmp/.tmpCkTTqH"}: rsnip::infrastructure::parsers::default: close time.busy=356µs time.idle=9.38µs
[INFO] [stderr] 2026-04-19T23:26:54.290411Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.68µs time.idle=12.6µs
[INFO] [stderr] 2026-04-19T23:26:54.290485Z DEBUG parse{path="/tmp/.tmppDqCAd"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmppDqCAd"
[INFO] [stderr] 2026-04-19T23:26:54.290609Z DEBUG parse{path="/tmp/.tmppDqCAd"}: rsnip::infrastructure::parsers::default: close time.busy=121µs time.idle=9.79µs
[INFO] [stderr] 2026-04-19T23:26:54.291038Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=7.19µs time.idle=11.7µs
[INFO] [stderr] 2026-04-19T23:26:54.291104Z DEBUG parse{path="/tmp/.tmpK1kU7S"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpK1kU7S"
[INFO] [stderr] 2026-04-19T23:26:54.291439Z TRACE parse{path="/tmp/.tmpK1kU7S"}:new{content="content"}: rsnip::domain::content: SnippetContent::new called with: content
[INFO] [stderr] 2026-04-19T23:26:54.291480Z TRACE parse{path="/tmp/.tmpK1kU7S"}:new{content="content"}: rsnip::domain::content: Detected as static
[INFO] [stderr] 2026-04-19T23:26:54.291511Z DEBUG parse{path="/tmp/.tmpK1kU7S"}:new{content="content"}: rsnip::domain::content: close time.busy=72.4µs time.idle=10.5µs
[INFO] [stderr] 2026-04-19T23:26:54.291588Z DEBUG parse{path="/tmp/.tmpK1kU7S"}: rsnip::infrastructure::parsers::default: close time.busy=482µs time.idle=8.69µs
[INFO] [stderr] 2026-04-19T23:26:54.292069Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.62µs time.idle=11.6µs
[INFO] [stderr] 2026-04-19T23:26:54.292132Z DEBUG parse{path="/tmp/.tmp9GLiGW"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmp9GLiGW"
[INFO] [stderr] 2026-04-19T23:26:54.292228Z DEBUG parse{path="/tmp/.tmp9GLiGW"}: rsnip::infrastructure::parsers::default: close time.busy=93.1µs time.idle=9.72µs
[INFO] [stderr] 2026-04-19T23:26:54.292345Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.44µs time.idle=10.6µs
[INFO] [stderr] 2026-04-19T23:26:54.292454Z DEBUG parse{path="/nonexistent/file"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/nonexistent/file"
[INFO] [stderr] 2026-04-19T23:26:54.292538Z DEBUG parse{path="/nonexistent/file"}: rsnip::infrastructure::parsers::default: close time.busy=81.6µs time.idle=11.2µs
[INFO] [stderr] 2026-04-19T23:26:54.292662Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=6.52µs time.idle=11.4µs
[INFO] [stderr] 2026-04-19T23:26:54.292719Z DEBUG parse{path="/tmp/.tmpqqOPFl"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpqqOPFl"
[INFO] [stderr] 2026-04-19T23:26:54.292748Z DEBUG create{format=Default}: rsnip::infrastructure::parsers: close time.busy=5.65µs time.idle=9.15µs
[INFO] [stderr] 2026-04-19T23:26:54.292795Z DEBUG parse{path="/tmp/.tmp7hRKWY"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmp7hRKWY"
[INFO] [stderr] 2026-04-19T23:26:54.292795Z TRACE parse{path="/tmp/.tmpqqOPFl"}: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] 2026-04-19T23:26:54.292826Z TRACE parse{path="/tmp/.tmpqqOPFl"}:new{content="this is green\nand nothing else"}: rsnip::domain::content: Detected as static
[INFO] [stderr] 2026-04-19T23:26:54.292858Z DEBUG parse{path="/tmp/.tmpqqOPFl"}:new{content="this is green\nand nothing else"}: rsnip::domain::content: close time.busy=61.4µs time.idle=8.29µs
[INFO] [stderr] 2026-04-19T23:26:54.292872Z TRACE parse{path="/tmp/.tmp7hRKWY"}: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] 2026-04-19T23:26:54.292915Z TRACE parse{path="/tmp/.tmp7hRKWY"}:new{content="this is green\nand nothing else"}: rsnip::domain::content: Detected as static
[INFO] [stderr] 2026-04-19T23:26:54.292934Z TRACE parse{path="/tmp/.tmpqqOPFl"}:new{content="this is green2"}: rsnip::domain::content: SnippetContent::new called with: this is green2
[INFO] [stderr] 2026-04-19T23:26:54.292945Z DEBUG parse{path="/tmp/.tmp7hRKWY"}:new{content="this is green\nand nothing else"}: rsnip::domain::content: close time.busy=70.8µs time.idle=8.43µs
[INFO] [stderr] 2026-04-19T23:26:54.292956Z TRACE parse{path="/tmp/.tmpqqOPFl"}:new{content="this is green2"}: rsnip::domain::content: Detected as static
[INFO] [stderr] 2026-04-19T23:26:54.292983Z DEBUG parse{path="/tmp/.tmp7hRKWY"}: rsnip::infrastructure::parsers::default: close time.busy=187µs time.idle=7.88µs
[INFO] [stderr] 2026-04-19T23:26:54.292984Z DEBUG parse{path="/tmp/.tmpqqOPFl"}:new{content="this is green2"}: rsnip::domain::content: close time.busy=49.5µs time.idle=7.86µs
[INFO] [stderr] 2026-04-19T23:26:54.293062Z TRACE parse{path="/tmp/.tmpqqOPFl"}:new{content="this is yellow"}: rsnip::domain::content: SnippetContent::new called with: this is yellow
[INFO] [stderr] 2026-04-19T23:26:54.293096Z TRACE parse{path="/tmp/.tmpqqOPFl"}:new{content="this is yellow"}: rsnip::domain::content: Detected as static
[INFO] [stderr] 2026-04-19T23:26:54.293130Z DEBUG parse{path="/tmp/.tmpqqOPFl"}:new{content="this is yellow"}: rsnip::domain::content: close time.busy=66.5µs time.idle=9.83µs
[INFO] [stderr] 2026-04-19T23:26:54.293191Z TRACE parse{path="/tmp/.tmpqqOPFl"}:new{content="this is other"}: rsnip::domain::content: SnippetContent::new called with: this is other
[INFO] [stderr] 2026-04-19T23:26:54.293214Z TRACE parse{path="/tmp/.tmpqqOPFl"}:new{content="this is other"}: rsnip::domain::content: Detected as static
[INFO] [stderr] 2026-04-19T23:26:54.293236Z DEBUG parse{path="/tmp/.tmpqqOPFl"}:new{content="this is other"}: rsnip::domain::content: close time.busy=46.1µs time.idle=8.83µs
[INFO] [stderr] 2026-04-19T23:26:54.293286Z DEBUG parse{path="/tmp/.tmpqqOPFl"}: rsnip::infrastructure::parsers::default: close time.busy=566µs time.idle=9.40µs
[INFO] [stderr] 2026-04-19T23:26:54.293313Z DEBUG create{format=Scls}: rsnip::infrastructure::parsers: close time.busy=6.69µs time.idle=10.9µs
[INFO] [stderr] 2026-04-19T23:26:54.293396Z DEBUG parse{path="/tmp/.tmp9WjHhb"}: rsnip::infrastructure::parsers::scls: Parsing SCLS format snippets from: "/tmp/.tmp9WjHhb"
[INFO] [stderr] 2026-04-19T23:26:54.293565Z DEBUG create{format=Scls}: rsnip::infrastructure::parsers: close time.busy=6.04µs time.idle=10.8µs
[INFO] [stderr] 2026-04-19T23:26:54.293617Z DEBUG parse{path="/tmp/.tmpmh3aGr"}: rsnip::infrastructure::parsers::scls: Parsing SCLS format snippets from: "/tmp/.tmpmh3aGr"
[INFO] [stderr] 2026-04-19T23:26:54.293657Z DEBUG create{format=Scls}: rsnip::infrastructure::parsers: close time.busy=6.65µs time.idle=11.5µs
[INFO] [stdout] test infrastructure::test_minijinja::given_template_with_date_when_rendering_then_formats_date ... ok
[INFO] [stderr] 2026-04-19T23:26:54.293711Z DEBUG parse{path="/tmp/.tmpNbw1wf"}: rsnip::infrastructure::parsers::scls: Parsing SCLS format snippets from: "/tmp/.tmpNbw1wf"
[INFO] [stdout] test infrastructure::test_minijinja::given_template_with_invalid_shell_command_when_rendering_then_returns_error ... ok
[INFO] [stderr] 2026-04-19T23:26:54.293841Z DEBUG parse{path="/tmp/.tmpNbw1wf"}: rsnip::infrastructure::parsers::scls: close time.busy=125µs time.idle=10.2µs
[INFO] [stdout] test infrastructure::test_minijinja::given_template_with_shell_command_when_rendering_then_executes ... ok
[INFO] [stderr] 2026-04-19T23:26:54.286902Z 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/.tmpvcAUHT"}: rsnip::infrastructure::parsers::default: close time.busy=283µs time.idle=8.10µs
[INFO] [stderr] 2026-04-19T23:26:54.294059Z DEBUG parse{path="/tmp/.tmp9WjHhb"}: rsnip::infrastructure::parsers::scls: close time.busy=669µs time.idle=10.5µs
[INFO] [stderr] 2026-04-19T23:26:54.294102Z 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=7.65ms time.idle=8.15µs
[INFO] [stderr] 2026-04-19T23:26:54.294151Z 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=7.72ms time.idle=7.40µs
[INFO] [stderr] 2026-04-19T23:26:54.294150Z DEBUG create{format=Scls}: rsnip::infrastructure::parsers: close time.busy=6.49µs time.idle=11.2µs
[INFO] [stderr] 2026-04-19T23:26:54.294185Z DEBUG copy_snippet_to_clipboard{completion_type="test" input="nonexistent" exact=true}: rsnip::application::snippet_service: close time.busy=7.77ms time.idle=15.5µs
[INFO] [stderr] 2026-04-19T23:26:54.294204Z DEBUG parse{path="/nonexistent/file"}: rsnip::infrastructure::parsers::scls: Parsing SCLS format snippets from: "/nonexistent/file"
[INFO] [stderr] 2026-04-19T23:26:54.294261Z DEBUG parse{path="/nonexistent/file"}: rsnip::infrastructure::parsers::scls: close time.busy=48.5µs time.idle=16.3µs
[INFO] [stderr] 2026-04-19T23:26:54.294633Z DEBUG parse{path="/tmp/.tmpLAeK6L"}: rsnip::infrastructure::parsers::vcode: Parsing VSCode format snippets from: "/tmp/.tmpLAeK6L"
[INFO] [stderr] 2026-04-19T23:26:54.294688Z DEBUG create{format=Scls}: rsnip::infrastructure::parsers: close time.busy=6.17µs time.idle=10.6µs
[INFO] [stderr] 2026-04-19T23:26:54.294730Z DEBUG parse{path="/tmp/.tmpLAeK6L"}: rsnip::infrastructure::parsers::vcode: close time.busy=97.8µs time.idle=10.0µs
[INFO] [stderr] 2026-04-19T23:26:54.294740Z DEBUG parse{path="/tmp/.tmpCkTAsS"}: rsnip::infrastructure::parsers::scls: Parsing SCLS format snippets from: "/tmp/.tmpCkTAsS"
[INFO] [stderr] 2026-04-19T23:26:54.295324Z 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/.tmpNwKRq2
[INFO] [stderr] 2026-04-19T23:26:54.295378Z 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=5.06µs time.idle=7.16µs
[INFO] [stderr] 2026-04-19T23:26:54.295428Z 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/.tmpNwKRq2"}: rsnip::infrastructure::parsers::default: Parsing default format snippets from: "/tmp/.tmpNwKRq2"
[INFO] [stderr] 2026-04-19T23:26:54.295504Z 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/.tmpNwKRq2"}:new{content="{{current_date|strftime('%Y-%m-%d')}}"}: rsnip::domain::content: SnippetContent::new called with: {{current_date|strftime('%Y-%m-%d')}}
[INFO] [stderr] 2026-04-19T23:26:54.295550Z 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/.tmpNwKRq2"}:new{content="{{current_date|strftime('%Y-%m-%d')}}"}: rsnip::domain::content: Detected as template
[INFO] [stderr] 2026-04-19T23:26:54.295585Z 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/.tmpNwKRq2"}:new{content="{{current_date|strftime('%Y-%m-%d')}}"}: rsnip::domain::content: close time.busy=80.8µs time.idle=8.25µs
[INFO] [stderr] 2026-04-19T23:26:54.295631Z 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/.tmpNwKRq2"}: rsnip::infrastructure::parsers::default: close time.busy=202µs time.idle=6.58µs
[INFO] [stderr] 2026-04-19T23:26:54.295670Z 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=355µs time.idle=6.05µs
[INFO] [stderr] 2026-04-19T23:26:54.295702Z 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=403µs time.idle=6.24µs
[INFO] [stderr] 2026-04-19T23:26:54.296041Z DEBUG parse{path="/tmp/.tmpL0ATmj"}: rsnip::infrastructure::parsers::vcode: Parsing VSCode format snippets from: "/tmp/.tmpL0ATmj"
[INFO] [stderr] 2026-04-19T23:26:54.296153Z TRACE parse{path="/tmp/.tmpL0ATmj"}:new{content="println!(\"${1:message}\");"}: rsnip::domain::content: SnippetContent::new called with: println!("${1:message}");
[INFO] [stderr] 2026-04-19T23:26:54.296186Z TRACE parse{path="/tmp/.tmpL0ATmj"}:new{content="println!(\"${1:message}\");"}: rsnip::domain::content: Detected as static
[INFO] [stderr] 2026-04-19T23:26:54.296216Z DEBUG parse{path="/tmp/.tmpL0ATmj"}:new{content="println!(\"${1:message}\");"}: rsnip::domain::content: close time.busy=62.4µs time.idle=9.24µs
[INFO] [stderr] 2026-04-19T23:26:54.296259Z DEBUG parse{path="/tmp/.tmpL0ATmj"}: rsnip::infrastructure::parsers::vcode: close time.busy=220µs time.idle=10.2µs
[INFO] [stderr] 2026-04-19T23:26:54.296254Z TRACE copy_snippet_to_clipboard{completion_type="test" input="date" exact=true}:copy_to_clipboard{text="2026-04-19"}: rsnip::infrastructure::clipboard: close time.busy=37.7µs time.idle=9.01µs
[INFO] [stderr] 2026-04-19T23:26:54.296294Z DEBUG copy_snippet_to_clipboard{completion_type="test" input="date" exact=true}: rsnip::application::snippet_service: close time.busy=1.01ms time.idle=9.54µs
[INFO] [stderr] 2026-04-19T23:26:54.296493Z TRACE parse{path="/tmp/.tmpmh3aGr"}: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] 2026-04-19T23:26:54.296525Z TRACE parse{path="/tmp/.tmpmh3aGr"}:new{content="class {{ ClassName }}:\n    def __init__(self):\n        {{ pass }}\n"}: rsnip::domain::content: Detected as template
[INFO] [stderr] 2026-04-19T23:26:54.296558Z DEBUG parse{path="/tmp/.tmpmh3aGr"}:new{content="class {{ ClassName }}:\n    def __init__(self):\n        {{ pass }}\n"}: rsnip::domain::content: close time.busy=65.0µs time.idle=11.2µs
[INFO] [stderr] 2026-04-19T23:26:54.296602Z DEBUG parse{path="/tmp/.tmpmh3aGr"}: rsnip::infrastructure::parsers::scls: close time.busy=2.98ms time.idle=9.14µs
[INFO] [stderr] 2026-04-19T23:26:54.296818Z DEBUG parse{path="/tmp/.tmpqWCNrD"}: rsnip::infrastructure::parsers::vcode: Parsing VSCode format snippets from: "/tmp/.tmpqWCNrD"
[INFO] [stderr] 2026-04-19T23:26:54.298074Z DEBUG parse{path="/tmp/.tmpTthn7u"}: rsnip::infrastructure::parsers::vcode: Parsing VSCode format snippets from: "/tmp/.tmpTthn7u"
[INFO] [stderr] 2026-04-19T23:26:54.299120Z DEBUG parse{path="/tmp/.tmpTthn7u"}: rsnip::infrastructure::parsers::vcode: close time.busy=1.04ms time.idle=15.5µs
[INFO] [stderr] 2026-04-19T23:26:54.297896Z DEBUG create{format=Scls}: rsnip::infrastructure::parsers: close time.busy=17.0µs time.idle=11.5µs
[INFO] [stderr] 2026-04-19T23:26:54.299260Z DEBUG parse{path="/tmp/.tmp5qi215"}: rsnip::infrastructure::parsers::scls: Parsing SCLS format snippets from: "/tmp/.tmp5qi215"
[INFO] [stderr] 2026-04-19T23:26:54.301638Z TRACE parse{path="/tmp/.tmpqWCNrD"}: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] 2026-04-19T23:26:54.301783Z TRACE parse{path="/tmp/.tmpqWCNrD"}:new{content="fn ${1:function_name}(${2:params}) -> ${3:ReturnType} {\n    ${4:// function body}\n}"}: rsnip::domain::content: Detected as static
[INFO] [stderr] 2026-04-19T23:26:54.301893Z TRACE parse{path="/tmp/.tmp5qi215"}:new{content="print({{ param1 }})"}: rsnip::domain::content: SnippetContent::new called with: print({{ param1 }})
[INFO] [stderr] 2026-04-19T23:26:54.301931Z TRACE parse{path="/tmp/.tmp5qi215"}:new{content="print({{ param1 }})"}: rsnip::domain::content: Detected as template
[INFO] [stderr] 2026-04-19T23:26:54.301970Z DEBUG parse{path="/tmp/.tmp5qi215"}:new{content="print({{ param1 }})"}: rsnip::domain::content: close time.busy=75.4µs time.idle=21.8µs
[INFO] [stderr] 2026-04-19T23:26:54.302416Z TRACE parse{path="/tmp/.tmp5qi215"}:new{content="def {{ name }}({{ args }}):\n    {{ pass }}"}: rsnip::domain::content: SnippetContent::new called with: def {{ name }}({{ args }}):
[INFO] [stderr]     {{ pass }}
[INFO] [stderr] 2026-04-19T23:26:54.302456Z TRACE parse{path="/tmp/.tmp5qi215"}:new{content="def {{ name }}({{ args }}):\n    {{ pass }}"}: rsnip::domain::content: Detected as template
[INFO] [stderr] 2026-04-19T23:26:54.302498Z DEBUG parse{path="/tmp/.tmp5qi215"}:new{content="def {{ name }}({{ args }}):\n    {{ pass }}"}: rsnip::domain::content: close time.busy=80.6µs time.idle=11.4µs
[INFO] [stderr] 2026-04-19T23:26:54.299631Z TRACE parse{path="/tmp/.tmpDwQ5YW"}:new{content="content"}: rsnip::domain::content: SnippetContent::new called with: content
[INFO] [stderr] 2026-04-19T23:26:54.302555Z TRACE parse{path="/tmp/.tmpDwQ5YW"}:new{content="content"}: rsnip::domain::content: Detected as static
[INFO] [stderr] 2026-04-19T23:26:54.302595Z DEBUG parse{path="/tmp/.tmpDwQ5YW"}:new{content="content"}: rsnip::domain::content: close time.busy=2.96ms time.idle=10.8µs
[INFO] [stderr] 2026-04-19T23:26:54.302663Z DEBUG parse{path="/tmp/.tmp5qi215"}: rsnip::infrastructure::parsers::scls: close time.busy=3.40ms time.idle=18.2µs
[INFO] [stderr] 2026-04-19T23:26:54.301875Z DEBUG parse{path="/tmp/.tmpqWCNrD"}:new{content="fn ${1:function_name}(${2:params}) -> ${3:ReturnType} {\n    ${4:// function body}\n}"}: rsnip::domain::content: close time.busy=235µs time.idle=10.9µs
[INFO] [stderr] 2026-04-19T23:26:54.304500Z TRACE parse{path="/tmp/.tmpqWCNrD"}: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] 2026-04-19T23:26:54.304541Z TRACE parse{path="/tmp/.tmpqWCNrD"}:new{content="fn main() {\n    println!(\"Hello, world!\");\n}"}: rsnip::domain::content: Detected as static
[INFO] [stderr] 2026-04-19T23:26:54.304574Z DEBUG parse{path="/tmp/.tmpqWCNrD"}:new{content="fn main() {\n    println!(\"Hello, world!\");\n}"}: rsnip::domain::content: close time.busy=73.7µs time.idle=12.2µs
[INFO] [stderr] 2026-04-19T23:26:54.304615Z DEBUG parse{path="/tmp/.tmpqWCNrD"}: rsnip::infrastructure::parsers::vcode: close time.busy=7.80ms time.idle=11.7µs
[INFO] [stderr] 2026-04-19T23:26:54.306610Z  INFO rsnip::infrastructure::minijinja: Executing shell command: echo hello; rm -rf /
[INFO] [stderr] 2026-04-19T23:26:54.306919Z  INFO rsnip::infrastructure::minijinja: Executing shell command: echo hello | rm -rf /
[INFO] [stderr] 2026-04-19T23:26:54.307196Z  INFO rsnip::infrastructure::minijinja: Executing shell command: echo hello && rm -rf /
[INFO] [stderr] 2026-04-19T23:26:54.311008Z TRACE parse{path="/tmp/.tmpCkTAsS"}:new{content="{{ first }} {{ second }} {{ param3 }}"}: rsnip::domain::content: SnippetContent::new called with: {{ first }} {{ second }} {{ param3 }}
[INFO] [stderr] 2026-04-19T23:26:54.313550Z TRACE parse{path="/tmp/.tmpCkTAsS"}:new{content="{{ first }} {{ second }} {{ param3 }}"}: rsnip::domain::content: Detected as template
[INFO] [stderr] 2026-04-19T23:26:54.313654Z DEBUG parse{path="/tmp/.tmpCkTAsS"}:new{content="{{ first }} {{ second }} {{ param3 }}"}: rsnip::domain::content: close time.busy=2.64ms time.idle=12.4µs
[INFO] [stderr] 2026-04-19T23:26:54.313752Z DEBUG parse{path="/tmp/.tmpCkTAsS"}: rsnip::infrastructure::parsers::scls: close time.busy=19.0ms time.idle=7.92µs
[INFO] [stderr] 2026-04-19T23:26:54.314275Z  INFO rsnip::infrastructure::minijinja: Executing shell command: sudo echo hello
[INFO] [stderr] 2026-04-19T23:26:54.316304Z  INFO rsnip::infrastructure::minijinja: Executing shell command: `echo hello`
[INFO] [stderr] 2026-04-19T23:26:54.316699Z  INFO rsnip::infrastructure::minijinja: Executing shell command: nonexistent_command
[INFO] [stderr] 2026-04-19T23:26:54.321281Z  INFO rsnip::infrastructure::minijinja: Executing shell command: echo Hello
[INFO] [stderr] 2026-04-19T23:26:54.321527Z  INFO rsnip::infrastructure::minijinja: Executing shell command: $(echo hello)
[INFO] [stderr] 2026-04-19T23:26:54.517008Z DEBUG parse{path="/tmp/.tmpDwQ5YW"}: rsnip::infrastructure::parsers::default: close time.busy=230ms time.idle=33.5µ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_malformed_snippet_without_closing_delimiter_when_parse_then_returns_error ... 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: <arboard::common::Error as anyhow::context::ext::StdError>::ext_context::<&str>
[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: <core::result::Result<arboard::Clipboard, arboard::common::Error> as anyhow::Context<arboard::Clipboard, arboard::common::Error>>::context::<&str>
[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#0}
[INFO] [stdout]              at ./tests/application/test_snippet_service.rs:157:75
[INFO] [stdout]    6: <test_lib::application::test_snippet_service::given_template_snippet_when_copying_then_returns_rendered_content::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    7: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    8: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/test/src/lib.rs:663:18
[INFO] [stdout]    9: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/test/src/lib.rs:686:74
[INFO] [stdout]   10: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   11: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/panicking.rs:581:40
[INFO] [stdout]   12: std::panicking::catch_unwind::<core::result::Result<(), alloc::string::String>, core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>>
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/panicking.rs:544:19
[INFO] [stdout]   13: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/panic.rs:359:14
[INFO] [stdout]   14: test::run_test_in_process
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/test/src/lib.rs:686:27
[INFO] [stdout]   15: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/test/src/lib.rs:607:43
[INFO] [stdout]   16: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/test/src/lib.rs:637:41
[INFO] [stdout]   17: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   18: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   19: <core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   20: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/panicking.rs:581:40
[INFO] [stdout]   21: std::panicking::catch_unwind::<(), core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/panicking.rs:544:19
[INFO] [stdout]   22: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/panic.rs:359:14
[INFO] [stdout]   23: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   24: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   26: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   27: <unknown>
[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.24s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test test_lib`
[INFO] running `Command { std: "docker" "inspect" "e062d761d0c7bdeeab3ae92e4816da68b855b650fcccf9986227bd72463170e7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e062d761d0c7bdeeab3ae92e4816da68b855b650fcccf9986227bd72463170e7", kill_on_drop: false }`
[INFO] [stdout] e062d761d0c7bdeeab3ae92e4816da68b855b650fcccf9986227bd72463170e7