[INFO] cloning repository https://github.com/rapiz1/rathole [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/rapiz1/rathole" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frapiz1%2Frathole", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frapiz1%2Frathole'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 8665e6a2cfb57ddfc4b0f5a8cf116b3a69d06521 [INFO] testing rapiz1/rathole against master#0d13f6afeba4935499abe0c9a07426c94492c94e for pr-96025 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frapiz1%2Frathole" "/workspace/builds/worker-15/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-15/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/rapiz1/rathole on toolchain 0d13f6afeba4935499abe0c9a07426c94492c94e [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+0d13f6afeba4935499abe0c9a07426c94492c94e" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking git repo https://github.com/rapiz1/rathole [INFO] finished tweaking git repo https://github.com/rapiz1/rathole [INFO] tweaked toml for git repo https://github.com/rapiz1/rathole written to /workspace/builds/worker-15/source/Cargo.toml [INFO] crate git repo https://github.com/rapiz1/rathole already has a lockfile, it will not be regenerated [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+0d13f6afeba4935499abe0c9a07426c94492c94e" "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] [stderr] Downloading crates ... [INFO] [stderr] Downloaded enum-iterator v0.7.0 [INFO] [stderr] Downloaded vergen v6.0.2 [INFO] [stderr] Downloaded tracing-subscriber v0.3.9 [INFO] [stderr] Downloaded async-http-proxy v1.2.5 [INFO] [stderr] Downloaded async-socks5 v0.5.1 [INFO] [stderr] Downloaded tonic-build v0.6.2 [INFO] [stderr] Downloaded tonic v0.6.2 [INFO] [stderr] Downloaded getset v0.1.2 [INFO] [stderr] Downloaded console-api v0.1.2 [INFO] [stderr] Downloaded console-subscriber v0.1.3 [INFO] [stderr] Downloaded notify v5.0.0-pre.13 [INFO] [stderr] Downloaded enum-iterator-derive v0.7.0 [INFO] [stderr] Downloaded hdrhistogram v7.5.0 [INFO] [stderr] Downloaded libgit2-sys v0.13.1+1.4.2 [INFO] [stderr] Downloaded snowstorm v0.3.1 [INFO] [stderr] Downloaded git2 v0.14.1 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-15/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-15/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:d190cb871061d98bc6d0581d85cb2ecb09a0f8a142ba5463de30be9999fc3251" "/opt/rustwide/cargo-home/bin/cargo" "+0d13f6afeba4935499abe0c9a07426c94492c94e" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 8ad0d60721c7b6f847179f2d91cdeb0b94cfc0d13951e91f441b5163e44b2124 [INFO] running `Command { std: "docker" "start" "-a" "8ad0d60721c7b6f847179f2d91cdeb0b94cfc0d13951e91f441b5163e44b2124", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "8ad0d60721c7b6f847179f2d91cdeb0b94cfc0d13951e91f441b5163e44b2124", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "8ad0d60721c7b6f847179f2d91cdeb0b94cfc0d13951e91f441b5163e44b2124", kill_on_drop: false }` [INFO] [stdout] 8ad0d60721c7b6f847179f2d91cdeb0b94cfc0d13951e91f441b5163e44b2124 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-15/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-15/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "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:d190cb871061d98bc6d0581d85cb2ecb09a0f8a142ba5463de30be9999fc3251" "/opt/rustwide/cargo-home/bin/cargo" "+0d13f6afeba4935499abe0c9a07426c94492c94e" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 923ffdb43ee365d06024c2dfc832533d3740e8457674d34e119436779c3268c3 [INFO] running `Command { std: "docker" "start" "-a" "923ffdb43ee365d06024c2dfc832533d3740e8457674d34e119436779c3268c3", 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] [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 once_cell v1.10.0 [INFO] [stderr] Compiling memchr v2.4.1 [INFO] [stderr] Compiling opaque-debug v0.3.0 [INFO] [stderr] Compiling smallvec v1.8.0 [INFO] [stderr] Compiling pin-project-lite v0.2.8 [INFO] [stderr] Compiling parking_lot_core v0.9.1 [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 rustversion v1.0.6 [INFO] [stderr] Compiling matches v0.1.9 [INFO] [stderr] Compiling getrandom v0.1.16 [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 unicode-bidi v0.3.7 [INFO] [stderr] Compiling ppv-lite86 v0.2.16 [INFO] [stderr] Compiling futures-core v0.3.21 [INFO] [stderr] Compiling openssl v0.10.38 [INFO] [stderr] Compiling crossbeam-utils v0.8.7 [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 futures-task v0.3.21 [INFO] [stderr] Compiling native-tls v0.2.8 [INFO] [stderr] Compiling serde_json v1.0.79 [INFO] [stderr] Compiling async-trait v0.1.52 [INFO] [stderr] Compiling futures-util v0.3.21 [INFO] [stderr] Compiling httparse v1.6.0 [INFO] [stderr] Compiling ryu v1.0.9 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling hashbrown v0.11.2 [INFO] [stderr] Compiling heck v0.4.0 [INFO] [stderr] Compiling openssl-probe v0.1.5 [INFO] [stderr] Compiling same-file v1.0.6 [INFO] [stderr] Compiling slab v0.4.5 [INFO] [stderr] Compiling itoa v1.0.1 [INFO] [stderr] Compiling strsim v0.10.0 [INFO] [stderr] Compiling base64 v0.13.0 [INFO] [stderr] Compiling termcolor v1.1.3 [INFO] [stderr] Compiling textwrap v0.15.0 [INFO] [stderr] Compiling ansi_term v0.12.1 [INFO] [stderr] Compiling hex v0.4.3 [INFO] [stderr] Compiling instant v0.1.12 [INFO] [stderr] Compiling tinyvec v1.5.1 [INFO] [stderr] Compiling lock_api v0.4.6 [INFO] [stderr] Compiling pest v2.1.3 [INFO] [stderr] Compiling tracing-core v0.1.23 [INFO] [stderr] Compiling sharded-slab v0.1.4 [INFO] [stderr] Compiling form_urlencoded v1.0.1 [INFO] [stderr] Compiling walkdir v2.3.2 [INFO] [stderr] Compiling foreign-types v0.3.2 [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 thread_local v1.1.4 [INFO] [stderr] Compiling tracing-log v0.1.2 [INFO] [stderr] Compiling num-traits v0.2.14 [INFO] [stderr] Compiling num-integer v0.1.44 [INFO] [stderr] Compiling indexmap v1.8.0 [INFO] [stderr] Compiling quote v1.0.15 [INFO] [stderr] Compiling crossbeam-channel v0.5.2 [INFO] [stderr] Compiling os_str_bytes v6.0.0 [INFO] [stderr] Compiling semver-parser v0.10.2 [INFO] [stderr] Compiling unicode-normalization v0.1.19 [INFO] [stderr] Compiling num_cpus v1.13.1 [INFO] [stderr] Compiling getrandom v0.2.5 [INFO] [stderr] Compiling mio v0.8.0 [INFO] [stderr] Compiling signal-hook-registry v1.4.0 [INFO] [stderr] Compiling socket2 v0.4.4 [INFO] [stderr] Compiling inotify-sys v0.1.5 [INFO] [stderr] Compiling mio v0.7.14 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling filetime v0.2.15 [INFO] [stderr] Compiling fdlimit v0.2.1 [INFO] [stderr] Compiling jobserver v0.1.24 [INFO] [stderr] Compiling time v0.1.43 [INFO] [stderr] Compiling inotify v0.9.6 [INFO] [stderr] Compiling rand_core v0.6.3 [INFO] [stderr] Compiling rand_core v0.5.1 [INFO] [stderr] Compiling cc v1.0.73 [INFO] [stderr] Compiling parking_lot v0.12.0 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling semver v0.11.0 [INFO] [stderr] Compiling notify v5.0.0-pre.13 [INFO] [stderr] Compiling rustc_version v0.3.3 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling idna v0.2.3 [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 aead v0.4.3 [INFO] [stderr] Compiling crypto-mac v0.8.0 [INFO] [stderr] Compiling block-buffer v0.9.0 [INFO] [stderr] Compiling block-buffer v0.10.2 [INFO] [stderr] Compiling crypto-common v0.1.3 [INFO] [stderr] Compiling snow v0.8.0 [INFO] [stderr] Compiling polyval v0.5.3 [INFO] [stderr] Compiling poly1305 v0.7.2 [INFO] [stderr] Compiling sha2 v0.9.9 [INFO] [stderr] Compiling blake2 v0.9.2 [INFO] [stderr] Compiling ctr v0.8.0 [INFO] [stderr] Compiling aes v0.7.5 [INFO] [stderr] Compiling digest v0.10.3 [INFO] [stderr] Compiling chrono v0.4.19 [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 regex-automata v0.1.10 [INFO] [stderr] Compiling regex v1.5.5 [INFO] [stderr] Compiling ghash v0.4.4 [INFO] [stderr] Compiling sha2 v0.10.2 [INFO] [stderr] Compiling url v2.2.2 [INFO] [stderr] Compiling aes-gcm v0.9.4 [INFO] [stderr] Compiling matchers v0.0.1 [INFO] [stderr] Compiling vergen v6.0.2 [INFO] [stderr] Compiling git2 v0.14.1 [INFO] [stderr] Compiling synstructure v0.12.6 [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 tracing-attributes v0.1.20 [INFO] [stderr] Compiling futures-macro v0.3.21 [INFO] [stderr] Compiling pin-project-internal v1.0.10 [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 chacha20 v0.7.3 [INFO] [stderr] Compiling curve25519-dalek v3.2.1 [INFO] [stderr] Compiling chacha20poly1305 v0.8.2 [INFO] [stderr] Compiling tracing v0.1.31 [INFO] [stderr] Compiling thiserror v1.0.30 [INFO] [stderr] Compiling pin-project v1.0.10 [INFO] [stderr] Compiling x25519-dalek v1.2.0 [INFO] [stderr] Compiling clap v3.1.6 [INFO] [stderr] Compiling rathole v0.4.1 (/opt/rustwide/workdir) [INFO] [stderr] Compiling bytes v1.1.0 [INFO] [stderr] Compiling tracing-serde v0.1.3 [INFO] [stderr] Compiling bincode v1.3.3 [INFO] [stderr] Compiling toml v0.5.8 [INFO] [stderr] Compiling tokio v1.17.0 [INFO] [stderr] Compiling tracing-subscriber v0.2.25 [INFO] [stderr] Compiling tokio-native-tls v0.3.0 [INFO] [stderr] Compiling async-http-proxy v1.2.5 [INFO] [stderr] Compiling snowstorm v0.3.1 [INFO] [stderr] Compiling backoff v0.4.0 [INFO] [stderr] Compiling async-socks5 v0.5.1 [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1m 30s [INFO] running `Command { std: "docker" "inspect" "923ffdb43ee365d06024c2dfc832533d3740e8457674d34e119436779c3268c3", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "923ffdb43ee365d06024c2dfc832533d3740e8457674d34e119436779c3268c3", kill_on_drop: false }` [INFO] [stdout] 923ffdb43ee365d06024c2dfc832533d3740e8457674d34e119436779c3268c3 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-15/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-15/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "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:d190cb871061d98bc6d0581d85cb2ecb09a0f8a142ba5463de30be9999fc3251" "/opt/rustwide/cargo-home/bin/cargo" "+0d13f6afeba4935499abe0c9a07426c94492c94e" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] ba30b76fbb30521e343a8245c95b7c1aca0b5cd0df27bbae797d6dc93b3fa44b [INFO] running `Command { std: "docker" "start" "-a" "ba30b76fbb30521e343a8245c95b7c1aca0b5cd0df27bbae797d6dc93b3fa44b", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [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 lazy_static v1.4.0 [INFO] [stderr] Compiling pin-project-lite v0.2.8 [INFO] [stderr] Compiling smallvec v1.8.0 [INFO] [stderr] Compiling scopeguard v1.1.0 [INFO] [stderr] Compiling ppv-lite86 v0.2.16 [INFO] [stderr] Compiling byteorder v1.4.3 [INFO] [stderr] Compiling foreign-types-shared v0.1.1 [INFO] [stderr] Compiling regex-syntax v0.6.25 [INFO] [stderr] Compiling itoa v1.0.1 [INFO] [stderr] Compiling openssl-probe v0.1.5 [INFO] [stderr] Compiling ryu v1.0.9 [INFO] [stderr] Compiling hashbrown v0.11.2 [INFO] [stderr] Compiling same-file v1.0.6 [INFO] [stderr] Compiling slab v0.4.5 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling base64 v0.13.0 [INFO] [stderr] Compiling strsim v0.10.0 [INFO] [stderr] Compiling termcolor v1.1.3 [INFO] [stderr] Compiling textwrap v0.15.0 [INFO] [stderr] Compiling ansi_term v0.12.1 [INFO] [stderr] Compiling hex v0.4.3 [INFO] [stderr] Compiling instant v0.1.12 [INFO] [stderr] Compiling typenum v1.15.0 [INFO] [stderr] Compiling log v0.4.14 [INFO] [stderr] Compiling memchr v2.4.1 [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 getrandom v0.2.5 [INFO] [stderr] Compiling getrandom v0.1.16 [INFO] [stderr] Compiling futures-core v0.3.21 [INFO] [stderr] Compiling inotify-sys v0.1.5 [INFO] [stderr] Compiling futures-task v0.3.21 [INFO] [stderr] Compiling filetime v0.2.15 [INFO] [stderr] Compiling httparse v1.6.0 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling fdlimit v0.2.1 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling serde v1.0.136 [INFO] [stderr] Compiling openssl-sys v0.9.72 [INFO] [stderr] Compiling pin-project v1.0.10 [INFO] [stderr] Compiling zeroize v1.3.0 [INFO] [stderr] Compiling lock_api v0.4.6 [INFO] [stderr] Compiling thread_local v1.1.4 [INFO] [stderr] Compiling tracing-core v0.1.23 [INFO] [stderr] Compiling crossbeam-utils v0.8.7 [INFO] [stderr] Compiling sharded-slab v0.1.4 [INFO] [stderr] Compiling crossbeam-channel v0.5.2 [INFO] [stderr] Compiling rand_core v0.6.3 [INFO] [stderr] Compiling parking_lot_core v0.9.1 [INFO] [stderr] Compiling foreign-types v0.3.2 [INFO] [stderr] Compiling inotify v0.9.6 [INFO] [stderr] Compiling walkdir v2.3.2 [INFO] [stderr] Compiling indexmap v1.8.0 [INFO] [stderr] Compiling rand_core v0.5.1 [INFO] [stderr] Compiling futures-util v0.3.21 [INFO] [stderr] Compiling mio v0.8.0 [INFO] [stderr] Compiling mio v0.7.14 [INFO] [stderr] Compiling tracing-log v0.1.2 [INFO] [stderr] Compiling tracing v0.1.31 [INFO] [stderr] Compiling os_str_bytes v6.0.0 [INFO] [stderr] Compiling generic-array v0.14.5 [INFO] [stderr] Compiling openssl v0.10.38 [INFO] [stderr] Compiling parking_lot v0.12.0 [INFO] [stderr] Compiling bytes v1.1.0 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling regex-automata v0.1.10 [INFO] [stderr] Compiling regex v1.5.5 [INFO] [stderr] Compiling tracing-serde v0.1.3 [INFO] [stderr] Compiling serde_json v1.0.79 [INFO] [stderr] Compiling url v2.2.2 [INFO] [stderr] Compiling toml v0.5.8 [INFO] [stderr] Compiling bincode v1.3.3 [INFO] [stderr] Compiling notify v5.0.0-pre.13 [INFO] [stderr] Compiling clap v3.1.6 [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 aead v0.4.3 [INFO] [stderr] Compiling crypto-mac v0.8.0 [INFO] [stderr] Compiling block-buffer v0.9.0 [INFO] [stderr] Compiling block-buffer v0.10.2 [INFO] [stderr] Compiling crypto-common v0.1.3 [INFO] [stderr] Compiling polyval v0.5.3 [INFO] [stderr] Compiling poly1305 v0.7.2 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling tokio v1.17.0 [INFO] [stderr] Compiling matchers v0.0.1 [INFO] [stderr] Compiling native-tls v0.2.8 [INFO] [stderr] Compiling digest v0.10.3 [INFO] [stderr] Compiling chacha20 v0.7.3 [INFO] [stderr] Compiling ctr v0.8.0 [INFO] [stderr] Compiling aes v0.7.5 [INFO] [stderr] Compiling curve25519-dalek v3.2.1 [INFO] [stderr] Compiling sha2 v0.9.9 [INFO] [stderr] Compiling blake2 v0.9.2 [INFO] [stderr] Compiling ghash v0.4.4 [INFO] [stderr] Compiling tracing-subscriber v0.2.25 [INFO] [stderr] Compiling sha2 v0.10.2 [INFO] [stderr] Compiling chacha20poly1305 v0.8.2 [INFO] [stderr] Compiling aes-gcm v0.9.4 [INFO] [stderr] Compiling x25519-dalek v1.2.0 [INFO] [stderr] Compiling snow v0.8.0 [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] Compiling rathole v0.4.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 3m 17s [INFO] [stderr] Executable unittests src/lib.rs (/opt/rustwide/target/debug/deps/rathole-a4d338eabfca104e) [INFO] [stderr] Executable unittests src/main.rs (/opt/rustwide/target/debug/deps/rathole-31ec9bf68233c696) [INFO] [stderr] Executable tests/integration_test.rs (/opt/rustwide/target/debug/deps/integration_test-946f1e482d5da1cc) [INFO] running `Command { std: "docker" "inspect" "ba30b76fbb30521e343a8245c95b7c1aca0b5cd0df27bbae797d6dc93b3fa44b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ba30b76fbb30521e343a8245c95b7c1aca0b5cd0df27bbae797d6dc93b3fa44b", kill_on_drop: false }` [INFO] [stdout] ba30b76fbb30521e343a8245c95b7c1aca0b5cd0df27bbae797d6dc93b3fa44b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-15/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-15/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "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:d190cb871061d98bc6d0581d85cb2ecb09a0f8a142ba5463de30be9999fc3251" "/opt/rustwide/cargo-home/bin/cargo" "+0d13f6afeba4935499abe0c9a07426c94492c94e" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 10cecb7d831e29a54d4c627fa1d32e0834fe63d410c3026e8f8016dcf0fc9a37 [INFO] running `Command { std: "docker" "start" "-a" "10cecb7d831e29a54d4c627fa1d32e0834fe63d410c3026e8f8016dcf0fc9a37", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.83s [INFO] [stdout] [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rathole-a4d338eabfca104e) [INFO] [stdout] running 7 tests [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/rathole-31ec9bf68233c696) [INFO] [stdout] test config::tests::test_example_config ... ok [INFO] [stderr] Running tests/integration_test.rs (/opt/rustwide/target/debug/deps/integration_test-946f1e482d5da1cc) [INFO] [stdout] test config::tests::test_validate_server_config ... ok [INFO] [stdout] test config::tests::test_validate_client_config ... ok [INFO] [stdout] test config_watcher::test::test_calculate_events ... ok [INFO] [stdout] test tests::test_determine_run_mode ... ok [INFO] [stdout] test config::tests::test_invalid_config ... ok [INFO] [stdout] test config::tests::test_valid_config ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] Apr 27 03:23:45.012  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: start the client [INFO] [stdout] Apr 27 03:23:45.012  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: start the client [INFO] [stdout] Apr 27 03:23:45.024  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:23:45.024  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 03:23:45.025  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 03:23:45.026 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 533.126687ms... [INFO] [stdout] Apr 27 03:23:45.026 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 482.991855ms... [INFO] [stdout] Apr 27 03:23:45.028  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:23:45.029  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 03:23:45.029  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 03:23:45.030 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 486.161376ms... [INFO] [stdout] Apr 27 03:23:45.030 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 540.194116ms... [INFO] [stdout] Apr 27 03:23:45.511 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 823.948668ms... [INFO] [stdout] Apr 27 03:23:45.518 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 707.888555ms... [INFO] [stdout] Apr 27 03:23:45.562 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 765.37362ms... [INFO] [stdout] Apr 27 03:23:45.572 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 824.31714ms... [INFO] [stdout] Apr 27 03:23:46.013  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: start the server [INFO] [stdout] Apr 27 03:23:46.014  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: start the server [INFO] [stdout] Apr 27 03:23:46.016  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:23:46.016  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:23:46.016  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Apr 27 03:23:46.016  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Apr 27 03:23:46.229  INFO connection{addr=127.0.0.1:38814}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:46.230  INFO connection{addr=127.0.0.1:38814}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 03:23:46.230  INFO connection{addr=127.0.0.1:38814}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 03:23:46.230  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:46.330  INFO connection{addr=127.0.0.1:36944}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:46.331  INFO connection{addr=127.0.0.1:36944}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 03:23:46.331  INFO connection{addr=127.0.0.1:36944}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 03:23:46.331  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:46.337  INFO connection{addr=127.0.0.1:36952}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:46.337  INFO connection{addr=127.0.0.1:36952}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 03:23:46.338  INFO connection{addr=127.0.0.1:36952}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 03:23:46.338  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:46.602  INFO connection{addr=127.0.0.1:38834}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:46.602  INFO connection{addr=127.0.0.1:38834}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 03:23:46.603  INFO connection{addr=127.0.0.1:38834}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 03:23:46.603  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:48.514  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Apr 27 03:23:48.514  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Apr 27 03:23:48.518  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Apr 27 03:23:48.520  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: shutdown the client [INFO] [stdout] Apr 27 03:23:48.520  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:23:48.520  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:23:48.521  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:23:48.521  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: restart the client [INFO] [stdout] Apr 27 03:23:48.523  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:23:48.523  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 03:23:48.523  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 03:23:48.524  INFO connection{addr=127.0.0.1:36958}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:48.524  INFO connection{addr=127.0.0.1:36962}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:48.525  WARN connection{addr=127.0.0.1:36958}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Apr 27 03:23:48.525  INFO connection{addr=127.0.0.1:36958}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 03:23:48.525  WARN connection{addr=127.0.0.1:36962}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Apr 27 03:23:48.525  INFO connection{addr=127.0.0.1:36962}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 03:23:48.526  INFO connection{addr=127.0.0.1:36952}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:48.526  INFO connection{addr=127.0.0.1:36958}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 03:23:48.526  INFO connection{addr=127.0.0.1:36944}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:48.526  INFO connection{addr=127.0.0.1:36962}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 03:23:48.527  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:48.527  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:48.527  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 03:23:48.527  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:48.528  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 03:23:48.528  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:48.552  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Apr 27 03:23:48.575  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: shutdown the client [INFO] [stdout] Apr 27 03:23:48.575  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:23:48.576  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:23:48.576  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:23:48.576  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: restart the client [INFO] [stdout] Apr 27 03:23:48.578  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:23:48.578  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 03:23:48.578  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 03:23:48.579  INFO connection{addr=127.0.0.1:38858}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:48.579  INFO connection{addr=127.0.0.1:38860}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:48.580  WARN connection{addr=127.0.0.1:38858}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Apr 27 03:23:48.580  INFO connection{addr=127.0.0.1:38858}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 03:23:48.580  WARN connection{addr=127.0.0.1:38860}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Apr 27 03:23:48.580  INFO connection{addr=127.0.0.1:38860}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 03:23:48.580  INFO connection{addr=127.0.0.1:38834}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 03:23:48.581  INFO connection{addr=127.0.0.1:38834}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:48.581  INFO connection{addr=127.0.0.1:38814}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 03:23:48.581  INFO connection{addr=127.0.0.1:38814}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:48.582  INFO connection{addr=127.0.0.1:38834}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:48.582  INFO connection{addr=127.0.0.1:38858}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 03:23:48.582  INFO connection{addr=127.0.0.1:38814}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:48.582  INFO connection{addr=127.0.0.1:38860}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 03:23:48.582  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:48.583  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:48.583  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:48.583  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:48.583  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:48.584  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:48.584  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:48.584  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:48.584  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:48.584  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:48.585  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:48.585  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:48.585  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:48.585  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:48.585  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:48.586  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:48.586  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:48.586  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.522  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Apr 27 03:23:49.525  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Apr 27 03:23:49.527  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: shutdown the server [INFO] [stdout] Apr 27 03:23:49.527  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:23:49.527  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 03:23:49.527  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:49.528  INFO connection{addr=127.0.0.1:36958}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:49.528  INFO connection{addr=127.0.0.1:36962}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:49.528  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: restart the server [INFO] [stdout] Apr 27 03:23:49.529 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 526.960264ms... [INFO] [stdout] Apr 27 03:23:49.529  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 03:23:49.529  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.529 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 470.262703ms... [INFO] [stdout] Apr 27 03:23:49.529  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 03:23:49.530  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.532  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:23:49.532  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Apr 27 03:23:49.578  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Apr 27 03:23:49.616  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Apr 27 03:23:49.640  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: shutdown the server [INFO] [stdout] Apr 27 03:23:49.640  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:23:49.640  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 03:23:49.641  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:49.641  INFO connection{addr=127.0.0.1:38860}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 03:23:49.641  INFO connection{addr=127.0.0.1:38860}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:49.641  INFO connection{addr=127.0.0.1:38858}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 03:23:49.641  INFO connection{addr=127.0.0.1:38858}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:49.641  INFO connection{addr=127.0.0.1:38860}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:49.642  INFO connection{addr=127.0.0.1:38858}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:49.642  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: restart the server [INFO] [stdout] Apr 27 03:23:49.642 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 539.100757ms... [INFO] [stdout] Apr 27 03:23:49.643 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 485.56515ms... [INFO] [stdout] Apr 27 03:23:49.643  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.643  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.643  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.643  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.644  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.644  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.644  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.644  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.644  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.645  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.645  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.645  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.645  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.645  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.646  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.646  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:49.648  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:23:49.648  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Apr 27 03:23:50.002  INFO connection{addr=127.0.0.1:36972}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:50.002  INFO connection{addr=127.0.0.1:36972}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 03:23:50.003  INFO connection{addr=127.0.0.1:36972}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 03:23:50.003  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:50.058  INFO connection{addr=127.0.0.1:36978}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:50.059  INFO connection{addr=127.0.0.1:36978}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 03:23:50.059  INFO connection{addr=127.0.0.1:36978}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 03:23:50.059  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:50.130  INFO connection{addr=127.0.0.1:38906}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:50.131  INFO connection{addr=127.0.0.1:38906}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 03:23:50.131  INFO connection{addr=127.0.0.1:38906}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 03:23:50.131  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:50.183  INFO connection{addr=127.0.0.1:38924}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:50.184  INFO connection{addr=127.0.0.1:38924}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 03:23:50.184  INFO connection{addr=127.0.0.1:38924}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 03:23:50.185  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:52.030  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: lots of echo and pingpong [INFO] [stdout] Apr 27 03:23:52.036  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: shutdown the server and the client [INFO] [stdout] Apr 27 03:23:52.036  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:23:52.036  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:23:52.036  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 03:23:52.036  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:52.036  INFO connection{addr=127.0.0.1:36978}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:52.037  INFO connection{addr=127.0.0.1:36972}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:52.037  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:23:52.037  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:23:52.037  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: start the client [INFO] [stdout] Apr 27 03:23:52.038  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 03:23:52.038  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:52.038  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 03:23:52.038  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:52.040  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:23:52.053  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 03:23:52.053  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 03:23:52.054 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 531.260758ms... [INFO] [stdout] Apr 27 03:23:52.054 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 453.451929ms... [INFO] [stdout] Apr 27 03:23:52.144  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: lots of echo and pingpong [INFO] [stdout] Apr 27 03:23:52.224  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: shutdown the server and the client [INFO] [stdout] Apr 27 03:23:52.224  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:23:52.224  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:23:52.224  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 03:23:52.224  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:52.224  INFO connection{addr=127.0.0.1:38906}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 03:23:52.225  INFO connection{addr=127.0.0.1:38906}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:52.225  INFO connection{addr=127.0.0.1:38924}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 03:23:52.225  INFO connection{addr=127.0.0.1:38924}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:52.225  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:23:52.225  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:23:52.225  INFO connection{addr=127.0.0.1:38906}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:52.226  INFO connection{addr=127.0.0.1:38924}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:52.226  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: start the client [INFO] [stdout] Apr 27 03:23:52.226  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:52.226  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:52.227  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:52.227  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:52.227  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:52.227  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:52.227  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:52.228  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:52.228  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:52.228  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:52.228  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:52.228  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:52.228  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:52.229  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:52.229  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:52.229  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:52.231  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:23:52.237  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 03:23:52.237  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 03:23:52.237 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 509.051612ms... [INFO] [stdout] Apr 27 03:23:52.238 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 479.024482ms... [INFO] [stdout] Apr 27 03:23:52.510 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 689.03982ms... [INFO] [stdout] Apr 27 03:23:52.587 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 812.675924ms... [INFO] [stdout] Apr 27 03:23:52.720 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 821.457621ms... [INFO] [stdout] Apr 27 03:23:52.748 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 796.783989ms... [INFO] [stdout] Apr 27 03:23:53.039  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: start the server [INFO] [stdout] Apr 27 03:23:53.041  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:23:53.048  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Apr 27 03:23:53.211  INFO connection{addr=127.0.0.1:36994}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:53.211  INFO connection{addr=127.0.0.1:36994}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 03:23:53.212  INFO connection{addr=127.0.0.1:36994}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 03:23:53.212  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:53.228  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: start the server [INFO] [stdout] Apr 27 03:23:53.231  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:23:53.233  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Apr 27 03:23:53.407  INFO connection{addr=127.0.0.1:37000}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:53.408  INFO connection{addr=127.0.0.1:37000}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 03:23:53.408  INFO connection{addr=127.0.0.1:37000}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 03:23:53.408  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:53.558  INFO connection{addr=127.0.0.1:38960}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:53.559  INFO connection{addr=127.0.0.1:38960}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 03:23:53.560  INFO connection{addr=127.0.0.1:38960}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 03:23:53.560  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:53.561  INFO connection{addr=127.0.0.1:38962}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:53.563  INFO connection{addr=127.0.0.1:38962}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 03:23:53.569  INFO connection{addr=127.0.0.1:38962}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 03:23:53.569  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:55.541  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Apr 27 03:23:55.545  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Apr 27 03:23:55.547  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: shutdown the client [INFO] [stdout] Apr 27 03:23:55.547  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:23:55.548  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:23:55.548  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:23:55.548  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: restart the client [INFO] [stdout] Apr 27 03:23:55.551  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:23:55.559  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 03:23:55.560  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 03:23:55.576  INFO connection{addr=127.0.0.1:37006}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:55.578  WARN connection{addr=127.0.0.1:37006}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Apr 27 03:23:55.578  INFO connection{addr=127.0.0.1:37006}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 03:23:55.579  INFO connection{addr=127.0.0.1:37000}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:55.579  INFO connection{addr=127.0.0.1:37006}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 03:23:55.580  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:55.580  INFO connection{addr=127.0.0.1:37008}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:55.580  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 03:23:55.581  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:55.582  WARN connection{addr=127.0.0.1:37008}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Apr 27 03:23:55.582  INFO connection{addr=127.0.0.1:37008}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 03:23:55.588  INFO connection{addr=127.0.0.1:36994}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:55.589  INFO connection{addr=127.0.0.1:37008}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 03:23:55.589  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:55.590  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 03:23:55.591  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:55.731  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Apr 27 03:23:55.792  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Apr 27 03:23:55.833  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: shutdown the client [INFO] [stdout] Apr 27 03:23:55.833  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:23:55.833  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:23:55.833  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:23:55.834  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: restart the client [INFO] [stdout] Apr 27 03:23:55.836  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:23:55.844  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 03:23:55.844  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 03:23:55.858  INFO connection{addr=127.0.0.1:39002}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:55.860  WARN connection{addr=127.0.0.1:39002}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Apr 27 03:23:55.860  INFO connection{addr=127.0.0.1:39002}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 03:23:55.860  INFO connection{addr=127.0.0.1:38960}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 03:23:55.860  INFO connection{addr=127.0.0.1:38960}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:55.861  INFO connection{addr=127.0.0.1:38960}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:55.861  INFO connection{addr=127.0.0.1:39002}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 03:23:55.861  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:55.862  INFO connection{addr=127.0.0.1:39004}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:55.862  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:55.862  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:55.863  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:55.863  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:55.863  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:55.863  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:55.864  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:55.864  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:55.866  WARN connection{addr=127.0.0.1:39004}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Apr 27 03:23:55.867  INFO connection{addr=127.0.0.1:39004}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 03:23:55.873  INFO connection{addr=127.0.0.1:38962}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 03:23:55.873  INFO connection{addr=127.0.0.1:38962}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:55.873  INFO connection{addr=127.0.0.1:38962}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:55.874  INFO connection{addr=127.0.0.1:39004}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 03:23:55.874  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:55.875  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:55.875  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:55.875  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:55.876  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:55.876  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:55.876  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:55.876  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:55.877  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.550  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Apr 27 03:23:56.553  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Apr 27 03:23:56.556  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: shutdown the server [INFO] [stdout] Apr 27 03:23:56.556  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:23:56.556  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 03:23:56.556  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:56.557  INFO connection{addr=127.0.0.1:37008}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:56.557  INFO connection{addr=127.0.0.1:37006}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:56.558  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: restart the server [INFO] [stdout] Apr 27 03:23:56.558 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 500.056768ms... [INFO] [stdout] Apr 27 03:23:56.559  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 03:23:56.559  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.560 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 517.311057ms... [INFO] [stdout] Apr 27 03:23:56.560  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 03:23:56.560  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.564  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:23:56.567  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Apr 27 03:23:56.835  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Apr 27 03:23:56.893  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Apr 27 03:23:56.933  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: shutdown the server [INFO] [stdout] Apr 27 03:23:56.934  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:23:56.934  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 03:23:56.934  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:56.934  INFO connection{addr=127.0.0.1:39004}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 03:23:56.934  INFO connection{addr=127.0.0.1:39004}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:56.935  INFO connection{addr=127.0.0.1:39002}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 03:23:56.935  INFO connection{addr=127.0.0.1:39002}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:56.935  INFO connection{addr=127.0.0.1:39004}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:56.935  INFO connection{addr=127.0.0.1:39002}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:56.936  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: restart the server [INFO] [stdout] Apr 27 03:23:56.936 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 531.640641ms... [INFO] [stdout] Apr 27 03:23:56.937 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 485.855088ms... [INFO] [stdout] Apr 27 03:23:56.937  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.937  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.938  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.938  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.938  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.938  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.939  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.939  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.939  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.939  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.940  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.940  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.940  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.941  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.941  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.941  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:56.944  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:23:56.947  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Apr 27 03:23:57.070  INFO connection{addr=127.0.0.1:37018}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:57.070  INFO connection{addr=127.0.0.1:37018}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 03:23:57.071  INFO connection{addr=127.0.0.1:37018}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 03:23:57.071  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:57.096  INFO connection{addr=127.0.0.1:37026}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:57.097  INFO connection{addr=127.0.0.1:37026}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 03:23:57.097  INFO connection{addr=127.0.0.1:37026}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 03:23:57.098  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:57.435  INFO connection{addr=127.0.0.1:39044}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:57.435  INFO connection{addr=127.0.0.1:39044}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 03:23:57.436  INFO connection{addr=127.0.0.1:39044}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 03:23:57.436  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:57.506  INFO connection{addr=127.0.0.1:39064}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:23:57.507  INFO connection{addr=127.0.0.1:39064}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 03:23:57.508  INFO connection{addr=127.0.0.1:39064}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 03:23:57.508  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:23:59.059  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: lots of echo and pingpong [INFO] [stdout] Apr 27 03:23:59.066  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: shutdown the server and the client [INFO] [stdout] Apr 27 03:23:59.066  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:23:59.066  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:23:59.066  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 03:23:59.066  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:59.067  INFO connection{addr=127.0.0.1:37026}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:59.067  INFO connection{addr=127.0.0.1:37018}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:59.068  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:23:59.068  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:23:59.068  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: start the client [INFO] [stdout] Apr 27 03:23:59.068  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 03:23:59.069  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:59.069  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 03:23:59.069  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:59.074  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:23:59.075  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 03:23:59.076  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 03:23:59.076 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 454.987129ms... [INFO] [stdout] Apr 27 03:23:59.076 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 482.389123ms... [INFO] [stdout] Apr 27 03:23:59.438  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: lots of echo and pingpong [INFO] [stdout] Apr 27 03:23:59.533 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 762.032401ms... [INFO] [stdout] Apr 27 03:23:59.561 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 823.098329ms... [INFO] [stdout] Apr 27 03:23:59.588  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: shutdown the server and the client [INFO] [stdout] Apr 27 03:23:59.588  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:23:59.588  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:23:59.589  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 03:23:59.589  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:59.589  INFO connection{addr=127.0.0.1:39064}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 03:23:59.589  INFO connection{addr=127.0.0.1:39064}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:59.589  INFO connection{addr=127.0.0.1:39044}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 03:23:59.590  INFO connection{addr=127.0.0.1:39044}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:23:59.590  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:23:59.590  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:23:59.590  INFO connection{addr=127.0.0.1:39064}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:59.591  INFO connection{addr=127.0.0.1:39044}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 03:23:59.591  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: start the client [INFO] [stdout] Apr 27 03:23:59.592  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:59.592  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:59.593  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:59.593  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:59.593  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:59.593  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:59.594  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:59.594  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:59.594  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:59.594  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:59.595  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:59.595  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:59.595  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:59.595  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:59.596  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:59.596  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:23:59.601  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:23:59.602  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 03:23:59.602  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 03:23:59.604 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 481.574659ms... [INFO] [stdout] Apr 27 03:23:59.604 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 483.055432ms... [INFO] [stdout] Apr 27 03:24:00.071  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: start the server [INFO] [stdout] Apr 27 03:24:00.075  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:24:00.075  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Apr 27 03:24:00.087 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 685.810283ms... [INFO] [stdout] Apr 27 03:24:00.089 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 805.307224ms... [INFO] [stdout] Apr 27 03:24:00.308  INFO connection{addr=127.0.0.1:37040}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:24:00.309  INFO connection{addr=127.0.0.1:37040}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 03:24:00.310  INFO connection{addr=127.0.0.1:37040}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 03:24:00.311  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:24:00.396  INFO connection{addr=127.0.0.1:37046}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:24:00.397  INFO connection{addr=127.0.0.1:37046}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 03:24:00.397  INFO connection{addr=127.0.0.1:37046}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 03:24:00.398  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:24:00.593  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: start the server [INFO] [stdout] Apr 27 03:24:00.596  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:24:00.597  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Apr 27 03:24:00.786  INFO connection{addr=127.0.0.1:39098}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:24:00.787  INFO connection{addr=127.0.0.1:39098}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 03:24:00.788  INFO connection{addr=127.0.0.1:39098}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 03:24:00.789  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:24:00.906  INFO connection{addr=127.0.0.1:39116}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:24:00.907  INFO connection{addr=127.0.0.1:39116}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 03:24:00.908  INFO connection{addr=127.0.0.1:39116}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 03:24:00.909  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:24:02.572  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Apr 27 03:24:02.579  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Apr 27 03:24:02.630  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: shutdown the client [INFO] [stdout] Apr 27 03:24:02.631  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:24:02.631  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:24:02.631  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:24:02.632  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: restart the client [INFO] [stdout] Apr 27 03:24:02.635  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:24:02.636  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 03:24:02.637  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 03:24:02.659  INFO connection{addr=127.0.0.1:37052}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:24:02.662  INFO connection{addr=127.0.0.1:37054}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:24:02.662  WARN connection{addr=127.0.0.1:37052}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Apr 27 03:24:02.663  INFO connection{addr=127.0.0.1:37052}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 03:24:02.663  INFO connection{addr=127.0.0.1:37046}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:24:02.664  INFO connection{addr=127.0.0.1:37052}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 03:24:02.665  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:24:02.665  WARN connection{addr=127.0.0.1:37054}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Apr 27 03:24:02.666  INFO connection{addr=127.0.0.1:37054}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 03:24:02.666  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 03:24:02.666  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:02.667  INFO connection{addr=127.0.0.1:37040}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:24:02.667  INFO connection{addr=127.0.0.1:37054}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 03:24:02.668  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:24:02.676  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 03:24:02.676  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.096  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Apr 27 03:24:03.293  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Apr 27 03:24:03.364  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: shutdown the client [INFO] [stdout] Apr 27 03:24:03.364  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:24:03.365  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:24:03.365  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:24:03.365  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: restart the client [INFO] [stdout] Apr 27 03:24:03.367  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:24:03.368  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 03:24:03.368  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 03:24:03.385  INFO connection{addr=127.0.0.1:39142}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:24:03.387  INFO connection{addr=127.0.0.1:39144}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:24:03.388  WARN connection{addr=127.0.0.1:39142}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Apr 27 03:24:03.388  INFO connection{addr=127.0.0.1:39142}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 03:24:03.388  INFO connection{addr=127.0.0.1:39116}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 03:24:03.389  INFO connection{addr=127.0.0.1:39116}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:24:03.390  INFO connection{addr=127.0.0.1:39116}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 03:24:03.390  INFO connection{addr=127.0.0.1:39142}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 03:24:03.390  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:24:03.391  WARN connection{addr=127.0.0.1:39144}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Apr 27 03:24:03.392  INFO connection{addr=127.0.0.1:39144}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 03:24:03.392  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.392  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.392  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.392  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.393  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.393  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.393  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.393  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.394  INFO connection{addr=127.0.0.1:39098}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 03:24:03.394  INFO connection{addr=127.0.0.1:39098}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:24:03.395  INFO connection{addr=127.0.0.1:39098}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 03:24:03.395  INFO connection{addr=127.0.0.1:39144}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 03:24:03.396  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:24:03.421  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.421  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.421  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.421  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.421  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.422  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.422  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.422  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.633  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Apr 27 03:24:03.686  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Apr 27 03:24:03.735  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: shutdown the server [INFO] [stdout] Apr 27 03:24:03.735  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:24:03.735  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 03:24:03.735  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 03:24:03.736  INFO connection{addr=127.0.0.1:37054}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:24:03.736  INFO connection{addr=127.0.0.1:37052}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:24:03.736  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: restart the server [INFO] [stdout] Apr 27 03:24:03.737 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 533.285867ms... [INFO] [stdout] Apr 27 03:24:03.737  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 03:24:03.738  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.738 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 450.586939ms... [INFO] [stdout] Apr 27 03:24:03.738  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 03:24:03.739  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:03.744  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:24:03.744  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Apr 27 03:24:04.201  INFO connection{addr=127.0.0.1:37064}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:24:04.203  INFO connection{addr=127.0.0.1:37064}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 03:24:04.203  INFO connection{addr=127.0.0.1:37064}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 03:24:04.204  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:24:04.282  INFO connection{addr=127.0.0.1:37070}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:24:04.283  INFO connection{addr=127.0.0.1:37070}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 03:24:04.283  INFO connection{addr=127.0.0.1:37070}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 03:24:04.284  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:24:04.367  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Apr 27 03:24:04.578  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Apr 27 03:24:04.853  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: shutdown the server [INFO] [stdout] Apr 27 03:24:04.853  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:24:04.853  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 03:24:04.853  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 03:24:04.854  INFO connection{addr=127.0.0.1:39142}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 03:24:04.854  INFO connection{addr=127.0.0.1:39142}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:24:04.854  INFO connection{addr=127.0.0.1:39144}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 03:24:04.854  INFO connection{addr=127.0.0.1:39144}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:24:04.854  INFO connection{addr=127.0.0.1:39142}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 03:24:04.855  INFO connection{addr=127.0.0.1:39144}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 03:24:04.855  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: restart the server [INFO] [stdout] Apr 27 03:24:04.855 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 500.392386ms... [INFO] [stdout] Apr 27 03:24:04.856 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 544.444512ms... [INFO] [stdout] Apr 27 03:24:04.856  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:04.856  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:04.856  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:04.856  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:04.857  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:04.857  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:04.857  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:04.857  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:04.858  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:04.858  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:04.858  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:04.858  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:04.858  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:04.859  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:04.859  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:04.859  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 03:24:04.861  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 03:24:04.862  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Apr 27 03:24:05.369  INFO connection{addr=127.0.0.1:39186}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:24:05.370  INFO connection{addr=127.0.0.1:39186}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 03:24:05.371  INFO connection{addr=127.0.0.1:39186}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 03:24:05.371  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:24:05.466  INFO connection{addr=127.0.0.1:39204}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 03:24:05.467  INFO connection{addr=127.0.0.1:39204}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 03:24:05.468  INFO connection{addr=127.0.0.1:39204}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 03:24:05.469  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 03:24:06.238  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: lots of echo and pingpong [INFO] [stdout] Apr 27 03:24:06.344  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: shutdown the server and the client [INFO] [stdout] Apr 27 03:24:06.344  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:24:06.344  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:24:06.344  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 03:24:06.344  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 03:24:06.345  INFO connection{addr=127.0.0.1:37070}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:24:06.345  INFO connection{addr=127.0.0.1:37064}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:24:06.345  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:24:06.345  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] test udp ... ok [INFO] [stdout] Apr 27 03:24:07.357  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: lots of echo and pingpong [INFO] [stdout] Apr 27 03:24:07.866  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: shutdown the server and the client [INFO] [stdout] Apr 27 03:24:07.867  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:24:07.867  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 03:24:07.867  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 03:24:07.867  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 03:24:07.867  INFO connection{addr=127.0.0.1:39186}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 03:24:07.868  INFO connection{addr=127.0.0.1:39186}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:24:07.868  INFO connection{addr=127.0.0.1:39204}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 03:24:07.868  INFO connection{addr=127.0.0.1:39204}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 03:24:07.868  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:24:07.868  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 03:24:07.868  INFO connection{addr=127.0.0.1:39186}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 03:24:07.869  INFO connection{addr=127.0.0.1:39204}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] test tcp ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 22.88s [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" "10cecb7d831e29a54d4c627fa1d32e0834fe63d410c3026e8f8016dcf0fc9a37", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "10cecb7d831e29a54d4c627fa1d32e0834fe63d410c3026e8f8016dcf0fc9a37", kill_on_drop: false }` [INFO] [stdout] 10cecb7d831e29a54d4c627fa1d32e0834fe63d410c3026e8f8016dcf0fc9a37