[INFO] cloning repository https://github.com/rohankumardubey/rathole [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/rohankumardubey/rathole" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frohankumardubey%2Frathole", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frohankumardubey%2Frathole'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 8a247238954722d4de7e83dba0cff410d62ffb14 [INFO] testing rohankumardubey/rathole against try#613d1972a772987ed4ee06faccbab0e2fc839871+rustflags=-Copt-level=3 for pr-107051 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frohankumardubey%2Frathole" "/workspace/builds/worker-4-tc2/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc2/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/rohankumardubey/rathole on toolchain 613d1972a772987ed4ee06faccbab0e2fc839871 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+613d1972a772987ed4ee06faccbab0e2fc839871" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking git repo https://github.com/rohankumardubey/rathole [INFO] finished tweaking git repo https://github.com/rohankumardubey/rathole [INFO] tweaked toml for git repo https://github.com/rohankumardubey/rathole written to /workspace/builds/worker-4-tc2/source/Cargo.toml [INFO] crate git repo https://github.com/rohankumardubey/rathole 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" "+613d1972a772987ed4ee06faccbab0e2fc839871" "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-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:7c93e6f9a94679f34221d1005849d4c83862f85b14a264fa0883942f4d043a66" "/opt/rustwide/cargo-home/bin/cargo" "+613d1972a772987ed4ee06faccbab0e2fc839871" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] e2fb1c15bd4c1a6f21db2619e7d72dfb26652da69bef1040936deb7fee8eca97 [INFO] running `Command { std: "docker" "start" "-a" "e2fb1c15bd4c1a6f21db2619e7d72dfb26652da69bef1040936deb7fee8eca97", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "e2fb1c15bd4c1a6f21db2619e7d72dfb26652da69bef1040936deb7fee8eca97", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "e2fb1c15bd4c1a6f21db2619e7d72dfb26652da69bef1040936deb7fee8eca97", kill_on_drop: false }` [INFO] [stdout] e2fb1c15bd4c1a6f21db2619e7d72dfb26652da69bef1040936deb7fee8eca97 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid -Copt-level=3" "-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:7c93e6f9a94679f34221d1005849d4c83862f85b14a264fa0883942f4d043a66" "/opt/rustwide/cargo-home/bin/cargo" "+613d1972a772987ed4ee06faccbab0e2fc839871" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] aaa85b1f73b32bb508a0c215395db0573bda92f0a2749ab212c25d0cba0eb796 [INFO] running `Command { std: "docker" "start" "-a" "aaa85b1f73b32bb508a0c215395db0573bda92f0a2749ab212c25d0cba0eb796", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.119 [INFO] [stderr] Compiling proc-macro2 v1.0.36 [INFO] [stderr] Compiling unicode-xid v0.2.2 [INFO] [stderr] Compiling syn v1.0.86 [INFO] [stderr] Compiling version_check v0.9.4 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling autocfg v1.1.0 [INFO] [stderr] Compiling typenum v1.15.0 [INFO] [stderr] Compiling pkg-config v0.3.24 [INFO] [stderr] Compiling serde_derive v1.0.136 [INFO] [stderr] Compiling serde v1.0.136 [INFO] [stderr] Compiling log v0.4.14 [INFO] [stderr] Compiling subtle v2.4.1 [INFO] [stderr] Compiling cpufeatures v0.2.1 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling opaque-debug v0.3.0 [INFO] [stderr] Compiling once_cell v1.10.0 [INFO] [stderr] Compiling memchr v2.4.1 [INFO] [stderr] Compiling parking_lot_core v0.9.1 [INFO] [stderr] Compiling smallvec v1.8.0 [INFO] [stderr] Compiling pin-project-lite v0.2.8 [INFO] [stderr] Compiling tinyvec_macros v0.1.0 [INFO] [stderr] Compiling scopeguard v1.1.0 [INFO] [stderr] Compiling ucd-trie v0.1.3 [INFO] [stderr] Compiling lock_api v0.4.6 [INFO] [stderr] Compiling pest v2.1.3 [INFO] [stderr] Compiling tinyvec v1.5.1 [INFO] [stderr] Compiling matches v0.1.9 [INFO] [stderr] Compiling rustversion v1.0.6 [INFO] [stderr] Compiling getrandom v0.1.16 [INFO] [stderr] Compiling unicode-bidi v0.3.7 [INFO] [stderr] Compiling anyhow v1.0.56 [INFO] [stderr] Compiling bitflags v1.3.2 [INFO] [stderr] Compiling percent-encoding v2.1.0 [INFO] [stderr] Compiling form_urlencoded v1.0.1 [INFO] [stderr] Compiling futures-core v0.3.21 [INFO] [stderr] Compiling ppv-lite86 v0.2.16 [INFO] [stderr] Compiling semver-parser v0.10.2 [INFO] [stderr] Compiling tracing-core v0.1.23 [INFO] [stderr] Compiling byteorder v1.4.3 [INFO] [stderr] Compiling quote v1.0.15 [INFO] [stderr] Compiling jobserver v0.1.24 [INFO] [stderr] Compiling num-traits v0.2.14 [INFO] [stderr] Compiling num-integer v0.1.44 [INFO] [stderr] Compiling cc v1.0.73 [INFO] [stderr] Compiling generic-array v0.14.5 [INFO] [stderr] Compiling proc-macro-error-attr v1.0.4 [INFO] [stderr] Compiling proc-macro-error v1.0.4 [INFO] [stderr] Compiling time v0.1.43 [INFO] [stderr] Compiling unicode-normalization v0.1.19 [INFO] [stderr] Compiling parking_lot v0.12.0 [INFO] [stderr] Compiling num_cpus v1.13.1 [INFO] [stderr] Compiling socket2 v0.4.4 [INFO] [stderr] Compiling signal-hook-registry v1.4.0 [INFO] [stderr] Compiling mio v0.8.0 [INFO] [stderr] Compiling getrandom v0.2.5 [INFO] [stderr] Compiling rand_core v0.6.3 [INFO] [stderr] Compiling semver v0.11.0 [INFO] [stderr] Compiling universal-hash v0.4.1 [INFO] [stderr] Compiling cipher v0.3.0 [INFO] [stderr] Compiling digest v0.9.0 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling idna v0.2.3 [INFO] [stderr] Compiling rand_core v0.5.1 [INFO] [stderr] Compiling polyval v0.5.3 [INFO] [stderr] Compiling rustc_version v0.3.3 [INFO] [stderr] Compiling aead v0.4.3 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling foreign-types-shared v0.1.1 [INFO] [stderr] Compiling regex-syntax v0.6.25 [INFO] [stderr] Compiling url v2.2.2 [INFO] [stderr] Compiling futures-task v0.3.21 [INFO] [stderr] Compiling openssl v0.10.38 [INFO] [stderr] Compiling crossbeam-utils v0.8.7 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling foreign-types v0.3.2 [INFO] [stderr] Compiling ghash v0.4.4 [INFO] [stderr] Compiling ctr v0.8.0 [INFO] [stderr] Compiling aes v0.7.5 [INFO] [stderr] Compiling poly1305 v0.7.2 [INFO] [stderr] Compiling crypto-mac v0.8.0 [INFO] [stderr] Compiling block-buffer v0.9.0 [INFO] [stderr] Compiling indexmap v1.8.0 [INFO] [stderr] Compiling httparse v1.6.0 [INFO] [stderr] Compiling native-tls v0.2.8 [INFO] [stderr] Compiling serde_json v1.0.79 [INFO] [stderr] Compiling futures-util v0.3.21 [INFO] [stderr] Compiling async-trait v0.1.52 [INFO] [stderr] Compiling aes-gcm v0.9.4 [INFO] [stderr] Compiling sha2 v0.9.9 [INFO] [stderr] Compiling blake2 v0.9.2 [INFO] [stderr] Compiling block-buffer v0.10.2 [INFO] [stderr] Compiling crypto-common v0.1.3 [INFO] [stderr] Compiling inotify-sys v0.1.5 [INFO] [stderr] Compiling itoa v1.0.1 [INFO] [stderr] Compiling snow v0.8.0 [INFO] [stderr] Compiling hashbrown v0.11.2 [INFO] [stderr] Compiling openssl-probe v0.1.5 [INFO] [stderr] Compiling same-file v1.0.6 [INFO] [stderr] Compiling synstructure v0.12.6 [INFO] [stderr] Compiling heck v0.4.0 [INFO] [stderr] Compiling regex-automata v0.1.10 [INFO] [stderr] Compiling slab v0.4.5 [INFO] [stderr] Compiling ryu v1.0.9 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling regex v1.5.5 [INFO] [stderr] Compiling vergen v6.0.2 [INFO] [stderr] Compiling matchers v0.0.1 [INFO] [stderr] Compiling walkdir v2.3.2 [INFO] [stderr] Compiling crossbeam-channel v0.5.2 [INFO] [stderr] Compiling digest v0.10.3 [INFO] [stderr] Compiling inotify v0.9.6 [INFO] [stderr] Compiling tracing-log v0.1.2 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling filetime v0.2.15 [INFO] [stderr] Compiling mio v0.7.14 [INFO] [stderr] Compiling os_str_bytes v6.0.0 [INFO] [stderr] Compiling thread_local v1.1.4 [INFO] [stderr] Compiling sharded-slab v0.1.4 [INFO] [stderr] Compiling instant v0.1.12 [INFO] [stderr] Compiling ansi_term v0.12.1 [INFO] [stderr] Compiling strsim v0.10.0 [INFO] [stderr] Compiling base64 v0.13.0 [INFO] [stderr] Compiling textwrap v0.15.0 [INFO] [stderr] Compiling termcolor v1.1.3 [INFO] [stderr] Compiling notify v5.0.0-pre.13 [INFO] [stderr] Compiling libz-sys v1.1.5 [INFO] [stderr] Compiling openssl-sys v0.9.72 [INFO] [stderr] Compiling libgit2-sys v0.13.1+1.4.2 [INFO] [stderr] Compiling sha2 v0.10.2 [INFO] [stderr] Compiling fdlimit v0.2.1 [INFO] [stderr] Compiling hex v0.4.3 [INFO] [stderr] Compiling git2 v0.14.1 [INFO] [stderr] Compiling thiserror-impl v1.0.30 [INFO] [stderr] Compiling zeroize_derive v1.3.2 [INFO] [stderr] Compiling tokio-macros v1.7.0 [INFO] [stderr] Compiling enum-iterator-derive v0.7.0 [INFO] [stderr] Compiling getset v0.1.2 [INFO] [stderr] Compiling futures-macro v0.3.21 [INFO] [stderr] Compiling pin-project-internal v1.0.10 [INFO] [stderr] Compiling tracing-attributes v0.1.20 [INFO] [stderr] Compiling clap_derive v3.1.4 [INFO] [stderr] Compiling enum-iterator v0.7.0 [INFO] [stderr] Compiling zeroize v1.3.0 [INFO] [stderr] Compiling curve25519-dalek v3.2.1 [INFO] [stderr] Compiling chacha20 v0.7.3 [INFO] [stderr] Compiling chacha20poly1305 v0.8.2 [INFO] [stderr] Compiling x25519-dalek v1.2.0 [INFO] [stderr] Compiling thiserror v1.0.30 [INFO] [stderr] Compiling rathole v0.4.1 (/opt/rustwide/workdir) [INFO] [stderr] Compiling pin-project v1.0.10 [INFO] [stderr] Compiling clap v3.1.6 [INFO] [stderr] Compiling tracing v0.1.31 [INFO] [stderr] Compiling bytes v1.1.0 [INFO] [stderr] Compiling tracing-serde v0.1.3 [INFO] [stderr] Compiling toml v0.5.8 [INFO] [stderr] Compiling bincode v1.3.3 [INFO] [stderr] Compiling tokio v1.17.0 [INFO] [stderr] Compiling tracing-subscriber v0.2.25 [INFO] [stderr] Compiling async-http-proxy v1.2.5 [INFO] [stderr] Compiling snowstorm v0.3.1 [INFO] [stderr] Compiling tokio-native-tls v0.3.0 [INFO] [stderr] Compiling async-socks5 v0.5.1 [INFO] [stderr] Compiling backoff v0.4.0 [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 6m 06s [INFO] running `Command { std: "docker" "inspect" "aaa85b1f73b32bb508a0c215395db0573bda92f0a2749ab212c25d0cba0eb796", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "aaa85b1f73b32bb508a0c215395db0573bda92f0a2749ab212c25d0cba0eb796", kill_on_drop: false }` [INFO] [stdout] aaa85b1f73b32bb508a0c215395db0573bda92f0a2749ab212c25d0cba0eb796 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid -Copt-level=3" "-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:7c93e6f9a94679f34221d1005849d4c83862f85b14a264fa0883942f4d043a66" "/opt/rustwide/cargo-home/bin/cargo" "+613d1972a772987ed4ee06faccbab0e2fc839871" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 75fdf1e37576fee267c912eeb6540f1631bc6b843529fd1487182fece907e57b [INFO] running `Command { std: "docker" "start" "-a" "75fdf1e37576fee267c912eeb6540f1631bc6b843529fd1487182fece907e57b", kill_on_drop: false }` [INFO] [stderr] Compiling typenum v1.15.0 [INFO] [stderr] Compiling log v0.4.14 [INFO] [stderr] Compiling subtle v2.4.1 [INFO] [stderr] Compiling cpufeatures v0.2.1 [INFO] [stderr] Compiling opaque-debug v0.3.0 [INFO] [stderr] Compiling once_cell v1.10.0 [INFO] [stderr] Compiling serde v1.0.136 [INFO] [stderr] Compiling smallvec v1.8.0 [INFO] [stderr] Compiling pin-project-lite v0.2.8 [INFO] [stderr] Compiling memchr v2.4.1 [INFO] [stderr] Compiling scopeguard v1.1.0 [INFO] [stderr] Compiling zeroize v1.3.0 [INFO] [stderr] Compiling socket2 v0.4.4 [INFO] [stderr] Compiling num_cpus v1.13.1 [INFO] [stderr] Compiling signal-hook-registry v1.4.0 [INFO] [stderr] Compiling getrandom v0.2.5 [INFO] [stderr] Compiling getrandom v0.1.16 [INFO] [stderr] Compiling ppv-lite86 v0.2.16 [INFO] [stderr] Compiling lock_api v0.4.6 [INFO] [stderr] Compiling tracing-core v0.1.23 [INFO] [stderr] Compiling mio v0.8.0 [INFO] [stderr] Compiling rand_core v0.6.3 [INFO] [stderr] Compiling parking_lot_core v0.9.1 [INFO] [stderr] Compiling rand_core v0.5.1 [INFO] [stderr] Compiling foreign-types-shared v0.1.1 [INFO] [stderr] Compiling byteorder v1.4.3 [INFO] [stderr] Compiling regex-syntax v0.6.25 [INFO] [stderr] Compiling foreign-types v0.3.2 [INFO] [stderr] Compiling openssl-sys v0.9.72 [INFO] [stderr] Compiling futures-core v0.3.21 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling crossbeam-utils v0.8.7 [INFO] [stderr] Compiling futures-task v0.3.21 [INFO] [stderr] Compiling parking_lot v0.12.0 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling inotify-sys v0.1.5 [INFO] [stderr] Compiling openssl v0.10.38 [INFO] [stderr] Compiling generic-array v0.14.5 [INFO] [stderr] Compiling openssl-probe v0.1.5 [INFO] [stderr] Compiling itoa v1.0.1 [INFO] [stderr] Compiling same-file v1.0.6 [INFO] [stderr] Compiling hashbrown v0.11.2 [INFO] [stderr] Compiling slab v0.4.5 [INFO] [stderr] Compiling ryu v1.0.9 [INFO] [stderr] Compiling walkdir v2.3.2 [INFO] [stderr] Compiling futures-util v0.3.21 [INFO] [stderr] Compiling crossbeam-channel v0.5.2 [INFO] [stderr] Compiling inotify v0.9.6 [INFO] [stderr] Compiling os_str_bytes v6.0.0 [INFO] [stderr] Compiling tracing v0.1.31 [INFO] [stderr] Compiling tracing-log v0.1.2 [INFO] [stderr] Compiling thread_local v1.1.4 [INFO] [stderr] Compiling cipher v0.3.0 [INFO] [stderr] Compiling universal-hash v0.4.1 [INFO] [stderr] Compiling digest v0.9.0 [INFO] [stderr] Compiling polyval v0.5.3 [INFO] [stderr] Compiling aead v0.4.3 [INFO] [stderr] Compiling aes v0.7.5 [INFO] [stderr] Compiling curve25519-dalek v3.2.1 [INFO] [stderr] Compiling ghash v0.4.4 [INFO] [stderr] Compiling ctr v0.8.0 [INFO] [stderr] Compiling chacha20 v0.7.3 [INFO] [stderr] Compiling poly1305 v0.7.2 [INFO] [stderr] Compiling crypto-mac v0.8.0 [INFO] [stderr] Compiling block-buffer v0.9.0 [INFO] [stderr] Compiling crypto-common v0.1.3 [INFO] [stderr] Compiling blake2 v0.9.2 [INFO] [stderr] Compiling block-buffer v0.10.2 [INFO] [stderr] Compiling indexmap v1.8.0 [INFO] [stderr] Compiling sha2 v0.9.9 [INFO] [stderr] Compiling mio v0.7.14 [INFO] [stderr] Compiling pin-project v1.0.10 [INFO] [stderr] Compiling httparse v1.6.0 [INFO] [stderr] Compiling aes-gcm v0.9.4 [INFO] [stderr] Compiling chacha20poly1305 v0.8.2 [INFO] [stderr] Compiling digest v0.10.3 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling sharded-slab v0.1.4 [INFO] [stderr] Compiling x25519-dalek v1.2.0 [INFO] [stderr] Compiling regex-automata v0.1.10 [INFO] [stderr] Compiling regex v1.5.5 [INFO] [stderr] Compiling snow v0.8.0 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling filetime v0.2.15 [INFO] [stderr] Compiling instant v0.1.12 [INFO] [stderr] Compiling native-tls v0.2.8 [INFO] [stderr] Compiling ansi_term v0.12.1 [INFO] [stderr] Compiling strsim v0.10.0 [INFO] [stderr] Compiling termcolor v1.1.3 [INFO] [stderr] Compiling base64 v0.13.0 [INFO] [stderr] Compiling matchers v0.0.1 [INFO] [stderr] Compiling textwrap v0.15.0 [INFO] [stderr] Compiling notify v5.0.0-pre.13 [INFO] [stderr] Compiling clap v3.1.6 [INFO] [stderr] Compiling sha2 v0.10.2 [INFO] [stderr] Compiling fdlimit v0.2.1 [INFO] [stderr] Compiling hex v0.4.3 [INFO] [stderr] Compiling bytes v1.1.0 [INFO] [stderr] Compiling tracing-serde v0.1.3 [INFO] [stderr] Compiling serde_json v1.0.79 [INFO] [stderr] Compiling tokio v1.17.0 [INFO] [stderr] Compiling bincode v1.3.3 [INFO] [stderr] Compiling toml v0.5.8 [INFO] [stderr] Compiling url v2.2.2 [INFO] [stderr] Compiling tracing-subscriber v0.2.25 [INFO] [stderr] Compiling async-http-proxy v1.2.5 [INFO] [stderr] Compiling tokio-native-tls v0.3.0 [INFO] [stderr] Compiling snowstorm v0.3.1 [INFO] [stderr] Compiling async-socks5 v0.5.1 [INFO] [stderr] Compiling backoff v0.4.0 [INFO] [stderr] Compiling rathole v0.4.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 3m 07s [INFO] running `Command { std: "docker" "inspect" "75fdf1e37576fee267c912eeb6540f1631bc6b843529fd1487182fece907e57b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "75fdf1e37576fee267c912eeb6540f1631bc6b843529fd1487182fece907e57b", kill_on_drop: false }` [INFO] [stdout] 75fdf1e37576fee267c912eeb6540f1631bc6b843529fd1487182fece907e57b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid -Copt-level=3" "-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:7c93e6f9a94679f34221d1005849d4c83862f85b14a264fa0883942f4d043a66" "/opt/rustwide/cargo-home/bin/cargo" "+613d1972a772987ed4ee06faccbab0e2fc839871" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] c9c37463bbc6beaba49dd6b331599b99431656ec048f990f03a03ab7cbb8cd4f [INFO] running `Command { std: "docker" "start" "-a" "c9c37463bbc6beaba49dd6b331599b99431656ec048f990f03a03ab7cbb8cd4f", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.64s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rathole-a4d338eabfca104e) [INFO] [stdout] [INFO] [stdout] running 7 tests [INFO] [stdout] test config::tests::test_example_config ... ok [INFO] [stdout] test config_watcher::test::test_calculate_events ... ok [INFO] [stdout] test config::tests::test_validate_server_config ... ok [INFO] [stdout] test tests::test_determine_run_mode ... ok [INFO] [stdout] test config::tests::test_valid_config ... ok [INFO] [stdout] test config::tests::test_validate_client_config ... ok [INFO] [stdout] test config::tests::test_invalid_config ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s [INFO] [stdout] [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/rathole-31ec9bf68233c696) [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/integration_test.rs (/opt/rustwide/target/debug/deps/integration_test-946f1e482d5da1cc) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] Jan 22 06:28:10.555  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: start the client [INFO] [stdout] Jan 22 06:28:10.555  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: start the client [INFO] [stdout] Jan 22 06:28:10.591  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:10.591  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Jan 22 06:28:10.591  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Jan 22 06:28:10.592 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2332: Connection refused (os error 111). Retry in 469.259743ms... [INFO] [stdout] Jan 22 06:28:10.592 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2332: Connection refused (os error 111). Retry in 522.176437ms... [INFO] [stdout] Jan 22 06:28:10.595  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:10.595  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Jan 22 06:28:10.595  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Jan 22 06:28:10.596 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2333: Connection refused (os error 111). Retry in 521.733582ms... [INFO] [stdout] Jan 22 06:28:10.596 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2333: Connection refused (os error 111). Retry in 534.912964ms... [INFO] [stdout] Jan 22 06:28:11.062 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2332: Connection refused (os error 111). Retry in 742.770731ms... [INFO] [stdout] Jan 22 06:28:11.115 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2332: Connection refused (os error 111). Retry in 782.667374ms... [INFO] [stdout] Jan 22 06:28:11.118 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2333: Connection refused (os error 111). Retry in 727.745237ms... [INFO] [stdout] Jan 22 06:28:11.132 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2333: Connection refused (os error 111). Retry in 710.465283ms... [INFO] [stdout] Jan 22 06:28:11.564  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: start the server [INFO] [stdout] Jan 22 06:28:11.564  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: start the server [INFO] [stdout] Jan 22 06:28:11.571  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:11.571  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Jan 22 06:28:11.571  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:11.575  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Jan 22 06:28:11.806  INFO connection{addr=127.0.0.1:46728}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:11.806  INFO connection{addr=127.0.0.1:46728}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Jan 22 06:28:11.806  INFO connection{addr=127.0.0.1:46728}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Jan 22 06:28:11.806  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:11.843  INFO connection{addr=127.0.0.1:38926}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:11.843  INFO connection{addr=127.0.0.1:38926}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Jan 22 06:28:11.843  INFO connection{addr=127.0.0.1:38926}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Jan 22 06:28:11.843  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:11.847  INFO connection{addr=127.0.0.1:38996}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:11.847  INFO connection{addr=127.0.0.1:38996}: rathole::server: Control channel established service=echo [INFO] [stdout] Jan 22 06:28:11.847  INFO connection{addr=127.0.0.1:38996}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Jan 22 06:28:11.847  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:11.924  INFO connection{addr=127.0.0.1:46748}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:11.924  INFO connection{addr=127.0.0.1:46748}: rathole::server: Control channel established service=echo [INFO] [stdout] Jan 22 06:28:11.924  INFO connection{addr=127.0.0.1:46748}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Jan 22 06:28:11.924  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:14.066  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Jan 22 06:28:14.066  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Jan 22 06:28:14.072  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Jan 22 06:28:14.078  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: shutdown the client [INFO] [stdout] Jan 22 06:28:14.078  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:14.078  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:14.078  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:14.078  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: restart the client [INFO] [stdout] Jan 22 06:28:14.087  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:14.087  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Jan 22 06:28:14.087  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Jan 22 06:28:14.088  INFO connection{addr=127.0.0.1:39106}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:14.088  INFO connection{addr=127.0.0.1:39114}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:14.088  WARN connection{addr=127.0.0.1:39106}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Jan 22 06:28:14.088  INFO connection{addr=127.0.0.1:39106}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Jan 22 06:28:14.088  WARN connection{addr=127.0.0.1:39114}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Jan 22 06:28:14.088  INFO connection{addr=127.0.0.1:39114}: rathole::server: Control channel established service=echo [INFO] [stdout] Jan 22 06:28:14.088  INFO connection{addr=127.0.0.1:38926}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Jan 22 06:28:14.088  INFO connection{addr=127.0.0.1:38926}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:14.088  INFO connection{addr=127.0.0.1:38996}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Jan 22 06:28:14.088  INFO connection{addr=127.0.0.1:38996}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:14.088  INFO connection{addr=127.0.0.1:38926}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:14.088  INFO connection{addr=127.0.0.1:39106}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Jan 22 06:28:14.088  INFO connection{addr=127.0.0.1:38996}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:14.088  INFO connection{addr=127.0.0.1:39114}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Jan 22 06:28:14.088  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:14.088  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:14.088  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:14.088  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:14.088  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:14.089  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:14.089  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:14.089  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:14.089  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:14.089  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:14.089  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:14.089  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:14.089  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:14.089  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:14.089  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:14.089  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:14.089  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:14.089  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:14.303  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Jan 22 06:28:14.543  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: shutdown the client [INFO] [stdout] Jan 22 06:28:14.543  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:14.544  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:14.544  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:14.544  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: restart the client [INFO] [stdout] Jan 22 06:28:14.544  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:14.544  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Jan 22 06:28:14.544  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Jan 22 06:28:14.544  INFO connection{addr=127.0.0.1:46766}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:14.544  INFO connection{addr=127.0.0.1:46770}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:14.544  WARN connection{addr=127.0.0.1:46766}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Jan 22 06:28:14.544  INFO connection{addr=127.0.0.1:46766}: rathole::server: Control channel established service=echo [INFO] [stdout] Jan 22 06:28:14.544  WARN connection{addr=127.0.0.1:46770}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Jan 22 06:28:14.544  INFO connection{addr=127.0.0.1:46770}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Jan 22 06:28:14.545  INFO connection{addr=127.0.0.1:46748}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:14.545  INFO connection{addr=127.0.0.1:46766}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Jan 22 06:28:14.545  INFO connection{addr=127.0.0.1:46728}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:14.545  INFO connection{addr=127.0.0.1:46770}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Jan 22 06:28:14.545  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:14.545  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:14.545  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Jan 22 06:28:14.545  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:14.545  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Jan 22 06:28:14.545  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.079  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Jan 22 06:28:15.084  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Jan 22 06:28:15.089  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: shutdown the server [INFO] [stdout] Jan 22 06:28:15.089  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:15.089  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Jan 22 06:28:15.089  INFO rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:15.089  INFO connection{addr=127.0.0.1:39114}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Jan 22 06:28:15.089  INFO connection{addr=127.0.0.1:39114}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:15.089  INFO connection{addr=127.0.0.1:39106}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Jan 22 06:28:15.089  INFO connection{addr=127.0.0.1:39106}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:15.089  INFO connection{addr=127.0.0.1:39114}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:15.090  INFO connection{addr=127.0.0.1:39106}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:15.090  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: restart the server [INFO] [stdout] Jan 22 06:28:15.090 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 504.759192ms... [INFO] [stdout] Jan 22 06:28:15.090 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 522.451153ms... [INFO] [stdout] Jan 22 06:28:15.090  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.090  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.090  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.090  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.090  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.090  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.090  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.090  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.090  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.091  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.091  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.091  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.091  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.091  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.091  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.091  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.095  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:15.095  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Jan 22 06:28:15.545  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Jan 22 06:28:15.596  INFO connection{addr=127.0.0.1:39268}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:15.596  INFO connection{addr=127.0.0.1:39268}: rathole::server: Control channel established service=echo [INFO] [stdout] Jan 22 06:28:15.596  INFO connection{addr=127.0.0.1:39268}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Jan 22 06:28:15.597  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:15.613  INFO connection{addr=127.0.0.1:39324}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:15.613  INFO connection{addr=127.0.0.1:39324}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Jan 22 06:28:15.614  INFO connection{addr=127.0.0.1:39324}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Jan 22 06:28:15.614  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:15.767  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Jan 22 06:28:15.991  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: shutdown the server [INFO] [stdout] Jan 22 06:28:15.991  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:15.992  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Jan 22 06:28:15.992  INFO rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:15.992  INFO connection{addr=127.0.0.1:46770}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:15.992  INFO connection{addr=127.0.0.1:46766}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:15.992  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: restart the server [INFO] [stdout] Jan 22 06:28:15.992 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 511.883523ms... [INFO] [stdout] Jan 22 06:28:15.992  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Jan 22 06:28:15.992  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.992 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 545.194367ms... [INFO] [stdout] Jan 22 06:28:15.992  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Jan 22 06:28:15.992  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:15.993  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:15.993  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Jan 22 06:28:16.506  INFO connection{addr=127.0.0.1:39446}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:16.506  INFO connection{addr=127.0.0.1:39446}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Jan 22 06:28:16.506  INFO connection{addr=127.0.0.1:39446}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Jan 22 06:28:16.506  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:16.538  INFO connection{addr=127.0.0.1:39474}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:16.538  INFO connection{addr=127.0.0.1:39474}: rathole::server: Control channel established service=echo [INFO] [stdout] Jan 22 06:28:16.539  INFO connection{addr=127.0.0.1:39474}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Jan 22 06:28:16.539  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:17.591  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: lots of echo and pingpong [INFO] [stdout] Jan 22 06:28:17.611  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: shutdown the server and the client [INFO] [stdout] Jan 22 06:28:17.611  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:17.612  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:17.612  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Jan 22 06:28:17.612  INFO rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:17.613  INFO connection{addr=127.0.0.1:39324}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Jan 22 06:28:17.613  INFO connection{addr=127.0.0.1:39324}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:17.613  INFO connection{addr=127.0.0.1:39268}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Jan 22 06:28:17.614  INFO connection{addr=127.0.0.1:39268}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:17.614  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:17.615  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:17.615  INFO connection{addr=127.0.0.1:39324}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:17.615  INFO connection{addr=127.0.0.1:39268}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:17.616  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: start the client [INFO] [stdout] Jan 22 06:28:17.616  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:17.617  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:17.617  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:17.617  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:17.618  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:17.618  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:17.618  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:17.619  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:17.619  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:17.619  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:17.620  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:17.620  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:17.621  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:17.621  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:17.621  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:17.622  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:17.631  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:17.736  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Jan 22 06:28:17.736  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Jan 22 06:28:17.737 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2333: Connection refused (os error 111). Retry in 547.268908ms... [INFO] [stdout] Jan 22 06:28:17.737 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2333: Connection refused (os error 111). Retry in 536.853239ms... [INFO] [stdout] Jan 22 06:28:18.275 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2333: Connection refused (os error 111). Retry in 683.017186ms... [INFO] [stdout] Jan 22 06:28:18.285 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2333: Connection refused (os error 111). Retry in 690.327757ms... [INFO] [stdout] Jan 22 06:28:18.494  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: lots of echo and pingpong [INFO] [stdout] Jan 22 06:28:18.617  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: start the server [INFO] [stdout] Jan 22 06:28:18.617  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:18.628  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Jan 22 06:28:18.723  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: shutdown the server and the client [INFO] [stdout] Jan 22 06:28:18.724  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:18.724  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:18.724  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Jan 22 06:28:18.724  INFO rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:18.724  INFO connection{addr=127.0.0.1:39474}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:18.724  INFO connection{addr=127.0.0.1:39446}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:18.724  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:18.724  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:18.724  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: start the client [INFO] [stdout] Jan 22 06:28:18.724  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Jan 22 06:28:18.724  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:18.724  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Jan 22 06:28:18.724  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:18.725  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:18.811  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Jan 22 06:28:18.811  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Jan 22 06:28:18.811 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2332: Connection refused (os error 111). Retry in 485.645513ms... [INFO] [stdout] Jan 22 06:28:18.811 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2332: Connection refused (os error 111). Retry in 454.497714ms... [INFO] [stdout] Jan 22 06:28:18.971  INFO connection{addr=127.0.0.1:47742}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:18.971  INFO connection{addr=127.0.0.1:47742}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Jan 22 06:28:18.971  INFO connection{addr=127.0.0.1:47742}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Jan 22 06:28:18.971  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:19.064  INFO connection{addr=127.0.0.1:47818}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:19.064  INFO connection{addr=127.0.0.1:47818}: rathole::server: Control channel established service=echo [INFO] [stdout] Jan 22 06:28:19.065  INFO connection{addr=127.0.0.1:47818}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Jan 22 06:28:19.065  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:19.267 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2332: Connection refused (os error 111). Retry in 692.939458ms... [INFO] [stdout] Jan 22 06:28:19.298 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2332: Connection refused (os error 111). Retry in 780.279439ms... [INFO] [stdout] Jan 22 06:28:19.725  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: start the server [INFO] [stdout] Jan 22 06:28:19.725  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:19.736  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Jan 22 06:28:19.972  INFO connection{addr=127.0.0.1:39536}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:19.972  INFO connection{addr=127.0.0.1:39536}: rathole::server: Control channel established service=echo [INFO] [stdout] Jan 22 06:28:19.972  INFO connection{addr=127.0.0.1:39536}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Jan 22 06:28:19.972  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:20.088  INFO connection{addr=127.0.0.1:39578}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:20.088  INFO connection{addr=127.0.0.1:39578}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Jan 22 06:28:20.088  INFO connection{addr=127.0.0.1:39578}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Jan 22 06:28:20.088  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:21.118  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Jan 22 06:28:21.166  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Jan 22 06:28:21.214  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: shutdown the client [INFO] [stdout] Jan 22 06:28:21.215  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:21.215  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:21.215  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:21.215  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: restart the client [INFO] [stdout] Jan 22 06:28:21.219  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:21.304  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Jan 22 06:28:21.304  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Jan 22 06:28:21.320  INFO connection{addr=127.0.0.1:47938}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:21.323  WARN connection{addr=127.0.0.1:47938}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Jan 22 06:28:21.323  INFO connection{addr=127.0.0.1:47938}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Jan 22 06:28:21.323  INFO connection{addr=127.0.0.1:47742}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Jan 22 06:28:21.327  INFO connection{addr=127.0.0.1:47742}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:21.328  INFO connection{addr=127.0.0.1:47742}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:21.328  INFO connection{addr=127.0.0.1:47938}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Jan 22 06:28:21.328  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:21.328  INFO connection{addr=127.0.0.1:47950}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:21.328  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:21.329  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:21.329  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:21.329  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:21.329  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:21.329  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:21.329  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:21.329  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:21.331  WARN connection{addr=127.0.0.1:47950}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Jan 22 06:28:21.332  INFO connection{addr=127.0.0.1:47950}: rathole::server: Control channel established service=echo [INFO] [stdout] Jan 22 06:28:21.338  INFO connection{addr=127.0.0.1:47818}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Jan 22 06:28:21.339  INFO connection{addr=127.0.0.1:47818}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:21.339  INFO connection{addr=127.0.0.1:47818}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:21.340  INFO connection{addr=127.0.0.1:47950}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Jan 22 06:28:21.340  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:21.343  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:21.343  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:21.343  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:21.343  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:21.344  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:21.344  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:21.344  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:21.345  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.216  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Jan 22 06:28:22.226  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Jan 22 06:28:22.267  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Jan 22 06:28:22.314  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: shutdown the server [INFO] [stdout] Jan 22 06:28:22.314  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:22.314  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Jan 22 06:28:22.314  INFO rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:22.314  INFO connection{addr=127.0.0.1:47950}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Jan 22 06:28:22.315  INFO connection{addr=127.0.0.1:47950}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:22.315  INFO connection{addr=127.0.0.1:47938}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Jan 22 06:28:22.315  INFO connection{addr=127.0.0.1:47938}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:22.315  INFO connection{addr=127.0.0.1:47950}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:22.315  INFO connection{addr=127.0.0.1:47938}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:22.315  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: restart the server [INFO] [stdout] Jan 22 06:28:22.316 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 529.941831ms... [INFO] [stdout] Jan 22 06:28:22.316 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 529.352336ms... [INFO] [stdout] Jan 22 06:28:22.316  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.316  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.316  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.316  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.316  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.317  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.317  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.317  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.317  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.317  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.317  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.317  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.317  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.317  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.318  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.318  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.323  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:22.330  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Jan 22 06:28:22.443  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Jan 22 06:28:22.663  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: shutdown the client [INFO] [stdout] Jan 22 06:28:22.664  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:22.664  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:22.664  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:22.664  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: restart the client [INFO] [stdout] Jan 22 06:28:22.664  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:22.746  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Jan 22 06:28:22.746  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Jan 22 06:28:22.762  INFO connection{addr=127.0.0.1:39596}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:22.764  WARN connection{addr=127.0.0.1:39596}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Jan 22 06:28:22.764  INFO connection{addr=127.0.0.1:39596}: rathole::server: Control channel established service=echo [INFO] [stdout] Jan 22 06:28:22.764  INFO connection{addr=127.0.0.1:39536}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:22.764  INFO connection{addr=127.0.0.1:39596}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Jan 22 06:28:22.764  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:22.764  INFO connection{addr=127.0.0.1:39602}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:22.764  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Jan 22 06:28:22.764  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.765  WARN connection{addr=127.0.0.1:39602}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Jan 22 06:28:22.765  INFO connection{addr=127.0.0.1:39602}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Jan 22 06:28:22.772  INFO connection{addr=127.0.0.1:39578}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:22.772  INFO connection{addr=127.0.0.1:39602}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Jan 22 06:28:22.772  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:22.774  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Jan 22 06:28:22.774  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:22.872  INFO connection{addr=127.0.0.1:48116}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:22.875  INFO connection{addr=127.0.0.1:48116}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Jan 22 06:28:22.875  INFO connection{addr=127.0.0.1:48116}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Jan 22 06:28:22.875  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:22.875  INFO connection{addr=127.0.0.1:48128}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:22.877  INFO connection{addr=127.0.0.1:48128}: rathole::server: Control channel established service=echo [INFO] [stdout] Jan 22 06:28:22.888  INFO connection{addr=127.0.0.1:48128}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Jan 22 06:28:22.889  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:23.665  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Jan 22 06:28:23.891  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Jan 22 06:28:24.127  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: shutdown the server [INFO] [stdout] Jan 22 06:28:24.127  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:24.128  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Jan 22 06:28:24.128  INFO rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:24.128  INFO connection{addr=127.0.0.1:39596}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:24.128  INFO connection{addr=127.0.0.1:39602}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:24.128  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: restart the server [INFO] [stdout] Jan 22 06:28:24.128 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 530.249729ms... [INFO] [stdout] Jan 22 06:28:24.128  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Jan 22 06:28:24.128  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:24.128 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 464.012211ms... [INFO] [stdout] Jan 22 06:28:24.128  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Jan 22 06:28:24.128  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:24.129  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:24.135  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Jan 22 06:28:24.605  INFO connection{addr=127.0.0.1:39642}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:24.605  INFO connection{addr=127.0.0.1:39642}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Jan 22 06:28:24.605  INFO connection{addr=127.0.0.1:39642}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Jan 22 06:28:24.606  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:24.668  INFO connection{addr=127.0.0.1:39684}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:24.669  INFO connection{addr=127.0.0.1:39684}: rathole::server: Control channel established service=echo [INFO] [stdout] Jan 22 06:28:24.669  INFO connection{addr=127.0.0.1:39684}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Jan 22 06:28:24.669  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:24.817  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: lots of echo and pingpong [INFO] [stdout] Jan 22 06:28:24.927  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: shutdown the server and the client [INFO] [stdout] Jan 22 06:28:24.927  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:24.927  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:24.927  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Jan 22 06:28:24.927  INFO rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:24.928  INFO connection{addr=127.0.0.1:48116}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Jan 22 06:28:24.928  INFO connection{addr=127.0.0.1:48116}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:24.928  INFO connection{addr=127.0.0.1:48128}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Jan 22 06:28:24.928  INFO connection{addr=127.0.0.1:48128}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:24.928  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:24.928  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:24.928  INFO connection{addr=127.0.0.1:48116}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:24.929  INFO connection{addr=127.0.0.1:48128}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:24.929  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: start the client [INFO] [stdout] Jan 22 06:28:24.930  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:24.930  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:24.930  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:24.930  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:24.931  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:24.931  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:24.931  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:24.931  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:24.931  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:24.932  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:24.932  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:24.932  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:24.933  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:24.933  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:24.933  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:24.933  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:24.939  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:24.940  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Jan 22 06:28:24.940  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Jan 22 06:28:24.940 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2333: Failed to connect TCP socket: Connection refused (os error 111). Retry in 538.326697ms... [INFO] [stdout] Jan 22 06:28:24.941 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2333: Failed to connect TCP socket: Connection refused (os error 111). Retry in 492.577803ms... [INFO] [stdout] Jan 22 06:28:25.435 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2333: Failed to connect TCP socket: Connection refused (os error 111). Retry in 748.373494ms... [INFO] [stdout] Jan 22 06:28:25.480 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2333: Failed to connect TCP socket: Connection refused (os error 111). Retry in 776.235574ms... [INFO] [stdout] Jan 22 06:28:25.931  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: start the server [INFO] [stdout] Jan 22 06:28:25.931  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:25.931  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Jan 22 06:28:26.185  INFO connection{addr=127.0.0.1:55830}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:26.185  INFO connection{addr=127.0.0.1:55830}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Jan 22 06:28:26.185  INFO connection{addr=127.0.0.1:55830}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Jan 22 06:28:26.185  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:26.260  INFO connection{addr=127.0.0.1:55902}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:26.260  INFO connection{addr=127.0.0.1:55902}: rathole::server: Control channel established service=echo [INFO] [stdout] Jan 22 06:28:26.260  INFO connection{addr=127.0.0.1:55902}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Jan 22 06:28:26.260  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:26.629  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: lots of echo and pingpong [INFO] [stdout] Jan 22 06:28:26.868  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: shutdown the server and the client [INFO] [stdout] Jan 22 06:28:26.868  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:26.868  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:26.868  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Jan 22 06:28:26.868  INFO rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:26.868  INFO connection{addr=127.0.0.1:39642}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:26.868  INFO connection{addr=127.0.0.1:39684}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:26.868  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:26.868  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:26.868  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: start the client [INFO] [stdout] Jan 22 06:28:26.868  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Jan 22 06:28:26.868  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:26.868  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Jan 22 06:28:26.869  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:26.870  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:26.870  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Jan 22 06:28:26.870  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Jan 22 06:28:26.870 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2332: Failed to connect TCP socket: Connection refused (os error 111). Retry in 467.502041ms... [INFO] [stdout] Jan 22 06:28:26.870 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2332: Failed to connect TCP socket: Connection refused (os error 111). Retry in 462.186407ms... [INFO] [stdout] Jan 22 06:28:27.333 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2332: Failed to connect TCP socket: Connection refused (os error 111). Retry in 785.219909ms... [INFO] [stdout] Jan 22 06:28:27.339 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2332: Failed to connect TCP socket: Connection refused (os error 111). Retry in 767.138676ms... [INFO] [stdout] Jan 22 06:28:27.869  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: start the server [INFO] [stdout] Jan 22 06:28:27.869  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:27.869  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Jan 22 06:28:28.108  INFO connection{addr=127.0.0.1:56266}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:28.108  INFO connection{addr=127.0.0.1:56266}: rathole::server: Control channel established service=echo [INFO] [stdout] Jan 22 06:28:28.108  INFO connection{addr=127.0.0.1:56266}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Jan 22 06:28:28.108  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:28.120  INFO connection{addr=127.0.0.1:56300}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:28.120  INFO connection{addr=127.0.0.1:56300}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Jan 22 06:28:28.120  INFO connection{addr=127.0.0.1:56300}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Jan 22 06:28:28.120  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:28.432  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Jan 22 06:28:28.483  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Jan 22 06:28:28.529  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: shutdown the client [INFO] [stdout] Jan 22 06:28:28.529  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:28.529  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:28.529  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:28.530  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: restart the client [INFO] [stdout] Jan 22 06:28:28.535  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:28.535  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Jan 22 06:28:28.535  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Jan 22 06:28:28.538  INFO connection{addr=127.0.0.1:55990}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:28.538  INFO connection{addr=127.0.0.1:56000}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:28.538  WARN connection{addr=127.0.0.1:55990}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Jan 22 06:28:28.538  INFO connection{addr=127.0.0.1:55990}: rathole::server: Control channel established service=echo [INFO] [stdout] Jan 22 06:28:28.538  INFO connection{addr=127.0.0.1:55902}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Jan 22 06:28:28.538  INFO connection{addr=127.0.0.1:55902}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:28.538  INFO connection{addr=127.0.0.1:55902}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:28.538  INFO connection{addr=127.0.0.1:55990}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Jan 22 06:28:28.539  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:28.539  WARN connection{addr=127.0.0.1:56000}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Jan 22 06:28:28.539  INFO connection{addr=127.0.0.1:56000}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Jan 22 06:28:28.539  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:28.539  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:28.539  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:28.539  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:28.539  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:28.539  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:28.539  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:28.539  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:28.540  INFO connection{addr=127.0.0.1:55830}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Jan 22 06:28:28.540  INFO connection{addr=127.0.0.1:55830}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:28.540  INFO connection{addr=127.0.0.1:55830}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:28.541  INFO connection{addr=127.0.0.1:56000}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Jan 22 06:28:28.541  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:28.544  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:28.545  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:28.545  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:28.545  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:28.545  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:28.546  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:28.546  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:28.546  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:29.531  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Jan 22 06:28:29.583  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Jan 22 06:28:29.634  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: shutdown the server [INFO] [stdout] Jan 22 06:28:29.634  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:29.634  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Jan 22 06:28:29.634  INFO rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:29.634  INFO connection{addr=127.0.0.1:56000}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Jan 22 06:28:29.634  INFO connection{addr=127.0.0.1:56000}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:29.634  INFO connection{addr=127.0.0.1:55990}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Jan 22 06:28:29.634  INFO connection{addr=127.0.0.1:55990}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:29.634  INFO connection{addr=127.0.0.1:56000}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:29.634  INFO connection{addr=127.0.0.1:55990}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:29.635  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: restart the server [INFO] [stdout] Jan 22 06:28:29.635 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 506.244477ms... [INFO] [stdout] Jan 22 06:28:29.635 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 515.998064ms... [INFO] [stdout] Jan 22 06:28:29.635  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:29.635  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:29.635  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:29.635  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:29.635  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:29.635  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:29.636  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:29.636  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:29.636  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:29.636  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:29.636  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:29.636  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:29.636  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:29.636  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:29.636  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:29.637  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:29.642  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:29.642  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Jan 22 06:28:30.143  INFO connection{addr=127.0.0.1:56168}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:30.143  INFO connection{addr=127.0.0.1:56168}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Jan 22 06:28:30.144  INFO connection{addr=127.0.0.1:56168}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Jan 22 06:28:30.144  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:30.154  INFO connection{addr=127.0.0.1:56240}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:30.154  INFO connection{addr=127.0.0.1:56240}: rathole::server: Control channel established service=echo [INFO] [stdout] Jan 22 06:28:30.154  INFO connection{addr=127.0.0.1:56240}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Jan 22 06:28:30.154  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:30.370  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Jan 22 06:28:30.595  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Jan 22 06:28:30.819  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: shutdown the client [INFO] [stdout] Jan 22 06:28:30.819  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:30.820  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:30.820  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:30.820  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: restart the client [INFO] [stdout] Jan 22 06:28:30.820  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:30.820  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Jan 22 06:28:30.820  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Jan 22 06:28:30.822  INFO connection{addr=127.0.0.1:56332}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:30.822  INFO connection{addr=127.0.0.1:56340}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:30.822  WARN connection{addr=127.0.0.1:56332}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Jan 22 06:28:30.822  INFO connection{addr=127.0.0.1:56332}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Jan 22 06:28:30.822  INFO connection{addr=127.0.0.1:56300}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:30.823  INFO connection{addr=127.0.0.1:56332}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Jan 22 06:28:30.823  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:30.823  WARN connection{addr=127.0.0.1:56340}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Jan 22 06:28:30.823  INFO connection{addr=127.0.0.1:56340}: rathole::server: Control channel established service=echo [INFO] [stdout] Jan 22 06:28:30.823  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Jan 22 06:28:30.823  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:30.823  INFO connection{addr=127.0.0.1:56266}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:30.823  INFO connection{addr=127.0.0.1:56340}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Jan 22 06:28:30.823  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:30.824  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Jan 22 06:28:30.824  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:31.821  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Jan 22 06:28:32.039  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Jan 22 06:28:32.136  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: lots of echo and pingpong [INFO] [stdout] Jan 22 06:28:32.202  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: shutdown the server and the client [INFO] [stdout] Jan 22 06:28:32.202  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:32.202  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:32.202  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Jan 22 06:28:32.202  INFO rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:32.202  INFO connection{addr=127.0.0.1:56168}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Jan 22 06:28:32.202  INFO connection{addr=127.0.0.1:56168}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:32.203  INFO connection{addr=127.0.0.1:56240}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Jan 22 06:28:32.203  INFO connection{addr=127.0.0.1:56240}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:32.203  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:32.203  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:32.203  INFO connection{addr=127.0.0.1:56168}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:32.203  INFO connection{addr=127.0.0.1:56240}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] test tcp ... ok [INFO] [stdout] Jan 22 06:28:32.263  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: shutdown the server [INFO] [stdout] Jan 22 06:28:32.263  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:32.264  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Jan 22 06:28:32.264  INFO rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:32.264  INFO connection{addr=127.0.0.1:56340}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:32.264  INFO connection{addr=127.0.0.1:56332}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:32.264  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: restart the server [INFO] [stdout] Jan 22 06:28:32.264 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 514.60953ms... [INFO] [stdout] Jan 22 06:28:32.264  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Jan 22 06:28:32.264  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:32.264 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 532.37049ms... [INFO] [stdout] Jan 22 06:28:32.264  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Jan 22 06:28:32.264  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Jan 22 06:28:32.264  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Jan 22 06:28:32.264  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Jan 22 06:28:32.781  INFO connection{addr=127.0.0.1:56386}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:32.781  INFO connection{addr=127.0.0.1:56386}: rathole::server: Control channel established service=echo [INFO] [stdout] Jan 22 06:28:32.781  INFO connection{addr=127.0.0.1:56386}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Jan 22 06:28:32.781  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:32.799  INFO connection{addr=127.0.0.1:56416}: rathole::server: Try to handshake a control channel [INFO] [stdout] Jan 22 06:28:32.799  INFO connection{addr=127.0.0.1:56416}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Jan 22 06:28:32.799  INFO connection{addr=127.0.0.1:56416}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Jan 22 06:28:32.799  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Jan 22 06:28:34.765  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: lots of echo and pingpong [INFO] [stdout] Jan 22 06:28:34.988  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: shutdown the server and the client [INFO] [stdout] Jan 22 06:28:34.988  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:34.988  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Jan 22 06:28:34.988  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Jan 22 06:28:34.988  INFO rathole::server: Shutdown [INFO] [stdout] Jan 22 06:28:34.988  INFO connection{addr=127.0.0.1:56386}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:34.988  INFO connection{addr=127.0.0.1:56416}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Jan 22 06:28:34.988  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Jan 22 06:28:34.988  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] test udp ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 24.44s [INFO] [stdout] [INFO] [stderr] Doc-tests rathole [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" "c9c37463bbc6beaba49dd6b331599b99431656ec048f990f03a03ab7cbb8cd4f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c9c37463bbc6beaba49dd6b331599b99431656ec048f990f03a03ab7cbb8cd4f", kill_on_drop: false }` [INFO] [stdout] c9c37463bbc6beaba49dd6b331599b99431656ec048f990f03a03ab7cbb8cd4f