[INFO] cloning repository https://github.com/rohankumardubey/rathole [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/rohankumardubey/rathole" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frohankumardubey%2Frathole", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frohankumardubey%2Frathole'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 8a247238954722d4de7e83dba0cff410d62ffb14 [INFO] testing rohankumardubey/rathole against try#1b74e096b9bfb06f84a3007193dcd2f059cbdf6a for pr-96025 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frohankumardubey%2Frathole" "/workspace/builds/worker-5/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-5/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/rohankumardubey/rathole on toolchain 1b74e096b9bfb06f84a3007193dcd2f059cbdf6a [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1b74e096b9bfb06f84a3007193dcd2f059cbdf6a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking git repo https://github.com/rohankumardubey/rathole [INFO] finished tweaking git repo https://github.com/rohankumardubey/rathole [INFO] tweaked toml for git repo https://github.com/rohankumardubey/rathole written to /workspace/builds/worker-5/source/Cargo.toml [INFO] crate git repo https://github.com/rohankumardubey/rathole already has a lockfile, it will not be regenerated [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1b74e096b9bfb06f84a3007193dcd2f059cbdf6a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1b74e096b9bfb06f84a3007193dcd2f059cbdf6a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 747a4ccef365d4066555c21cf37bc7ab8e2aad53eae265f46c65054328cda0f2 [INFO] running `Command { std: "docker" "start" "-a" "747a4ccef365d4066555c21cf37bc7ab8e2aad53eae265f46c65054328cda0f2", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "747a4ccef365d4066555c21cf37bc7ab8e2aad53eae265f46c65054328cda0f2", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "747a4ccef365d4066555c21cf37bc7ab8e2aad53eae265f46c65054328cda0f2", kill_on_drop: false }` [INFO] [stdout] 747a4ccef365d4066555c21cf37bc7ab8e2aad53eae265f46c65054328cda0f2 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1b74e096b9bfb06f84a3007193dcd2f059cbdf6a" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 58bc344fabb6690340b34efe7aa96f817ff23261eb0f9997e79816b8a1043ab9 [INFO] running `Command { std: "docker" "start" "-a" "58bc344fabb6690340b34efe7aa96f817ff23261eb0f9997e79816b8a1043ab9", kill_on_drop: false }` [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 memchr v2.4.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 parking_lot_core v0.9.1 [INFO] [stderr] Compiling smallvec v1.8.0 [INFO] [stderr] Compiling pin-project-lite v0.2.8 [INFO] [stderr] Compiling scopeguard v1.1.0 [INFO] [stderr] Compiling ucd-trie v0.1.3 [INFO] [stderr] Compiling tinyvec_macros v0.1.0 [INFO] [stderr] Compiling matches v0.1.9 [INFO] [stderr] Compiling getrandom v0.1.16 [INFO] [stderr] Compiling rustversion v1.0.6 [INFO] [stderr] Compiling unicode-bidi v0.3.7 [INFO] [stderr] Compiling anyhow v1.0.56 [INFO] [stderr] Compiling percent-encoding v2.1.0 [INFO] [stderr] Compiling bitflags v1.3.2 [INFO] [stderr] Compiling futures-core v0.3.21 [INFO] [stderr] Compiling ppv-lite86 v0.2.16 [INFO] [stderr] Compiling futures-task v0.3.21 [INFO] [stderr] Compiling crossbeam-utils v0.8.7 [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 openssl v0.10.38 [INFO] [stderr] Compiling httparse v1.6.0 [INFO] [stderr] Compiling futures-util 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 pin-utils v0.1.0 [INFO] [stderr] Compiling itoa v1.0.1 [INFO] [stderr] Compiling openssl-probe v0.1.5 [INFO] [stderr] Compiling slab v0.4.5 [INFO] [stderr] Compiling ryu v1.0.9 [INFO] [stderr] Compiling same-file v1.0.6 [INFO] [stderr] Compiling heck v0.4.0 [INFO] [stderr] Compiling hashbrown v0.11.2 [INFO] [stderr] Compiling textwrap v0.15.0 [INFO] [stderr] Compiling ansi_term v0.12.1 [INFO] [stderr] Compiling strsim v0.10.0 [INFO] [stderr] Compiling base64 v0.13.0 [INFO] [stderr] Compiling termcolor v1.1.3 [INFO] [stderr] Compiling hex v0.4.3 [INFO] [stderr] Compiling instant v0.1.12 [INFO] [stderr] Compiling tracing-core v0.1.23 [INFO] [stderr] Compiling sharded-slab v0.1.4 [INFO] [stderr] Compiling foreign-types v0.3.2 [INFO] [stderr] Compiling tinyvec v1.5.1 [INFO] [stderr] Compiling thread_local v1.1.4 [INFO] [stderr] Compiling lock_api v0.4.6 [INFO] [stderr] Compiling form_urlencoded v1.0.1 [INFO] [stderr] Compiling walkdir v2.3.2 [INFO] [stderr] Compiling pest v2.1.3 [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 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 crossbeam-channel v0.5.2 [INFO] [stderr] Compiling tracing-log v0.1.2 [INFO] [stderr] Compiling quote v1.0.15 [INFO] [stderr] Compiling unicode-normalization v0.1.19 [INFO] [stderr] Compiling semver-parser v0.10.2 [INFO] [stderr] Compiling mio v0.8.0 [INFO] [stderr] Compiling num_cpus v1.13.1 [INFO] [stderr] Compiling getrandom v0.2.5 [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 atty v0.2.14 [INFO] [stderr] Compiling mio v0.7.14 [INFO] [stderr] Compiling filetime v0.2.15 [INFO] [stderr] Compiling fdlimit v0.2.1 [INFO] [stderr] Compiling os_str_bytes v6.0.0 [INFO] [stderr] Compiling inotify v0.9.6 [INFO] [stderr] Compiling parking_lot v0.12.0 [INFO] [stderr] Compiling rand_core v0.6.3 [INFO] [stderr] Compiling rand_core v0.5.1 [INFO] [stderr] Compiling jobserver v0.1.24 [INFO] [stderr] Compiling time v0.1.43 [INFO] [stderr] Compiling semver v0.11.0 [INFO] [stderr] Compiling cc v1.0.73 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling notify v5.0.0-pre.13 [INFO] [stderr] Compiling regex-automata v0.1.10 [INFO] [stderr] Compiling regex v1.5.5 [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 snow v0.8.0 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling universal-hash v0.4.1 [INFO] [stderr] Compiling cipher v0.3.0 [INFO] [stderr] Compiling digest v0.9.0 [INFO] [stderr] Compiling aead v0.4.3 [INFO] [stderr] Compiling crypto-mac v0.8.0 [INFO] [stderr] Compiling block-buffer v0.9.0 [INFO] [stderr] Compiling crypto-common v0.1.3 [INFO] [stderr] Compiling block-buffer v0.10.2 [INFO] [stderr] Compiling url v2.2.2 [INFO] [stderr] Compiling sha2 v0.9.9 [INFO] [stderr] Compiling blake2 v0.9.2 [INFO] [stderr] Compiling polyval v0.5.3 [INFO] [stderr] Compiling poly1305 v0.7.2 [INFO] [stderr] Compiling digest v0.10.3 [INFO] [stderr] Compiling aes v0.7.5 [INFO] [stderr] Compiling ctr v0.8.0 [INFO] [stderr] Compiling matchers v0.0.1 [INFO] [stderr] Compiling sha2 v0.10.2 [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 ghash v0.4.4 [INFO] [stderr] Compiling aes-gcm v0.9.4 [INFO] [stderr] Compiling vergen v6.0.2 [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 futures-macro v0.3.21 [INFO] [stderr] Compiling pin-project-internal v1.0.10 [INFO] [stderr] Compiling tracing-attributes v0.1.20 [INFO] [stderr] Compiling clap_derive v3.1.4 [INFO] [stderr] Compiling enum-iterator v0.7.0 [INFO] [stderr] Compiling git2 v0.14.1 [INFO] [stderr] Compiling zeroize v1.3.0 [INFO] [stderr] Compiling tracing v0.1.31 [INFO] [stderr] Compiling curve25519-dalek v3.2.1 [INFO] [stderr] Compiling chacha20 v0.7.3 [INFO] [stderr] Compiling chacha20poly1305 v0.8.2 [INFO] [stderr] Compiling clap v3.1.6 [INFO] [stderr] Compiling x25519-dalek v1.2.0 [INFO] [stderr] Compiling thiserror v1.0.30 [INFO] [stderr] Compiling pin-project v1.0.10 [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 toml v0.5.8 [INFO] [stderr] Compiling bincode v1.3.3 [INFO] [stderr] Compiling tokio v1.17.0 [INFO] [stderr] Compiling tracing-subscriber v0.2.25 [INFO] [stderr] Compiling async-http-proxy v1.2.5 [INFO] [stderr] Compiling async-socks5 v0.5.1 [INFO] [stderr] Compiling backoff v0.4.0 [INFO] [stderr] Compiling tokio-native-tls v0.3.0 [INFO] [stderr] Compiling snowstorm v0.3.1 [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 40.80s [INFO] running `Command { std: "docker" "inspect" "58bc344fabb6690340b34efe7aa96f817ff23261eb0f9997e79816b8a1043ab9", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "58bc344fabb6690340b34efe7aa96f817ff23261eb0f9997e79816b8a1043ab9", kill_on_drop: false }` [INFO] [stdout] 58bc344fabb6690340b34efe7aa96f817ff23261eb0f9997e79816b8a1043ab9 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1b74e096b9bfb06f84a3007193dcd2f059cbdf6a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] ec6ea65d547f649a027eb1fa080f4b710b50fc8192575e1e2cc8b23607afe632 [INFO] running `Command { std: "docker" "start" "-a" "ec6ea65d547f649a027eb1fa080f4b710b50fc8192575e1e2cc8b23607afe632", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Compiling subtle v2.4.1 [INFO] [stderr] Compiling smallvec v1.8.0 [INFO] [stderr] Compiling lock_api v0.4.6 [INFO] [stderr] Compiling tracing-core v0.1.23 [INFO] [stderr] Compiling regex-automata v0.1.10 [INFO] [stderr] Compiling instant v0.1.12 [INFO] [stderr] Compiling regex v1.5.5 [INFO] [stderr] Compiling sharded-slab v0.1.4 [INFO] [stderr] Compiling typenum v1.15.0 [INFO] [stderr] Compiling log v0.4.14 [INFO] [stderr] Compiling memchr v2.4.1 [INFO] [stderr] Compiling futures-core v0.3.21 [INFO] [stderr] Compiling crossbeam-utils v0.8.7 [INFO] [stderr] Compiling futures-task v0.3.21 [INFO] [stderr] Compiling walkdir v2.3.2 [INFO] [stderr] Compiling socket2 v0.4.4 [INFO] [stderr] Compiling signal-hook-registry v1.4.0 [INFO] [stderr] Compiling num_cpus v1.13.1 [INFO] [stderr] Compiling getrandom v0.2.5 [INFO] [stderr] Compiling getrandom v0.1.16 [INFO] [stderr] Compiling inotify-sys v0.1.5 [INFO] [stderr] Compiling httparse v1.6.0 [INFO] [stderr] Compiling filetime v0.2.15 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling fdlimit v0.2.1 [INFO] [stderr] Compiling indexmap v1.8.0 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling serde v1.0.136 [INFO] [stderr] Compiling pin-project v1.0.10 [INFO] [stderr] Compiling zeroize v1.3.0 [INFO] [stderr] Compiling openssl-sys v0.9.72 [INFO] [stderr] Compiling parking_lot_core v0.9.1 [INFO] [stderr] Compiling inotify v0.9.6 [INFO] [stderr] Compiling rand_core v0.6.3 [INFO] [stderr] Compiling futures-util v0.3.21 [INFO] [stderr] Compiling rand_core v0.5.1 [INFO] [stderr] Compiling tracing v0.1.31 [INFO] [stderr] Compiling mio v0.8.0 [INFO] [stderr] Compiling tracing-log v0.1.2 [INFO] [stderr] Compiling mio v0.7.14 [INFO] [stderr] Compiling crossbeam-channel v0.5.2 [INFO] [stderr] Compiling parking_lot v0.12.0 [INFO] [stderr] Compiling os_str_bytes v6.0.0 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling openssl v0.10.38 [INFO] [stderr] Compiling generic-array v0.14.5 [INFO] [stderr] Compiling matchers v0.0.1 [INFO] [stderr] Compiling clap v3.1.6 [INFO] [stderr] Compiling notify v5.0.0-pre.13 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling universal-hash v0.4.1 [INFO] [stderr] Compiling cipher v0.3.0 [INFO] [stderr] Compiling digest v0.9.0 [INFO] [stderr] Compiling 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 curve25519-dalek v3.2.1 [INFO] [stderr] Compiling blake2 v0.9.2 [INFO] [stderr] Compiling sha2 v0.9.9 [INFO] [stderr] Compiling ctr v0.8.0 [INFO] [stderr] Compiling aes v0.7.5 [INFO] [stderr] Compiling chacha20 v0.7.3 [INFO] [stderr] Compiling digest v0.10.3 [INFO] [stderr] Compiling ghash v0.4.4 [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 bytes v1.1.0 [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 native-tls v0.2.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 backoff v0.4.0 [INFO] [stderr] Compiling async-socks5 v0.5.1 [INFO] [stderr] Compiling snowstorm v0.3.1 [INFO] [stderr] Compiling rathole v0.4.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 51.76s [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" "ec6ea65d547f649a027eb1fa080f4b710b50fc8192575e1e2cc8b23607afe632", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ec6ea65d547f649a027eb1fa080f4b710b50fc8192575e1e2cc8b23607afe632", kill_on_drop: false }` [INFO] [stdout] ec6ea65d547f649a027eb1fa080f4b710b50fc8192575e1e2cc8b23607afe632 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1b74e096b9bfb06f84a3007193dcd2f059cbdf6a" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 58b718479f3d6b947ce005ea7444ff2804c843f7bbfc8f8d6845ae336ed5ef04 [INFO] running `Command { std: "docker" "start" "-a" "58b718479f3d6b947ce005ea7444ff2804c843f7bbfc8f8d6845ae336ed5ef04", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.42s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rathole-a4d338eabfca104e) [INFO] [stdout] [INFO] [stdout] running 7 tests [INFO] [stdout] test config::tests::test_example_config ... ok [INFO] [stdout] test config::tests::test_validate_client_config ... ok [INFO] [stdout] test config::tests::test_validate_server_config ... ok [INFO] [stdout] test config::tests::test_invalid_config ... ok [INFO] [stdout] test tests::test_determine_run_mode ... ok [INFO] [stdout] test config_watcher::test::test_calculate_events ... 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.02s [INFO] [stdout] [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/rathole-31ec9bf68233c696) [INFO] [stdout] [INFO] [stderr] Running tests/integration_test.rs (/opt/rustwide/target/debug/deps/integration_test-946f1e482d5da1cc) [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 04:27:53.941  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: start the client [INFO] [stdout] Apr 27 04:27:53.941  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: start the client [INFO] [stdout] Apr 27 04:27:53.949  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:27:53.949  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 04:27:53.950  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 04:27:53.950 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 451.397017ms... [INFO] [stdout] Apr 27 04:27:53.951 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 516.54746ms... [INFO] [stdout] Apr 27 04:27:53.955  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:27:53.956  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 04:27:53.956  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 04:27:53.957 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 491.733153ms... [INFO] [stdout] Apr 27 04:27:53.957 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 477.278532ms... [INFO] [stdout] Apr 27 04:27:54.404 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 799.82371ms... [INFO] [stdout] Apr 27 04:27:54.436 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 744.00744ms... [INFO] [stdout] Apr 27 04:27:54.450 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 743.169187ms... [INFO] [stdout] Apr 27 04:27:54.469 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 797.38171ms... [INFO] [stdout] Apr 27 04:27:54.942  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: start the server [INFO] [stdout] Apr 27 04:27:54.944  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:27:54.944  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Apr 27 04:27:54.947  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: start the server [INFO] [stdout] Apr 27 04:27:54.949  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:27:54.949  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Apr 27 04:27:55.183  INFO connection{addr=127.0.0.1:57242}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:27:55.183  INFO connection{addr=127.0.0.1:57242}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 04:27:55.184  INFO connection{addr=127.0.0.1:57242}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 04:27:55.184  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:27:55.195  INFO connection{addr=127.0.0.1:57260}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:27:55.196  INFO connection{addr=127.0.0.1:57260}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 04:27:55.196  INFO connection{addr=127.0.0.1:57260}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 04:27:55.196  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:27:55.250  INFO connection{addr=127.0.0.1:47020}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:27:55.251  INFO connection{addr=127.0.0.1:47020}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 04:27:55.251  INFO connection{addr=127.0.0.1:47020}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 04:27:55.251  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:27:55.268  INFO connection{addr=127.0.0.1:47026}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:27:55.269  INFO connection{addr=127.0.0.1:47026}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 04:27:55.269  INFO connection{addr=127.0.0.1:47026}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 04:27:55.269  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:27:57.443  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Apr 27 04:27:57.450  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Apr 27 04:27:57.453  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Apr 27 04:27:57.455  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: shutdown the client [INFO] [stdout] Apr 27 04:27:57.456  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:27:57.456  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:27:57.456  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:27:57.456  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: restart the client [INFO] [stdout] Apr 27 04:27:57.459  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:27:57.459  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 04:27:57.459  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 04:27:57.460  INFO connection{addr=127.0.0.1:47032}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:27:57.461  INFO connection{addr=127.0.0.1:47034}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:27:57.462  WARN connection{addr=127.0.0.1:47032}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Apr 27 04:27:57.462  INFO connection{addr=127.0.0.1:47032}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 04:27:57.463  WARN connection{addr=127.0.0.1:47034}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Apr 27 04:27:57.463  INFO connection{addr=127.0.0.1:47034}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 04:27:57.463  INFO connection{addr=127.0.0.1:47020}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:27:57.463  INFO connection{addr=127.0.0.1:47032}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 04:27:57.464  INFO connection{addr=127.0.0.1:47026}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:27:57.464  INFO connection{addr=127.0.0.1:47034}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 04:27:57.465  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:27:57.465  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:27:57.465  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 04:27:57.465  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:57.466  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 04:27:57.466  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:57.485  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Apr 27 04:27:57.509  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: shutdown the client [INFO] [stdout] Apr 27 04:27:57.509  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:27:57.509  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:27:57.509  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:27:57.510  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: restart the client [INFO] [stdout] Apr 27 04:27:57.523  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:27:57.523  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 04:27:57.524  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 04:27:57.525  INFO connection{addr=127.0.0.1:57282}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:27:57.525  INFO connection{addr=127.0.0.1:57286}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:27:57.525  WARN connection{addr=127.0.0.1:57282}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Apr 27 04:27:57.526  INFO connection{addr=127.0.0.1:57282}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 04:27:57.526  WARN connection{addr=127.0.0.1:57286}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Apr 27 04:27:57.526  INFO connection{addr=127.0.0.1:57286}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 04:27:57.527  INFO connection{addr=127.0.0.1:57260}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 04:27:57.527  INFO connection{addr=127.0.0.1:57260}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:27:57.527  INFO connection{addr=127.0.0.1:57242}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 04:27:57.528  INFO connection{addr=127.0.0.1:57242}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:27:57.528  INFO connection{addr=127.0.0.1:57260}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 04:27:57.528  INFO connection{addr=127.0.0.1:57282}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 04:27:57.529  INFO connection{addr=127.0.0.1:57242}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 04:27:57.529  INFO connection{addr=127.0.0.1:57286}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 04:27:57.529  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:27:57.530  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:27:57.530  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:57.530  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:57.531  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:57.531  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:57.531  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:57.531  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:57.531  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:57.532  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:57.532  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:57.532  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:57.532  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:57.532  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:57.533  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:57.533  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:57.533  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:57.533  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.457  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Apr 27 04:27:58.460  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Apr 27 04:27:58.461  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: shutdown the server [INFO] [stdout] Apr 27 04:27:58.461  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:27:58.462  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 04:27:58.462  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 04:27:58.462  INFO connection{addr=127.0.0.1:47034}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:27:58.462  INFO connection{addr=127.0.0.1:47032}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:27:58.462  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: restart the server [INFO] [stdout] Apr 27 04:27:58.463 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 456.874106ms... [INFO] [stdout] Apr 27 04:27:58.463  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 04:27:58.463  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.463 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 502.474898ms... [INFO] [stdout] Apr 27 04:27:58.464  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 04:27:58.464  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.469  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:27:58.470  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Apr 27 04:27:58.511  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Apr 27 04:27:58.559  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Apr 27 04:27:58.589  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: shutdown the server [INFO] [stdout] Apr 27 04:27:58.589  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:27:58.589  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 04:27:58.589  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 04:27:58.590  INFO connection{addr=127.0.0.1:57282}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 04:27:58.590  INFO connection{addr=127.0.0.1:57282}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:27:58.590  INFO connection{addr=127.0.0.1:57286}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 04:27:58.590  INFO connection{addr=127.0.0.1:57286}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:27:58.590  INFO connection{addr=127.0.0.1:57282}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 04:27:58.591  INFO connection{addr=127.0.0.1:57286}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 04:27:58.591  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: restart the server [INFO] [stdout] Apr 27 04:27:58.591 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 457.51245ms... [INFO] [stdout] Apr 27 04:27:58.591 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 521.558766ms... [INFO] [stdout] Apr 27 04:27:58.592  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.592  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.592  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.593  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.593  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.593  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.593  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.593  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.594  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.594  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.594  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.594  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.595  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.595  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.595  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.596  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:27:58.597  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:27:58.598  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Apr 27 04:27:58.922  INFO connection{addr=127.0.0.1:47044}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:27:58.923  INFO connection{addr=127.0.0.1:47044}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 04:27:58.923  INFO connection{addr=127.0.0.1:47044}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 04:27:58.923  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:27:58.967  INFO connection{addr=127.0.0.1:47052}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:27:58.968  INFO connection{addr=127.0.0.1:47052}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 04:27:58.968  INFO connection{addr=127.0.0.1:47052}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 04:27:58.969  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:27:59.051  INFO connection{addr=127.0.0.1:57328}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:27:59.052  INFO connection{addr=127.0.0.1:57328}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 04:27:59.053  INFO connection{addr=127.0.0.1:57328}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 04:27:59.053  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:27:59.115  INFO connection{addr=127.0.0.1:57346}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:27:59.116  INFO connection{addr=127.0.0.1:57346}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 04:27:59.117  INFO connection{addr=127.0.0.1:57346}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 04:27:59.117  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:00.964  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: lots of echo and pingpong [INFO] [stdout] Apr 27 04:28:00.969  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: shutdown the server and the client [INFO] [stdout] Apr 27 04:28:00.969  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:00.969  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:00.969  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 04:28:00.969  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:00.970  INFO connection{addr=127.0.0.1:47044}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:00.970  INFO connection{addr=127.0.0.1:47052}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:00.970  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:00.970  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:00.971  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: start the client [INFO] [stdout] Apr 27 04:28:00.971  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 04:28:00.971  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:00.971  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 04:28:00.972  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:00.974  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:28:00.998  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 04:28:00.998  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 04:28:00.999 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.716655ms... [INFO] [stdout] Apr 27 04:28:00.999 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 538.166069ms... [INFO] [stdout] Apr 27 04:28:01.093  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: lots of echo and pingpong [INFO] [stdout] Apr 27 04:28:01.191  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: shutdown the server and the client [INFO] [stdout] Apr 27 04:28:01.192  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:01.192  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:01.192  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 04:28:01.192  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:01.193  INFO connection{addr=127.0.0.1:57328}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 04:28:01.193  INFO connection{addr=127.0.0.1:57328}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:01.193  INFO connection{addr=127.0.0.1:57346}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 04:28:01.193  INFO connection{addr=127.0.0.1:57346}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:01.193  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:01.193  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:01.194  INFO connection{addr=127.0.0.1:57328}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:01.194  INFO connection{addr=127.0.0.1:57346}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:01.194  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: start the client [INFO] [stdout] Apr 27 04:28:01.195  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:01.195  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:01.195  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:01.195  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:01.196  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:01.196  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:01.196  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:01.196  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:01.196  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:01.196  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:01.197  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:01.197  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:01.197  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:01.197  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:01.198  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:01.198  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:01.200  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:28:01.207  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 04:28:01.207  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 04:28:01.208 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 463.835026ms... [INFO] [stdout] Apr 27 04:28:01.209 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 495.270959ms... [INFO] [stdout] Apr 27 04:28:01.532 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 694.042262ms... [INFO] [stdout] Apr 27 04:28:01.539 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 804.376647ms... [INFO] [stdout] Apr 27 04:28:01.674 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 691.590161ms... [INFO] [stdout] Apr 27 04:28:01.706 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 788.532257ms... [INFO] [stdout] Apr 27 04:28:01.971  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: start the server [INFO] [stdout] Apr 27 04:28:01.973  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:28:01.977  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Apr 27 04:28:02.196  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: start the server [INFO] [stdout] Apr 27 04:28:02.198  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:28:02.200  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Apr 27 04:28:02.238  INFO connection{addr=127.0.0.1:47066}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:02.239  INFO connection{addr=127.0.0.1:47066}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 04:28:02.239  INFO connection{addr=127.0.0.1:47066}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 04:28:02.239  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:02.353  INFO connection{addr=127.0.0.1:47072}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:02.354  INFO connection{addr=127.0.0.1:47072}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 04:28:02.354  INFO connection{addr=127.0.0.1:47072}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 04:28:02.354  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:02.377  INFO connection{addr=127.0.0.1:57384}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:02.378  INFO connection{addr=127.0.0.1:57384}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 04:28:02.379  INFO connection{addr=127.0.0.1:57384}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 04:28:02.379  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:02.505  INFO connection{addr=127.0.0.1:57402}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:02.505  INFO connection{addr=127.0.0.1:57402}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 04:28:02.506  INFO connection{addr=127.0.0.1:57402}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 04:28:02.506  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:04.473  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Apr 27 04:28:04.477  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Apr 27 04:28:04.479  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: shutdown the client [INFO] [stdout] Apr 27 04:28:04.479  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:04.480  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:04.480  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:04.480  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: restart the client [INFO] [stdout] Apr 27 04:28:04.483  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:28:04.490  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 04:28:04.490  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 04:28:04.504  INFO connection{addr=127.0.0.1:47078}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:04.505  WARN connection{addr=127.0.0.1:47078}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Apr 27 04:28:04.506  INFO connection{addr=127.0.0.1:47078}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 04:28:04.506  INFO connection{addr=127.0.0.1:47072}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:04.507  INFO connection{addr=127.0.0.1:47078}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 04:28:04.507  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:04.507  INFO connection{addr=127.0.0.1:47080}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:04.508  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 04:28:04.508  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:04.509  WARN connection{addr=127.0.0.1:47080}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Apr 27 04:28:04.509  INFO connection{addr=127.0.0.1:47080}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 04:28:04.515  INFO connection{addr=127.0.0.1:47066}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:04.516  INFO connection{addr=127.0.0.1:47080}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 04:28:04.516  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:04.517  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 04:28:04.517  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:04.698  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Apr 27 04:28:04.755  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Apr 27 04:28:04.793  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: shutdown the client [INFO] [stdout] Apr 27 04:28:04.793  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:04.793  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:04.794  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:04.794  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: restart the client [INFO] [stdout] Apr 27 04:28:04.796  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:28:04.802  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 04:28:04.803  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 04:28:04.816  INFO connection{addr=127.0.0.1:57426}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:04.818  WARN connection{addr=127.0.0.1:57426}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Apr 27 04:28:04.818  INFO connection{addr=127.0.0.1:57426}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 04:28:04.818  INFO connection{addr=127.0.0.1:57384}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 04:28:04.819  INFO connection{addr=127.0.0.1:57384}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:04.819  INFO connection{addr=127.0.0.1:57384}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:04.820  INFO connection{addr=127.0.0.1:57426}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 04:28:04.820  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:04.820  INFO connection{addr=127.0.0.1:57428}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:04.821  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:04.821  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:04.821  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:04.822  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:04.822  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:04.822  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:04.823  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:04.823  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:04.826  WARN connection{addr=127.0.0.1:57428}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Apr 27 04:28:04.826  INFO connection{addr=127.0.0.1:57428}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 04:28:04.833  INFO connection{addr=127.0.0.1:57402}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 04:28:04.833  INFO connection{addr=127.0.0.1:57402}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:04.835  INFO connection{addr=127.0.0.1:57402}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:04.835  INFO connection{addr=127.0.0.1:57428}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 04:28:04.835  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:04.837  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:04.837  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:04.837  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:04.838  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:04.838  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:04.838  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:04.839  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:04.840  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.483  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Apr 27 04:28:05.527  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Apr 27 04:28:05.530  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: shutdown the server [INFO] [stdout] Apr 27 04:28:05.530  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:05.530  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 04:28:05.530  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:05.530  INFO connection{addr=127.0.0.1:47078}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:05.531  INFO connection{addr=127.0.0.1:47080}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:05.531  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: restart the server [INFO] [stdout] Apr 27 04:28:05.531 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 525.04518ms... [INFO] [stdout] Apr 27 04:28:05.531  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 04:28:05.532  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.532 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 539.468773ms... [INFO] [stdout] Apr 27 04:28:05.532  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 04:28:05.532  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.534  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:28:05.536  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Apr 27 04:28:05.795  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Apr 27 04:28:05.874  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Apr 27 04:28:05.931  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: shutdown the server [INFO] [stdout] Apr 27 04:28:05.931  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:05.931  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 04:28:05.931  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:05.932  INFO connection{addr=127.0.0.1:57428}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 04:28:05.932  INFO connection{addr=127.0.0.1:57428}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:05.932  INFO connection{addr=127.0.0.1:57426}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 04:28:05.932  INFO connection{addr=127.0.0.1:57426}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:05.932  INFO connection{addr=127.0.0.1:57428}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:05.933  INFO connection{addr=127.0.0.1:57426}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:05.933  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: restart the server [INFO] [stdout] Apr 27 04:28:05.934 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 540.192598ms... [INFO] [stdout] Apr 27 04:28:05.934 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 507.60559ms... [INFO] [stdout] Apr 27 04:28:05.934  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.935  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.935  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.935  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.935  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.936  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.936  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.936  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.936  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.937  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.937  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.937  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.937  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.938  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.938  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.938  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:05.940  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:28:05.943  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Apr 27 04:28:06.071  INFO connection{addr=127.0.0.1:47090}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:06.072  INFO connection{addr=127.0.0.1:47090}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 04:28:06.072  INFO connection{addr=127.0.0.1:47090}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 04:28:06.073  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:06.086  INFO connection{addr=127.0.0.1:47092}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:06.088  INFO connection{addr=127.0.0.1:47092}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 04:28:06.094  INFO connection{addr=127.0.0.1:47092}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 04:28:06.094  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:06.454  INFO connection{addr=127.0.0.1:57466}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:06.454  INFO connection{addr=127.0.0.1:57466}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 04:28:06.455  INFO connection{addr=127.0.0.1:57466}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 04:28:06.455  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:06.525  INFO connection{addr=127.0.0.1:57484}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:06.526  INFO connection{addr=127.0.0.1:57484}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 04:28:06.527  INFO connection{addr=127.0.0.1:57484}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 04:28:06.527  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:08.033  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: lots of echo and pingpong [INFO] [stdout] Apr 27 04:28:08.038  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: shutdown the server and the client [INFO] [stdout] Apr 27 04:28:08.038  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:08.038  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:08.039  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 04:28:08.039  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:08.039  INFO connection{addr=127.0.0.1:47092}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:08.039  INFO connection{addr=127.0.0.1:47090}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:08.040  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:08.040  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:08.040  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: start the client [INFO] [stdout] Apr 27 04:28:08.040  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 04:28:08.041  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:08.041  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 04:28:08.041  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:08.046  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:28:08.047  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 04:28:08.047  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 04:28:08.047 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 504.226385ms... [INFO] [stdout] Apr 27 04:28:08.048 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2332: Failed to connect TCP socket: Connection refused (os error 111). Retry in 467.838365ms... [INFO] [stdout] Apr 27 04:28:08.434  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: lots of echo and pingpong [INFO] [stdout] Apr 27 04:28:08.518 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 706.45238ms... [INFO] [stdout] Apr 27 04:28:08.554 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 779.002597ms... [INFO] [stdout] Apr 27 04:28:08.591  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: shutdown the server and the client [INFO] [stdout] Apr 27 04:28:08.592  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:08.592  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:08.592  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 04:28:08.592  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:08.592  INFO connection{addr=127.0.0.1:57466}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 04:28:08.593  INFO connection{addr=127.0.0.1:57466}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:08.593  INFO connection{addr=127.0.0.1:57484}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 04:28:08.593  INFO connection{addr=127.0.0.1:57484}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:08.593  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:08.593  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:08.594  INFO connection{addr=127.0.0.1:57466}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:08.594  INFO connection{addr=127.0.0.1:57484}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:08.594  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: start the client [INFO] [stdout] Apr 27 04:28:08.595  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:08.595  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:08.595  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:08.596  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:08.596  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:08.596  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:08.596  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:08.597  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:08.597  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:08.597  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:08.597  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:08.598  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:08.598  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:08.598  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:08.598  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:08.599  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:08.607  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:28:08.608  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 04:28:08.609  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 04:28:08.609 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 516.040679ms... [INFO] [stdout] Apr 27 04:28:08.609 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 503.552875ms... [INFO] [stdout] Apr 27 04:28:09.042  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: start the server [INFO] [stdout] Apr 27 04:28:09.054  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:28:09.055  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Apr 27 04:28:09.114 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 715.253491ms... [INFO] [stdout] Apr 27 04:28:09.131 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 781.244729ms... [INFO] [stdout] Apr 27 04:28:09.234  INFO connection{addr=127.0.0.1:47112}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:09.235  INFO connection{addr=127.0.0.1:47112}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 04:28:09.236  INFO connection{addr=127.0.0.1:47112}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 04:28:09.236  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:09.343  INFO connection{addr=127.0.0.1:47118}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:09.344  INFO connection{addr=127.0.0.1:47118}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 04:28:09.344  INFO connection{addr=127.0.0.1:47118}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 04:28:09.345  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:09.596  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: start the server [INFO] [stdout] Apr 27 04:28:09.598  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:28:09.598  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Apr 27 04:28:09.858  INFO connection{addr=127.0.0.1:57518}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:09.858  INFO connection{addr=127.0.0.1:57518}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 04:28:09.859  INFO connection{addr=127.0.0.1:57518}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 04:28:09.860  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:09.937  INFO connection{addr=127.0.0.1:57542}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:09.938  INFO connection{addr=127.0.0.1:57542}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 04:28:09.939  INFO connection{addr=127.0.0.1:57542}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 04:28:09.939  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:11.544  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Apr 27 04:28:11.549  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Apr 27 04:28:11.554  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: shutdown the client [INFO] [stdout] Apr 27 04:28:11.554  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:11.554  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:11.554  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:11.555  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: restart the client [INFO] [stdout] Apr 27 04:28:11.558  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:28:11.559  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 04:28:11.559  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 04:28:11.574  INFO connection{addr=127.0.0.1:47124}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:11.578  INFO connection{addr=127.0.0.1:47126}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:11.579  WARN connection{addr=127.0.0.1:47124}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Apr 27 04:28:11.579  INFO connection{addr=127.0.0.1:47124}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 04:28:11.580  INFO connection{addr=127.0.0.1:47112}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:11.580  INFO connection{addr=127.0.0.1:47124}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 04:28:11.581  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:11.581  WARN connection{addr=127.0.0.1:47126}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Apr 27 04:28:11.581  INFO connection{addr=127.0.0.1:47126}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 04:28:11.582  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 04:28:11.582  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:11.582  INFO connection{addr=127.0.0.1:47118}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:11.583  INFO connection{addr=127.0.0.1:47126}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 04:28:11.583  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:11.590  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 04:28:11.590  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.098  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Apr 27 04:28:12.322  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Apr 27 04:28:12.390  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: shutdown the client [INFO] [stdout] Apr 27 04:28:12.390  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:12.391  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:12.391  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:12.391  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: restart the client [INFO] [stdout] Apr 27 04:28:12.437  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:28:12.438  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 27 04:28:12.438  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 27 04:28:12.453  INFO connection{addr=127.0.0.1:57564}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:12.455  INFO connection{addr=127.0.0.1:57566}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:12.455  WARN connection{addr=127.0.0.1:57564}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Apr 27 04:28:12.456  INFO connection{addr=127.0.0.1:57564}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 04:28:12.456  INFO connection{addr=127.0.0.1:57542}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 04:28:12.456  INFO connection{addr=127.0.0.1:57542}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:12.457  INFO connection{addr=127.0.0.1:57542}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:12.457  INFO connection{addr=127.0.0.1:57564}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 04:28:12.458  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:12.459  WARN connection{addr=127.0.0.1:57566}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Apr 27 04:28:12.459  INFO connection{addr=127.0.0.1:57566}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 04:28:12.459  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.459  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.460  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.460  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.460  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.460  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.460  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.460  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.461  INFO connection{addr=127.0.0.1:57518}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 04:28:12.461  INFO connection{addr=127.0.0.1:57518}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:12.462  INFO connection{addr=127.0.0.1:57518}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:12.463  INFO connection{addr=127.0.0.1:57566}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 04:28:12.463  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:12.486  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.486  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.486  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.486  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.486  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.486  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.487  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.487  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.555  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Apr 27 04:28:12.561  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Apr 27 04:28:12.566  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: shutdown the server [INFO] [stdout] Apr 27 04:28:12.569  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:12.570  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 04:28:12.570  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:12.570  INFO connection{addr=127.0.0.1:47124}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:12.570  INFO connection{addr=127.0.0.1:47126}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:12.571  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: restart the server [INFO] [stdout] Apr 27 04:28:12.571 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 470.487415ms... [INFO] [stdout] Apr 27 04:28:12.571  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 04:28:12.572  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.572 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 543.997709ms... [INFO] [stdout] Apr 27 04:28:12.572  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 27 04:28:12.573  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:12.609  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:28:12.610  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Apr 27 04:28:13.051  INFO connection{addr=127.0.0.1:47136}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:13.052  INFO connection{addr=127.0.0.1:47136}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 04:28:13.052  INFO connection{addr=127.0.0.1:47136}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 04:28:13.053  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:13.125  INFO connection{addr=127.0.0.1:47144}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:13.126  INFO connection{addr=127.0.0.1:47144}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 04:28:13.127  INFO connection{addr=127.0.0.1:47144}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 04:28:13.127  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:13.444  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Apr 27 04:28:13.662  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Apr 27 04:28:14.496  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: shutdown the server [INFO] [stdout] Apr 27 04:28:14.496  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:14.496  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 04:28:14.496  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:14.496  INFO connection{addr=127.0.0.1:57566}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 04:28:14.496  INFO connection{addr=127.0.0.1:57566}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:14.496  INFO connection{addr=127.0.0.1:57564}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 04:28:14.497  INFO connection{addr=127.0.0.1:57564}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:14.497  INFO connection{addr=127.0.0.1:57566}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:14.497  INFO connection{addr=127.0.0.1:57564}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:14.497  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: restart the server [INFO] [stdout] Apr 27 04:28:14.498 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 452.513878ms... [INFO] [stdout] Apr 27 04:28:14.498 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 519.129229ms... [INFO] [stdout] Apr 27 04:28:14.498  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:14.498  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:14.498  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:14.498  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:14.499  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:14.499  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:14.499  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:14.499  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:14.499  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:14.499  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:14.500  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:14.500  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:14.500  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:14.500  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:14.500  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:14.501  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 27 04:28:14.513  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 27 04:28:14.513  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Apr 27 04:28:14.960  INFO connection{addr=127.0.0.1:57608}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:14.961  INFO connection{addr=127.0.0.1:57608}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 27 04:28:14.962  INFO connection{addr=127.0.0.1:57608}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 27 04:28:14.962  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:15.037  INFO connection{addr=127.0.0.1:57628}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 27 04:28:15.038  INFO connection{addr=127.0.0.1:57628}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 27 04:28:15.039  INFO connection{addr=127.0.0.1:57628}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 27 04:28:15.039  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 27 04:28:15.072  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: lots of echo and pingpong [INFO] [stdout] Apr 27 04:28:15.088  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: shutdown the server and the client [INFO] [stdout] Apr 27 04:28:15.093  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:15.094  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:15.094  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 04:28:15.094  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:15.094  INFO connection{addr=127.0.0.1:47136}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:15.095  INFO connection{addr=127.0.0.1:47144}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:15.095  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:15.095  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] test udp ... ok [INFO] [stdout] Apr 27 04:28:16.998  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: lots of echo and pingpong [INFO] [stdout] Apr 27 04:28:17.374  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: shutdown the server and the client [INFO] [stdout] Apr 27 04:28:17.374  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:17.375  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 27 04:28:17.375  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 27 04:28:17.375  INFO rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:17.375  INFO connection{addr=127.0.0.1:57628}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 04:28:17.375  INFO connection{addr=127.0.0.1:57628}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:17.375  INFO connection{addr=127.0.0.1:57608}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 27 04:28:17.375  INFO connection{addr=127.0.0.1:57608}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 27 04:28:17.375  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:17.376  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 27 04:28:17.376  INFO connection{addr=127.0.0.1:57628}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 27 04:28:17.376  INFO connection{addr=127.0.0.1:57608}:handle{service=pingpong}: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 23.46s [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" "58b718479f3d6b947ce005ea7444ff2804c843f7bbfc8f8d6845ae336ed5ef04", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "58b718479f3d6b947ce005ea7444ff2804c843f7bbfc8f8d6845ae336ed5ef04", kill_on_drop: false }` [INFO] [stdout] 58b718479f3d6b947ce005ea7444ff2804c843f7bbfc8f8d6845ae336ed5ef04