[INFO] cloning repository https://github.com/herwigstuetz/open-here [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/herwigstuetz/open-here" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fherwigstuetz%2Fopen-here", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fherwigstuetz%2Fopen-here'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 755aa0af17ef35aecfdfa78e648f1f58c152ce59 [INFO] testing herwigstuetz/open-here against try#2440211fe03bc45c89b6dc1a3df18382ce91e32b for pr-146098-1 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fherwigstuetz%2Fopen-here" "/workspace/builds/worker-5-tc2/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-5-tc2/source'... [INFO] [stderr] done. [INFO] started tweaking git repo https://github.com/herwigstuetz/open-here [INFO] finished tweaking git repo https://github.com/herwigstuetz/open-here [INFO] tweaked toml for git repo https://github.com/herwigstuetz/open-here written to /workspace/builds/worker-5-tc2/source/Cargo.toml [INFO] validating manifest of git repo https://github.com/herwigstuetz/open-here on toolchain 2440211fe03bc45c89b6dc1a3df18382ce91e32b [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate git repo https://github.com/herwigstuetz/open-here 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" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] a18b39715cf91a97e446498282dedff87e5ffff3fba6725577667bec8cc57bc6 [INFO] running `Command { std: "docker" "start" "-a" "a18b39715cf91a97e446498282dedff87e5ffff3fba6725577667bec8cc57bc6", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "a18b39715cf91a97e446498282dedff87e5ffff3fba6725577667bec8cc57bc6", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "a18b39715cf91a97e446498282dedff87e5ffff3fba6725577667bec8cc57bc6", kill_on_drop: false }` [INFO] [stdout] a18b39715cf91a97e446498282dedff87e5ffff3fba6725577667bec8cc57bc6 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] eb65e3e5b53f1b44cad8dcb2f2cbb4f8b53390d878e86d4cb2eda92846b7ba0c [INFO] running `Command { std: "docker" "start" "-a" "eb65e3e5b53f1b44cad8dcb2f2cbb4f8b53390d878e86d4cb2eda92846b7ba0c", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.86 [INFO] [stderr] Compiling proc-macro2 v1.0.24 [INFO] [stderr] Compiling unicode-xid v0.2.1 [INFO] [stderr] Compiling syn v1.0.60 [INFO] [stderr] Compiling log v0.4.14 [INFO] [stderr] Compiling once_cell v1.5.2 [INFO] [stderr] Compiling memchr v2.3.4 [INFO] [stderr] Compiling version_check v0.9.2 [INFO] [stderr] Compiling proc-macro-hack v0.5.19 [INFO] [stderr] Compiling futures-core v0.3.12 [INFO] [stderr] Compiling slab v0.4.2 [INFO] [stderr] Compiling autocfg v1.0.1 [INFO] [stderr] Compiling pin-project-lite v0.2.4 [INFO] [stderr] Compiling futures-sink v0.3.12 [INFO] [stderr] Compiling smallvec v1.6.1 [INFO] [stderr] Compiling proc-macro-nested v0.1.7 [INFO] [stderr] Compiling instant v0.1.9 [INFO] [stderr] Compiling futures-channel v0.3.12 [INFO] [stderr] Compiling futures-io v0.3.12 [INFO] [stderr] Compiling scopeguard v1.1.0 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling pin-project-internal v0.4.27 [INFO] [stderr] Compiling lock_api v0.4.2 [INFO] [stderr] Compiling cfg-if v0.1.10 [INFO] [stderr] Compiling cc v1.0.66 [INFO] [stderr] Compiling bytes v1.0.1 [INFO] [stderr] Compiling bytes v0.5.6 [INFO] [stderr] Compiling futures-task v0.3.12 [INFO] [stderr] Compiling pin-project-lite v0.1.11 [INFO] [stderr] Compiling itoa v0.4.7 [INFO] [stderr] Compiling bitflags v1.2.1 [INFO] [stderr] Compiling matches v0.1.8 [INFO] [stderr] Compiling fnv v1.0.7 [INFO] [stderr] Compiling ppv-lite86 v0.2.10 [INFO] [stderr] Compiling getrandom v0.1.16 [INFO] [stderr] Compiling percent-encoding v2.1.0 [INFO] [stderr] Compiling tinyvec_macros v0.1.0 [INFO] [stderr] Compiling tracing-core v0.1.17 [INFO] [stderr] Compiling tinyvec v1.1.1 [INFO] [stderr] Compiling unicode-segmentation v1.7.1 [INFO] [stderr] Compiling miniz_oxide v0.4.3 [INFO] [stderr] Compiling indexmap v1.6.1 [INFO] [stderr] Compiling serde_derive v1.0.123 [INFO] [stderr] Compiling form_urlencoded v1.0.0 [INFO] [stderr] Compiling standback v0.2.15 [INFO] [stderr] Compiling quote v1.0.9 [INFO] [stderr] Compiling http v0.2.3 [INFO] [stderr] Compiling signal-hook-registry v1.3.0 [INFO] [stderr] Compiling parking_lot_core v0.8.3 [INFO] [stderr] Compiling num_cpus v1.13.0 [INFO] [stderr] Compiling net2 v0.2.37 [INFO] [stderr] Compiling iovec v0.1.4 [INFO] [stderr] Compiling heck v0.3.2 [INFO] [stderr] Compiling threadpool v1.8.1 [INFO] [stderr] Compiling unicode-bidi v0.3.4 [INFO] [stderr] Compiling parking_lot v0.11.1 [INFO] [stderr] Compiling mio v0.6.23 [INFO] [stderr] Compiling serde v1.0.123 [INFO] [stderr] Compiling adler v0.2.3 [INFO] [stderr] Compiling copyless v0.1.5 [INFO] [stderr] Compiling typenum v1.12.0 [INFO] [stderr] Compiling hashbrown v0.9.1 [INFO] [stderr] Compiling rand_core v0.5.1 [INFO] [stderr] Compiling tokio v1.2.0 [INFO] [stderr] Compiling generic-array v0.14.4 [INFO] [stderr] Compiling either v1.6.1 [INFO] [stderr] Compiling unicode-normalization v0.1.17 [INFO] [stderr] Compiling httparse v1.3.5 [INFO] [stderr] Compiling gimli v0.23.0 [INFO] [stderr] Compiling untrusted v0.7.1 [INFO] [stderr] Compiling spin v0.5.2 [INFO] [stderr] Compiling ring v0.16.20 [INFO] [stderr] Compiling ryu v1.0.5 [INFO] [stderr] Compiling rand_chacha v0.2.2 [INFO] [stderr] Compiling mio-uds v0.6.8 [INFO] [stderr] Compiling mio v0.7.7 [INFO] [stderr] Compiling rustc-demangle v0.1.18 [INFO] [stderr] Compiling const_fn v0.4.5 [INFO] [stderr] Compiling tokio v0.2.25 [INFO] [stderr] Compiling base64 v0.13.0 [INFO] [stderr] Compiling object v0.23.0 [INFO] [stderr] Compiling match_cfg v0.1.0 [INFO] [stderr] Compiling hostname v0.3.1 [INFO] [stderr] Compiling rand v0.7.3 [INFO] [stderr] Compiling brotli-sys v0.3.2 [INFO] [stderr] Compiling socket2 v0.3.19 [INFO] [stderr] Compiling time v0.2.25 [INFO] [stderr] Compiling aho-corasick v0.7.15 [INFO] [stderr] Compiling thread_local v1.1.3 [INFO] [stderr] Compiling linked-hash-map v0.5.4 [INFO] [stderr] Compiling idna v0.2.1 [INFO] [stderr] Compiling quick-error v1.2.3 [INFO] [stderr] Compiling serde_json v1.0.62 [INFO] [stderr] Compiling encoding_rs v0.8.28 [INFO] [stderr] Compiling crc32fast v1.2.1 [INFO] [stderr] Compiling regex-syntax v0.6.22 [INFO] [stderr] Compiling getrandom v0.2.2 [INFO] [stderr] Compiling resolv-conf v0.7.0 [INFO] [stderr] Compiling lru-cache v0.1.2 [INFO] [stderr] Compiling cookie v0.14.3 [INFO] [stderr] Compiling proc-macro-error-attr v1.0.4 [INFO] [stderr] Compiling digest v0.9.0 [INFO] [stderr] Compiling block-buffer v0.9.0 [INFO] [stderr] Compiling proc-macro-error v1.0.4 [INFO] [stderr] Compiling byteorder v1.4.2 [INFO] [stderr] Compiling cpuid-bool v0.1.2 [INFO] [stderr] Compiling opaque-debug v0.3.0 [INFO] [stderr] Compiling try-lock v0.2.3 [INFO] [stderr] Compiling mime v0.3.16 [INFO] [stderr] Compiling sha-1 v0.9.3 [INFO] [stderr] Compiling url v2.2.0 [INFO] [stderr] Compiling want v0.3.0 [INFO] [stderr] Compiling addr2line v0.14.1 [INFO] [stderr] Compiling fxhash v0.2.1 [INFO] [stderr] Compiling flate2 v1.0.20 [INFO] [stderr] Compiling rand_core v0.6.2 [INFO] [stderr] Compiling http-body v0.4.0 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling tower-service v0.3.1 [INFO] [stderr] Compiling language-tags v0.2.2 [INFO] [stderr] Compiling unicode-width v0.1.8 [INFO] [stderr] Compiling httpdate v0.3.2 [INFO] [stderr] Compiling textwrap v0.11.0 [INFO] [stderr] Compiling rand_chacha v0.3.0 [INFO] [stderr] Compiling bytestring v1.0.0 [INFO] [stderr] Compiling ansi_term v0.11.0 [INFO] [stderr] Compiling regex v1.4.3 [INFO] [stderr] Compiling vec_map v0.8.2 [INFO] [stderr] Compiling strsim v0.8.0 [INFO] [stderr] Compiling rand v0.8.3 [INFO] [stderr] Compiling termcolor v1.1.2 [INFO] [stderr] Compiling clap v2.33.3 [INFO] [stderr] Compiling humantime v2.1.0 [INFO] [stderr] Compiling remove_dir_all v0.5.3 [INFO] [stderr] Compiling ipnet v2.3.0 [INFO] [stderr] Compiling env_logger v0.8.3 [INFO] [stderr] Compiling tempfile v3.2.0 [INFO] [stderr] Compiling tokio-util v0.3.1 [INFO] [stderr] Compiling backtrace v0.3.56 [INFO] [stderr] Compiling webpki v0.21.4 [INFO] [stderr] Compiling sct v0.6.0 [INFO] [stderr] Compiling futures-macro v0.3.12 [INFO] [stderr] Compiling tracing-attributes v0.1.12 [INFO] [stderr] Compiling derive_more v0.99.11 [INFO] [stderr] Compiling actix-macros v0.1.3 [INFO] [stderr] Compiling tokio-macros v1.1.0 [INFO] [stderr] Compiling pin-project-internal v1.0.5 [INFO] [stderr] Compiling thiserror-impl v1.0.23 [INFO] [stderr] Compiling enum-as-inner v0.3.3 [INFO] [stderr] Compiling async-trait v0.1.42 [INFO] [stderr] Compiling time-macros-impl v0.1.1 [INFO] [stderr] Compiling actix-web-codegen v0.4.0 [INFO] [stderr] Compiling rustls v0.19.0 [INFO] [stderr] Compiling webpki-roots v0.21.0 [INFO] [stderr] Compiling envconfig_derive v0.9.1 [INFO] [stderr] Compiling structopt-derive v0.4.14 [INFO] [stderr] Compiling time-macros v0.1.1 [INFO] [stderr] Compiling futures-util v0.3.12 [INFO] [stderr] Compiling envconfig v0.9.1 [INFO] [stderr] Compiling tracing v0.1.23 [INFO] [stderr] Compiling thiserror v1.0.23 [INFO] [stderr] Compiling pin-project v0.4.27 [INFO] [stderr] Compiling pin-project v1.0.5 [INFO] [stderr] Compiling actix-codec v0.3.0 [INFO] [stderr] Compiling tracing-futures v0.2.4 [INFO] [stderr] Compiling structopt v0.3.21 [INFO] [stderr] Compiling actix-threadpool v0.3.3 [INFO] [stderr] Compiling brotli2 v0.3.2 [INFO] [stderr] Compiling actix-service v1.0.6 [INFO] [stderr] Compiling actix-rt v1.1.1 [INFO] [stderr] Compiling futures v0.3.12 [INFO] [stderr] Compiling h2 v0.2.7 [INFO] [stderr] Compiling trust-dns-proto v0.19.6 [INFO] [stderr] Compiling actix-utils v2.0.0 [INFO] [stderr] Compiling actix-server v1.0.4 [INFO] [stderr] Compiling actix-tls v2.0.0 [INFO] [stderr] Compiling tokio-util v0.6.3 [INFO] [stderr] Compiling tokio-rustls v0.22.0 [INFO] [stderr] Compiling h2 v0.3.0 [INFO] [stderr] Compiling actix-testing v1.0.1 [INFO] [stderr] Compiling trust-dns-resolver v0.19.6 [INFO] [stderr] Compiling actix-connect v2.0.0 [INFO] [stderr] Compiling serde_urlencoded v0.7.0 [INFO] [stderr] Compiling actix-router v0.2.7 [INFO] [stderr] Compiling actix-http v2.2.0 [INFO] [stderr] Compiling hyper v0.14.4 [INFO] [stderr] Compiling awc v2.0.3 [INFO] [stderr] Compiling actix-web v3.3.2 [INFO] [stderr] Compiling hyper-rustls v0.22.1 [INFO] [stderr] Compiling reqwest v0.11.0 [INFO] [stderr] Compiling open-here v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 06s [INFO] running `Command { std: "docker" "inspect" "eb65e3e5b53f1b44cad8dcb2f2cbb4f8b53390d878e86d4cb2eda92846b7ba0c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "eb65e3e5b53f1b44cad8dcb2f2cbb4f8b53390d878e86d4cb2eda92846b7ba0c", kill_on_drop: false }` [INFO] [stdout] eb65e3e5b53f1b44cad8dcb2f2cbb4f8b53390d878e86d4cb2eda92846b7ba0c [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 7c60a73ab44a5f7d19060083f7abf3a88cbb1544f94924a5d6d1119efafe4451 [INFO] running `Command { std: "docker" "start" "-a" "7c60a73ab44a5f7d19060083f7abf3a88cbb1544f94924a5d6d1119efafe4451", kill_on_drop: false }` [INFO] [stderr] Compiling open-here v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 1.98s [INFO] running `Command { std: "docker" "inspect" "7c60a73ab44a5f7d19060083f7abf3a88cbb1544f94924a5d6d1119efafe4451", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7c60a73ab44a5f7d19060083f7abf3a88cbb1544f94924a5d6d1119efafe4451", kill_on_drop: false }` [INFO] [stdout] 7c60a73ab44a5f7d19060083f7abf3a88cbb1544f94924a5d6d1119efafe4451 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] d2283f0dee45bb72c5706fbd425aea61033b216f96588774c5aab8ad24199f2d [INFO] running `Command { std: "docker" "start" "-a" "d2283f0dee45bb72c5706fbd425aea61033b216f96588774c5aab8ad24199f2d", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.25s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/open_here-b5cba54c297974c9) [INFO] [stdout] [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/open_here-7536f68703a199c0) [INFO] [stdout] [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/open_check.rs (/opt/rustwide/target/debug/deps/open_check-43f180b5e18e8b53) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE | WRITABLE [INFO] [stderr] [2025-10-01T16:02:33Z INFO open_here::server] Running open-here server on port 32963 [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z INFO actix_server::builder] Starting 16 workers [INFO] [stderr] [2025-10-01T16:02:33Z INFO open_here::server] Running open-here server on port 36551 [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z INFO actix_server::builder] Starting 16 workers [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:32963" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE | WRITABLE [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:32963" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:36551" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:32963" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:36551" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:32963" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:32963" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:36551" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:36551" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:36551" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:32963" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:36551" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:36551" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:32963" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:36551" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:32963" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:32963" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:36551" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:36551" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:36551" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:36551" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:36551" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:32963" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:32963" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:32963" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:32963" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:32963" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:36551" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:36551" is available [INFO] [stderr] [2025-10-01T16:02:33Z INFO actix_server::builder] Starting "actix-web-service-[::1]:36551" service on [::1]:36551 [INFO] [stderr] [2025-10-01T16:02:33Z INFO actix_server::builder] Starting "actix-web-service-[::1]:32963" service on [::1]:32963 [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:32963" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:36551" is available [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG open_here::client] Sent request: RequestBuilder { method: GET, url: Url { scheme: "http", host: Some(Domain("localhost")), port: Some(32963), path: "/open/url", query: None, fragment: None }, headers: {"content-type": "application/json"} } [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::client::pool] checkout waiting for idle connection: ("http", localhost:32963) [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG reqwest::connect] starting new connection: http://localhost:32963/ [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::client::connect::http] Http::connect; scheme=Some("http"), host=Some("localhost"), port=Some(Port(32963)) [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG open_here::client] Sent request: RequestBuilder { method: GET, url: Url { scheme: "http", host: Some(Domain("localhost")), port: Some(36551), path: "/open/path", query: Some("filename=image.png"), fragment: None }, headers: {} } [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::client::pool] checkout waiting for idle connection: ("http", localhost:36551) [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG reqwest::connect] starting new connection: http://localhost:36551/ [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::client::connect::http] Http::connect; scheme=Some("http"), host=Some("localhost"), port=Some(Port(36551)) [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG hyper::client::connect::dns] resolving host="localhost" [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_server::worker] Service "actix-web-service-[::1]:32963" is available [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG hyper::client::connect::http] connecting to [::1]:32963 [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering event source with poller: token=Token(1), interests=READABLE | WRITABLE [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG hyper::client::connect::http] connected to [::1]:32963 [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::client::conn] client handshake Http1 [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::client::client] handshake complete, spawning background dispatcher task [INFO] [stderr] [2025-10-01T16:02:33Z TRACE want] signal: Want [INFO] [stderr] [2025-10-01T16:02:33Z TRACE want] signal found waiting giver, notifying [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Busy } [INFO] [stderr] [2025-10-01T16:02:33Z TRACE want] poll_want: taker wants! [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::client::pool] checkout dropped for ("http", localhost:32963) [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] encode_headers [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] -> encode_headers [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] Client::encode method=GET, body=Some(Known(34)) [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] <- encode_headers [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] -- encode_headers [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::encode] sized write, len = 34 [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG hyper::proto::h1::io] flushed 148 bytes [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: KeepAlive, keep_alive: Busy } [INFO] [stderr] [2025-10-01T16:02:33Z TRACE actix_http::h1::decoder] Length read: 34 [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG open_here::server] open; open=Url(UrlTarget { target: "http://localhost:1234" }) [INFO] [stderr] [2025-10-01T16:02:33Z TRACE open_here::server] -> open [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG open_here::cmd] dry_run: xdg-open http://localhost:1234 [INFO] [stderr] [2025-10-01T16:02:33Z TRACE open_here::server] <- open [INFO] [stderr] [2025-10-01T16:02:33Z TRACE open_here::server] -- open [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::conn] Conn::read_head [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] parse_headers [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] -> parse_headers [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] Response.parse([Header; 100], [u8; 147]) [INFO] [stdout] test test_open_url ... ok [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] Response.parse Complete(108) [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] <- parse_headers [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] -- parse_headers [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG hyper::proto::h1::io] parsed 3 headers [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG hyper::proto::h1::conn] incoming body is content-length (39 bytes) [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::decode] decode; state=Length(39) [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG hyper::proto::h1::conn] incoming body completed [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::conn] maybe_notify; read_from_io blocked [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG hyper::client::connect::dns] resolving host="localhost" [INFO] [stderr] [2025-10-01T16:02:33Z TRACE want] signal: Want [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } [INFO] [stderr] [2025-10-01T16:02:33Z TRACE want] signal: Want [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::client::pool] put; add idle connection for ("http", localhost:32963) [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG hyper::client::pool] pooling idle connection for ("http", localhost:32963) [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG reqwest::async_impl::client] response '200 OK' for http://localhost:32963/open/url [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] deregistering event source from poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] deregistering handle with poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE want] signal: Closed [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG open_check] open-here server returned: xdg-open http://localhost:1234 [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG hyper::client::connect::http] connecting to [::1]:36551 [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering event source with poller: token=Token(1), interests=READABLE | WRITABLE [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] registering with poller [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG hyper::client::connect::http] connected to [::1]:36551 [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::client::conn] client handshake Http1 [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::client::client] handshake complete, spawning background dispatcher task [INFO] [stderr] [2025-10-01T16:02:33Z TRACE want] signal: Want [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Busy } [INFO] [stderr] [2025-10-01T16:02:33Z TRACE want] poll_want: taker wants! [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::client::pool] checkout dropped for ("http", localhost:36551) [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] encode_headers [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] -> encode_headers [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] Client::encode method=GET, body=None [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] <- encode_headers [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] -- encode_headers [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG hyper::proto::h1::io] flushed 82 bytes [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: KeepAlive, keep_alive: Busy } [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG open_here::server] open; open=Path(PathTarget { filename: "image.png", content: [] }) [INFO] [stderr] [2025-10-01T16:02:33Z TRACE open_here::server] -> open [INFO] [stderr] [2025-10-01T16:02:33Z TRACE open_here::cmd] Writing file /tmp/open-here/image.png [INFO] [stderr] [2025-10-01T16:02:33Z TRACE open_here::cmd] Writing file /tmp/open-here/image.png [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG open_here::cmd] dry_run: xdg-open /tmp/open-here/image.png [INFO] [stderr] [2025-10-01T16:02:33Z TRACE open_here::server] <- open [INFO] [stderr] [2025-10-01T16:02:33Z TRACE open_here::server] -- open [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::conn] Conn::read_head [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] parse_headers [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] -> parse_headers [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] Response.parse([Header; 100], [u8; 150]) [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] Response.parse Complete(108) [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] <- parse_headers [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::role] -- parse_headers [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG hyper::proto::h1::io] parsed 3 headers [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG hyper::proto::h1::conn] incoming body is content-length (42 bytes) [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::decode] decode; state=Length(42) [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG hyper::proto::h1::conn] incoming body completed [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::conn] maybe_notify; read_from_io blocked [INFO] [stderr] [2025-10-01T16:02:33Z TRACE want] signal: Want [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } [INFO] [stderr] [2025-10-01T16:02:33Z TRACE want] signal: Want [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } [INFO] [stderr] [2025-10-01T16:02:33Z TRACE hyper::client::pool] put; add idle connection for ("http", localhost:36551) [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG hyper::client::pool] pooling idle connection for ("http", localhost:36551) [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG reqwest::async_impl::client] response '200 OK' for http://localhost:36551/open/path?filename=image.png [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] deregistering event source from poller [INFO] [stderr] [2025-10-01T16:02:33Z TRACE want] signal: Closed [INFO] [stderr] [2025-10-01T16:02:33Z TRACE mio::poll] deregistering handle with poller [INFO] [stdout] test test_open_file ... ok [INFO] [stderr] [2025-10-01T16:02:33Z DEBUG open_check] open-here server returned: xdg-open /tmp/open-here/image.png [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.14s [INFO] [stdout] [INFO] [stderr] Doc-tests open_here [INFO] [stdout] [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "d2283f0dee45bb72c5706fbd425aea61033b216f96588774c5aab8ad24199f2d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "d2283f0dee45bb72c5706fbd425aea61033b216f96588774c5aab8ad24199f2d", kill_on_drop: false }` [INFO] [stdout] d2283f0dee45bb72c5706fbd425aea61033b216f96588774c5aab8ad24199f2d