[INFO] cloning repository https://github.com/rapiz1/rathole [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/rapiz1/rathole" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frapiz1%2Frathole", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frapiz1%2Frathole'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 8665e6a2cfb57ddfc4b0f5a8cf116b3a69d06521 [INFO] testing rapiz1/rathole against beta-2022-04-10 for beta-1.61-1 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frapiz1%2Frathole" "/workspace/builds/worker-25/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-25/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/rapiz1/rathole on toolchain beta-2022-04-10 [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking git repo https://github.com/rapiz1/rathole [INFO] finished tweaking git repo https://github.com/rapiz1/rathole [INFO] tweaked toml for git repo https://github.com/rapiz1/rathole written to /workspace/builds/worker-25/source/Cargo.toml [INFO] crate git repo https://github.com/rapiz1/rathole already has a lockfile, it will not be regenerated [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-25/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-25/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" "+beta-2022-04-10" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 24093b8f2b9e658dd118155da2b20b9412325b432e95560b32e6bab2fb376630 [INFO] running `Command { std: "docker" "start" "-a" "24093b8f2b9e658dd118155da2b20b9412325b432e95560b32e6bab2fb376630", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "24093b8f2b9e658dd118155da2b20b9412325b432e95560b32e6bab2fb376630", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "24093b8f2b9e658dd118155da2b20b9412325b432e95560b32e6bab2fb376630", kill_on_drop: false }` [INFO] [stdout] 24093b8f2b9e658dd118155da2b20b9412325b432e95560b32e6bab2fb376630 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-25/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-25/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=warn" "-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" "+beta-2022-04-10" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 98bb3bc6540f43b60c4a903916445f75785d6d9008989ae73e4b12dd14887691 [INFO] running `Command { std: "docker" "start" "-a" "98bb3bc6540f43b60c4a903916445f75785d6d9008989ae73e4b12dd14887691", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.119 [INFO] [stderr] Compiling syn v1.0.86 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling pkg-config v0.3.24 [INFO] [stderr] Compiling serde v1.0.136 [INFO] [stderr] Compiling subtle v2.4.1 [INFO] [stderr] Compiling cpufeatures v0.2.1 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling once_cell v1.10.0 [INFO] [stderr] Compiling opaque-debug v0.3.0 [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 matches v0.1.9 [INFO] [stderr] Compiling rustversion v1.0.6 [INFO] [stderr] Compiling anyhow v1.0.56 [INFO] [stderr] Compiling bitflags v1.3.2 [INFO] [stderr] Compiling unicode-bidi v0.3.7 [INFO] [stderr] Compiling ppv-lite86 v0.2.16 [INFO] [stderr] Compiling futures-core v0.3.21 [INFO] [stderr] Compiling openssl v0.10.38 [INFO] [stderr] Compiling tinyvec_macros v0.1.0 [INFO] [stderr] Compiling foreign-types-shared v0.1.1 [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 regex-syntax v0.6.25 [INFO] [stderr] Compiling native-tls v0.2.8 [INFO] [stderr] Compiling futures-util v0.3.21 [INFO] [stderr] Compiling httparse v1.6.0 [INFO] [stderr] Compiling serde_json v1.0.79 [INFO] [stderr] Compiling itoa v1.0.1 [INFO] [stderr] Compiling ryu v1.0.9 [INFO] [stderr] Compiling heck v0.4.0 [INFO] [stderr] Compiling same-file v1.0.6 [INFO] [stderr] Compiling hashbrown v0.11.2 [INFO] [stderr] Compiling slab v0.4.5 [INFO] [stderr] Compiling percent-encoding v2.1.0 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling openssl-probe v0.1.5 [INFO] [stderr] Compiling ansi_term v0.12.1 [INFO] [stderr] Compiling strsim v0.10.0 [INFO] [stderr] Compiling termcolor v1.1.3 [INFO] [stderr] Compiling textwrap v0.15.0 [INFO] [stderr] Compiling base64 v0.13.0 [INFO] [stderr] Compiling hex v0.4.3 [INFO] [stderr] Compiling num-traits v0.2.14 [INFO] [stderr] Compiling num-integer v0.1.44 [INFO] [stderr] Compiling typenum v1.15.0 [INFO] [stderr] Compiling memchr v2.4.1 [INFO] [stderr] Compiling form_urlencoded v1.0.1 [INFO] [stderr] Compiling tracing-core v0.1.23 [INFO] [stderr] Compiling sharded-slab v0.1.4 [INFO] [stderr] Compiling lock_api v0.4.6 [INFO] [stderr] Compiling thread_local v1.1.4 [INFO] [stderr] Compiling foreign-types v0.3.2 [INFO] [stderr] Compiling pest v2.1.3 [INFO] [stderr] Compiling log v0.4.14 [INFO] [stderr] Compiling instant v0.1.12 [INFO] [stderr] Compiling tinyvec v1.5.1 [INFO] [stderr] Compiling walkdir v2.3.2 [INFO] [stderr] Compiling idna v0.2.3 [INFO] [stderr] Compiling os_str_bytes v6.0.0 [INFO] [stderr] Compiling tracing-log v0.1.2 [INFO] [stderr] Compiling indexmap v1.8.0 [INFO] [stderr] Compiling crossbeam-channel v0.5.2 [INFO] [stderr] Compiling generic-array v0.14.5 [INFO] [stderr] Compiling url v2.2.2 [INFO] [stderr] Compiling semver-parser v0.10.2 [INFO] [stderr] Compiling unicode-normalization v0.1.19 [INFO] [stderr] Compiling jobserver v0.1.24 [INFO] [stderr] Compiling time v0.1.43 [INFO] [stderr] Compiling parking_lot_core v0.9.1 [INFO] [stderr] Compiling mio v0.8.0 [INFO] [stderr] Compiling socket2 v0.4.4 [INFO] [stderr] Compiling num_cpus v1.13.1 [INFO] [stderr] Compiling signal-hook-registry v1.4.0 [INFO] [stderr] Compiling getrandom v0.2.5 [INFO] [stderr] Compiling getrandom v0.1.16 [INFO] [stderr] Compiling inotify-sys v0.1.5 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling filetime v0.2.15 [INFO] [stderr] Compiling mio v0.7.14 [INFO] [stderr] Compiling fdlimit v0.2.1 [INFO] [stderr] Compiling cc v1.0.73 [INFO] [stderr] Compiling inotify v0.9.6 [INFO] [stderr] Compiling rand_core v0.6.3 [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 block-buffer v0.9.0 [INFO] [stderr] Compiling crypto-mac v0.8.0 [INFO] [stderr] Compiling crypto-common v0.1.3 [INFO] [stderr] Compiling block-buffer v0.10.2 [INFO] [stderr] Compiling parking_lot v0.12.0 [INFO] [stderr] Compiling rand_core v0.5.1 [INFO] [stderr] Compiling semver v0.11.0 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling aes v0.7.5 [INFO] [stderr] Compiling ctr v0.8.0 [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 rand_chacha v0.3.1 [INFO] [stderr] Compiling digest v0.10.3 [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 ghash v0.4.4 [INFO] [stderr] Compiling sha2 v0.10.2 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling snow v0.8.0 [INFO] [stderr] Compiling aes-gcm v0.9.4 [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 matchers v0.0.1 [INFO] [stderr] Compiling vergen v6.0.2 [INFO] [stderr] Compiling git2 v0.14.1 [INFO] [stderr] Compiling synstructure v0.12.6 [INFO] [stderr] Compiling proc-macro-error v1.0.4 [INFO] [stderr] Compiling serde_derive v1.0.136 [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 pin-project-internal v1.0.10 [INFO] [stderr] Compiling tracing-attributes v0.1.20 [INFO] [stderr] Compiling futures-macro v0.3.21 [INFO] [stderr] Compiling async-trait v0.1.52 [INFO] [stderr] Compiling clap_derive v3.1.4 [INFO] [stderr] Compiling enum-iterator v0.7.0 [INFO] [stderr] Compiling zeroize v1.3.0 [INFO] [stderr] Compiling curve25519-dalek v3.2.1 [INFO] [stderr] Compiling chacha20 v0.7.3 [INFO] [stderr] Compiling chacha20poly1305 v0.8.2 [INFO] [stderr] Compiling pin-project v1.0.10 [INFO] [stderr] Compiling thiserror v1.0.30 [INFO] [stderr] Compiling tracing v0.1.31 [INFO] [stderr] Compiling clap v3.1.6 [INFO] [stderr] Compiling x25519-dalek v1.2.0 [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 snowstorm v0.3.1 [INFO] [stderr] Compiling async-http-proxy v1.2.5 [INFO] [stderr] Compiling tokio-native-tls v0.3.0 [INFO] [stderr] Compiling backoff v0.4.0 [INFO] [stderr] Compiling async-socks5 v0.5.1 [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 29.70s [INFO] running `Command { std: "docker" "inspect" "98bb3bc6540f43b60c4a903916445f75785d6d9008989ae73e4b12dd14887691", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "98bb3bc6540f43b60c4a903916445f75785d6d9008989ae73e4b12dd14887691", kill_on_drop: false }` [INFO] [stdout] 98bb3bc6540f43b60c4a903916445f75785d6d9008989ae73e4b12dd14887691 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-25/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-25/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=warn" "-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" "+beta-2022-04-10" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 4779b27a8a50d4879e5fc1e72d225194f1a312b452ba4b7fc30b061dda350a23 [INFO] running `Command { std: "docker" "start" "-a" "4779b27a8a50d4879e5fc1e72d225194f1a312b452ba4b7fc30b061dda350a23", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Compiling subtle v2.4.1 [INFO] [stderr] Compiling opaque-debug v0.3.0 [INFO] [stderr] Compiling once_cell v1.10.0 [INFO] [stderr] Compiling smallvec v1.8.0 [INFO] [stderr] Compiling byteorder v1.4.3 [INFO] [stderr] Compiling openssl-probe v0.1.5 [INFO] [stderr] Compiling textwrap v0.15.0 [INFO] [stderr] Compiling strsim v0.10.0 [INFO] [stderr] Compiling termcolor v1.1.3 [INFO] [stderr] Compiling tracing-core v0.1.23 [INFO] [stderr] Compiling regex-automata v0.1.10 [INFO] [stderr] Compiling sharded-slab v0.1.4 [INFO] [stderr] Compiling regex v1.5.5 [INFO] [stderr] Compiling futures-core v0.3.21 [INFO] [stderr] Compiling walkdir v2.3.2 [INFO] [stderr] Compiling num_cpus v1.13.1 [INFO] [stderr] Compiling mio v0.8.0 [INFO] [stderr] Compiling signal-hook-registry v1.4.0 [INFO] [stderr] Compiling socket2 v0.4.4 [INFO] [stderr] Compiling getrandom v0.2.5 [INFO] [stderr] Compiling getrandom v0.1.16 [INFO] [stderr] Compiling crossbeam-utils v0.8.7 [INFO] [stderr] Compiling futures-task v0.3.21 [INFO] [stderr] Compiling inotify-sys v0.1.5 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling httparse v1.6.0 [INFO] [stderr] Compiling mio v0.7.14 [INFO] [stderr] Compiling os_str_bytes v6.0.0 [INFO] [stderr] Compiling filetime v0.2.15 [INFO] [stderr] Compiling fdlimit v0.2.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 block-buffer v0.9.0 [INFO] [stderr] Compiling block-buffer v0.10.2 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling serde v1.0.136 [INFO] [stderr] Compiling zeroize v1.3.0 [INFO] [stderr] Compiling openssl-sys v0.9.72 [INFO] [stderr] Compiling pin-project v1.0.10 [INFO] [stderr] Compiling universal-hash v0.4.1 [INFO] [stderr] Compiling crypto-mac v0.8.0 [INFO] [stderr] Compiling digest v0.10.3 [INFO] [stderr] Compiling sha2 v0.9.9 [INFO] [stderr] Compiling thread_local v1.1.4 [INFO] [stderr] Compiling inotify v0.9.6 [INFO] [stderr] Compiling blake2 v0.9.2 [INFO] [stderr] Compiling parking_lot_core v0.9.1 [INFO] [stderr] Compiling polyval v0.5.3 [INFO] [stderr] Compiling poly1305 v0.7.2 [INFO] [stderr] Compiling futures-util v0.3.21 [INFO] [stderr] Compiling sha2 v0.10.2 [INFO] [stderr] Compiling aes v0.7.5 [INFO] [stderr] Compiling chacha20 v0.7.3 [INFO] [stderr] Compiling ctr v0.8.0 [INFO] [stderr] Compiling rand_core v0.5.1 [INFO] [stderr] Compiling rand_core v0.6.3 [INFO] [stderr] Compiling tracing-log v0.1.2 [INFO] [stderr] Compiling tracing v0.1.31 [INFO] [stderr] Compiling openssl v0.10.38 [INFO] [stderr] Compiling clap v3.1.6 [INFO] [stderr] Compiling crossbeam-channel v0.5.2 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling matchers v0.0.1 [INFO] [stderr] Compiling curve25519-dalek v3.2.1 [INFO] [stderr] Compiling parking_lot v0.12.0 [INFO] [stderr] Compiling ghash v0.4.4 [INFO] [stderr] Compiling aes-gcm v0.9.4 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling chacha20poly1305 v0.8.2 [INFO] [stderr] Compiling notify v5.0.0-pre.13 [INFO] [stderr] Compiling x25519-dalek v1.2.0 [INFO] [stderr] Compiling snow v0.8.0 [INFO] [stderr] Compiling native-tls v0.2.8 [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 tokio v1.17.0 [INFO] [stderr] Compiling tracing-subscriber v0.2.25 [INFO] [stderr] Compiling async-socks5 v0.5.1 [INFO] [stderr] Compiling snowstorm v0.3.1 [INFO] [stderr] Compiling async-http-proxy v1.2.5 [INFO] [stderr] Compiling backoff v0.4.0 [INFO] [stderr] Compiling tokio-native-tls v0.3.0 [INFO] [stderr] Compiling rathole v0.4.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 1m 01s [INFO] [stderr] Executable unittests src/lib.rs (/opt/rustwide/target/debug/deps/rathole-951f5df0aad54b98) [INFO] [stderr] Executable unittests src/main.rs (/opt/rustwide/target/debug/deps/rathole-3ab60389802acdd8) [INFO] [stderr] Executable tests/integration_test.rs (/opt/rustwide/target/debug/deps/integration_test-ac2ec1d57b087ce8) [INFO] running `Command { std: "docker" "inspect" "4779b27a8a50d4879e5fc1e72d225194f1a312b452ba4b7fc30b061dda350a23", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "4779b27a8a50d4879e5fc1e72d225194f1a312b452ba4b7fc30b061dda350a23", kill_on_drop: false }` [INFO] [stdout] 4779b27a8a50d4879e5fc1e72d225194f1a312b452ba4b7fc30b061dda350a23 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-25/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-25/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=warn" "-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" "+beta-2022-04-10" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 3a5dbc5ae64dc91f428920a047f85b9bf39969a41d7188a47819e20906e59465 [INFO] running `Command { std: "docker" "start" "-a" "3a5dbc5ae64dc91f428920a047f85b9bf39969a41d7188a47819e20906e59465", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.43s [INFO] [stdout] [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rathole-951f5df0aad54b98) [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_valid_config ... ok [INFO] [stdout] test config::tests::test_validate_server_config ... ok [INFO] [stdout] test config::tests::test_invalid_config ... ok [INFO] [stdout] test config_watcher::test::test_calculate_events ... ok [INFO] [stdout] test tests::test_determine_run_mode ... 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-3ab60389802acdd8) [INFO] [stdout] [INFO] [stderr] Running tests/integration_test.rs (/opt/rustwide/target/debug/deps/integration_test-ac2ec1d57b087ce8) [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 16 02:40:18.606  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: start the client [INFO] [stdout] Apr 16 02:40:18.607  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: start the client [INFO] [stdout] Apr 16 02:40:18.617  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:18.617  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 16 02:40:18.617  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 16 02:40:18.618 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 492.40623ms... [INFO] [stdout] Apr 16 02:40:18.618 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 546.999355ms... [INFO] [stdout] Apr 16 02:40:18.625  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:18.625  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 16 02:40:18.625  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 16 02:40:18.626 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 465.132107ms... [INFO] [stdout] Apr 16 02:40:18.626 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 514.606719ms... [INFO] [stdout] Apr 16 02:40:19.093 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 711.961544ms... [INFO] [stdout] Apr 16 02:40:19.112 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2333: Connection refused (os error 111). Retry in 796.754174ms... [INFO] [stdout] Apr 16 02:40:19.143 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 716.045183ms... [INFO] [stdout] Apr 16 02:40:19.167 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 785.913108ms... [INFO] [stdout] Apr 16 02:40:19.608  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: start the server [INFO] [stdout] Apr 16 02:40:19.609  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: start the server [INFO] [stdout] Apr 16 02:40:19.610  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:19.610  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Apr 16 02:40:19.617  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:19.617  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Apr 16 02:40:19.807  INFO connection{addr=127.0.0.1:60630}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:19.807  INFO connection{addr=127.0.0.1:60630}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 16 02:40:19.808  INFO connection{addr=127.0.0.1:60630}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 16 02:40:19.808  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:19.860  INFO connection{addr=127.0.0.1:60636}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:19.861  INFO connection{addr=127.0.0.1:60636}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 16 02:40:19.861  INFO connection{addr=127.0.0.1:60636}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 16 02:40:19.862  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:19.912  INFO connection{addr=127.0.0.1:58874}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:19.912  INFO connection{addr=127.0.0.1:58874}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 16 02:40:19.913  INFO connection{addr=127.0.0.1:58874}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 16 02:40:19.913  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:19.966  INFO connection{addr=127.0.0.1:58892}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:19.967  INFO connection{addr=127.0.0.1:58892}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 16 02:40:19.967  INFO connection{addr=127.0.0.1:58892}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 16 02:40:19.967  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:22.110  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Apr 16 02:40:22.110  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Apr 16 02:40:22.113  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Apr 16 02:40:22.125  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: shutdown the client [INFO] [stdout] Apr 16 02:40:22.126  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:22.126  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:22.127  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:22.127  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: restart the client [INFO] [stdout] Apr 16 02:40:22.129  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:22.129  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 16 02:40:22.129  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 16 02:40:22.130  INFO connection{addr=127.0.0.1:60644}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:22.130  INFO connection{addr=127.0.0.1:60646}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:22.132  WARN connection{addr=127.0.0.1:60644}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Apr 16 02:40:22.132  INFO connection{addr=127.0.0.1:60644}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 16 02:40:22.132  WARN connection{addr=127.0.0.1:60646}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Apr 16 02:40:22.132  INFO connection{addr=127.0.0.1:60646}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 16 02:40:22.133  INFO connection{addr=127.0.0.1:60636}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:22.133  INFO connection{addr=127.0.0.1:60644}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 16 02:40:22.133  INFO connection{addr=127.0.0.1:60630}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:22.134  INFO connection{addr=127.0.0.1:60646}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 16 02:40:22.134  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:22.134  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:22.135  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 16 02:40:22.135  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:22.135  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 16 02:40:22.135  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:22.167  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Apr 16 02:40:22.193  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: shutdown the client [INFO] [stdout] Apr 16 02:40:22.195  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:22.195  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:22.195  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:22.196  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: restart the client [INFO] [stdout] Apr 16 02:40:22.197  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:22.198  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 16 02:40:22.198  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 16 02:40:22.199  INFO connection{addr=127.0.0.1:58916}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:22.199  INFO connection{addr=127.0.0.1:58918}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:22.200  WARN connection{addr=127.0.0.1:58916}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Apr 16 02:40:22.200  INFO connection{addr=127.0.0.1:58916}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 16 02:40:22.200  WARN connection{addr=127.0.0.1:58918}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Apr 16 02:40:22.200  INFO connection{addr=127.0.0.1:58918}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 16 02:40:22.200  INFO connection{addr=127.0.0.1:58892}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 16 02:40:22.201  INFO connection{addr=127.0.0.1:58892}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:22.257  INFO connection{addr=127.0.0.1:58874}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 16 02:40:22.258  INFO connection{addr=127.0.0.1:58874}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:22.258  INFO connection{addr=127.0.0.1:58892}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:22.258  INFO connection{addr=127.0.0.1:58916}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 16 02:40:22.259  INFO connection{addr=127.0.0.1:58874}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:22.259  INFO connection{addr=127.0.0.1:58918}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 16 02:40:22.259  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:22.260  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:22.260  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:22.260  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:22.260  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:22.261  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:22.261  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:22.261  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:22.261  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:22.261  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:22.262  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:22.262  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:22.262  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:22.262  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:22.263  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:22.263  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:22.263  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:22.263  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.128  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Apr 16 02:40:23.131  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Apr 16 02:40:23.134  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: shutdown the server [INFO] [stdout] Apr 16 02:40:23.134  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:23.134  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 16 02:40:23.134  INFO rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:23.134  INFO connection{addr=127.0.0.1:60646}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:23.135  INFO connection{addr=127.0.0.1:60644}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:23.135  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: restart the server [INFO] [stdout] Apr 16 02:40:23.135 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 469.277458ms... [INFO] [stdout] Apr 16 02:40:23.135  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 16 02:40:23.136  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.136 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 514.477562ms... [INFO] [stdout] Apr 16 02:40:23.136  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 16 02:40:23.136  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.145  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:23.145  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Apr 16 02:40:23.197  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Apr 16 02:40:23.251  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Apr 16 02:40:23.276  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: shutdown the server [INFO] [stdout] Apr 16 02:40:23.277  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:23.277  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 16 02:40:23.277  INFO rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:23.277  INFO connection{addr=127.0.0.1:58916}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 16 02:40:23.278  INFO connection{addr=127.0.0.1:58916}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:23.278  INFO connection{addr=127.0.0.1:58918}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 16 02:40:23.278  INFO connection{addr=127.0.0.1:58918}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:23.278  INFO connection{addr=127.0.0.1:58916}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:23.279  INFO connection{addr=127.0.0.1:58918}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:23.279  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: restart the server [INFO] [stdout] Apr 16 02:40:23.279 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 531.885594ms... [INFO] [stdout] Apr 16 02:40:23.280 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 485.049515ms... [INFO] [stdout] Apr 16 02:40:23.280  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.280  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.280  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.280  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.281  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.281  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.281  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.281  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.282  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.282  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.282  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.282  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.282  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.283  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.283  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.283  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:23.285  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:23.285  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Apr 16 02:40:23.606  INFO connection{addr=127.0.0.1:60656}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:23.607  INFO connection{addr=127.0.0.1:60656}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 16 02:40:23.607  INFO connection{addr=127.0.0.1:60656}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 16 02:40:23.608  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:23.653  INFO connection{addr=127.0.0.1:60662}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:23.653  INFO connection{addr=127.0.0.1:60662}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 16 02:40:23.654  INFO connection{addr=127.0.0.1:60662}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 16 02:40:23.654  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:23.766  INFO connection{addr=127.0.0.1:58962}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:23.767  INFO connection{addr=127.0.0.1:58962}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 16 02:40:23.767  INFO connection{addr=127.0.0.1:58962}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 16 02:40:23.768  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:23.813  INFO connection{addr=127.0.0.1:58980}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:23.813  INFO connection{addr=127.0.0.1:58980}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 16 02:40:23.814  INFO connection{addr=127.0.0.1:58980}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 16 02:40:23.814  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:25.636  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: lots of echo and pingpong [INFO] [stdout] Apr 16 02:40:25.643  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: shutdown the server and the client [INFO] [stdout] Apr 16 02:40:25.643  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:25.643  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:25.643  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 16 02:40:25.643  INFO rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:25.644  INFO connection{addr=127.0.0.1:60656}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:25.644  INFO connection{addr=127.0.0.1:60662}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:25.644  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:25.645  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:25.645  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: start the client [INFO] [stdout] Apr 16 02:40:25.645  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 16 02:40:25.645  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:25.645  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 16 02:40:25.646  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:25.649  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:25.664  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 16 02:40:25.664  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 16 02:40:25.664 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 506.057644ms... [INFO] [stdout] Apr 16 02:40:25.665 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 465.46997ms... [INFO] [stdout] Apr 16 02:40:25.781  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: lots of echo and pingpong [INFO] [stdout] Apr 16 02:40:25.880  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: shutdown the server and the client [INFO] [stdout] Apr 16 02:40:25.880  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:25.881  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:25.881  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 16 02:40:25.881  INFO rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:25.881  INFO connection{addr=127.0.0.1:58962}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 16 02:40:25.881  INFO connection{addr=127.0.0.1:58962}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:25.881  INFO connection{addr=127.0.0.1:58980}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 16 02:40:25.881  INFO connection{addr=127.0.0.1:58980}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:25.882  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:25.882  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:25.882  INFO connection{addr=127.0.0.1:58962}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:25.882  INFO connection{addr=127.0.0.1:58980}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:25.883  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: start the client [INFO] [stdout] Apr 16 02:40:25.883  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:25.883  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:25.883  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:25.883  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:25.884  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:25.884  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:25.884  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:25.884  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:25.884  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:25.884  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:25.884  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:25.885  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:25.885  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:25.885  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:25.885  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:25.885  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:25.897  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:25.904  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 16 02:40:25.904  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 16 02:40:25.905 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2333: Connection refused (os error 111). Retry in 479.047521ms... [INFO] [stdout] Apr 16 02:40:25.905 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.925687ms... [INFO] [stdout] Apr 16 02:40:26.132 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 676.530727ms... [INFO] [stdout] Apr 16 02:40:26.172 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 731.713356ms... [INFO] [stdout] Apr 16 02:40:26.386 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 675.283882ms... [INFO] [stdout] Apr 16 02:40:26.403 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 764.631128ms... [INFO] [stdout] Apr 16 02:40:26.647  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: start the server [INFO] [stdout] Apr 16 02:40:26.650  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:26.654  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Apr 16 02:40:26.822  INFO connection{addr=127.0.0.1:60676}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:26.823  INFO connection{addr=127.0.0.1:60676}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 16 02:40:26.823  INFO connection{addr=127.0.0.1:60676}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 16 02:40:26.823  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:26.884  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: start the server [INFO] [stdout] Apr 16 02:40:26.886  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:26.889  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Apr 16 02:40:26.912  INFO connection{addr=127.0.0.1:60682}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:26.913  INFO connection{addr=127.0.0.1:60682}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 16 02:40:26.913  INFO connection{addr=127.0.0.1:60682}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 16 02:40:26.914  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:27.072  INFO connection{addr=127.0.0.1:59016}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:27.077  INFO connection{addr=127.0.0.1:59016}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 16 02:40:27.078  INFO connection{addr=127.0.0.1:59016}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 16 02:40:27.078  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:27.178  INFO connection{addr=127.0.0.1:59038}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:27.179  INFO connection{addr=127.0.0.1:59038}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 16 02:40:27.179  INFO connection{addr=127.0.0.1:59038}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 16 02:40:27.180  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:29.148  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Apr 16 02:40:29.152  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Apr 16 02:40:29.155  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: shutdown the client [INFO] [stdout] Apr 16 02:40:29.156  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:29.157  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:29.157  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:29.158  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: restart the client [INFO] [stdout] Apr 16 02:40:29.161  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:29.170  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 16 02:40:29.170  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 16 02:40:29.185  INFO connection{addr=127.0.0.1:60688}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:29.187  WARN connection{addr=127.0.0.1:60688}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Apr 16 02:40:29.187  INFO connection{addr=127.0.0.1:60688}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 16 02:40:29.188  INFO connection{addr=127.0.0.1:60682}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:29.188  INFO connection{addr=127.0.0.1:60688}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 16 02:40:29.189  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:29.189  INFO connection{addr=127.0.0.1:60690}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:29.190  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 16 02:40:29.190  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:29.192  WARN connection{addr=127.0.0.1:60690}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Apr 16 02:40:29.192  INFO connection{addr=127.0.0.1:60690}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 16 02:40:29.198  INFO connection{addr=127.0.0.1:60676}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:29.199  INFO connection{addr=127.0.0.1:60690}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 16 02:40:29.200  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:29.201  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 16 02:40:29.202  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:29.386  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Apr 16 02:40:29.446  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Apr 16 02:40:29.488  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: shutdown the client [INFO] [stdout] Apr 16 02:40:29.488  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:29.488  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:29.489  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:29.489  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: restart the client [INFO] [stdout] Apr 16 02:40:29.491  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:29.496  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 16 02:40:29.496  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 16 02:40:29.508  INFO connection{addr=127.0.0.1:59060}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:29.509  WARN connection{addr=127.0.0.1:59060}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Apr 16 02:40:29.509  INFO connection{addr=127.0.0.1:59060}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 16 02:40:29.510  INFO connection{addr=127.0.0.1:59038}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 16 02:40:29.510  INFO connection{addr=127.0.0.1:59038}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:29.510  INFO connection{addr=127.0.0.1:59038}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:29.511  INFO connection{addr=127.0.0.1:59060}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 16 02:40:29.511  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:29.511  INFO connection{addr=127.0.0.1:59064}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:29.512  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:29.512  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:29.512  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:29.512  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:29.512  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:29.513  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:29.513  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:29.513  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:29.515  WARN connection{addr=127.0.0.1:59064}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Apr 16 02:40:29.515  INFO connection{addr=127.0.0.1:59064}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 16 02:40:29.520  INFO connection{addr=127.0.0.1:59016}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 16 02:40:29.521  INFO connection{addr=127.0.0.1:59016}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:29.521  INFO connection{addr=127.0.0.1:59016}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:29.521  INFO connection{addr=127.0.0.1:59064}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 16 02:40:29.521  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:29.522  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:29.522  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:29.523  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:29.523  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:29.523  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:29.523  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:29.523  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:29.524  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.158  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Apr 16 02:40:30.162  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Apr 16 02:40:30.165  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: shutdown the server [INFO] [stdout] Apr 16 02:40:30.165  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:30.165  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 16 02:40:30.165  INFO rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:30.166  INFO connection{addr=127.0.0.1:60688}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:30.166  INFO connection{addr=127.0.0.1:60690}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:30.167  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: restart the server [INFO] [stdout] Apr 16 02:40:30.167 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 521.679234ms... [INFO] [stdout] Apr 16 02:40:30.167  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 16 02:40:30.167  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.168 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 490.731867ms... [INFO] [stdout] Apr 16 02:40:30.168  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 16 02:40:30.168  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.173  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:30.176  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Apr 16 02:40:30.491  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Apr 16 02:40:30.552  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Apr 16 02:40:30.598  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: shutdown the server [INFO] [stdout] Apr 16 02:40:30.598  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:30.599  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 16 02:40:30.599  INFO rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:30.599  INFO connection{addr=127.0.0.1:59060}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 16 02:40:30.599  INFO connection{addr=127.0.0.1:59060}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:30.599  INFO connection{addr=127.0.0.1:59064}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 16 02:40:30.599  INFO connection{addr=127.0.0.1:59064}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:30.599  INFO connection{addr=127.0.0.1:59060}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:30.600  INFO connection{addr=127.0.0.1:59064}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:30.600  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: restart the server [INFO] [stdout] Apr 16 02:40:30.601 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 450.859467ms... [INFO] [stdout] Apr 16 02:40:30.601 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 472.466613ms... [INFO] [stdout] Apr 16 02:40:30.601  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.602  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.602  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.602  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.602  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.602  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.603  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.603  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.603  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.603  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.603  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.604  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.604  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.604  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.604  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.604  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:30.606  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:30.609  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Apr 16 02:40:30.669  INFO connection{addr=127.0.0.1:60700}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:30.670  INFO connection{addr=127.0.0.1:60700}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 16 02:40:30.670  INFO connection{addr=127.0.0.1:60700}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 16 02:40:30.671  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:30.697  INFO connection{addr=127.0.0.1:60708}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:30.698  INFO connection{addr=127.0.0.1:60708}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 16 02:40:30.698  INFO connection{addr=127.0.0.1:60708}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 16 02:40:30.699  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:31.063  INFO connection{addr=127.0.0.1:59102}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:31.063  INFO connection{addr=127.0.0.1:59102}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 16 02:40:31.064  INFO connection{addr=127.0.0.1:59102}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 16 02:40:31.064  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:31.133  INFO connection{addr=127.0.0.1:59120}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:31.133  INFO connection{addr=127.0.0.1:59120}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 16 02:40:31.134  INFO connection{addr=127.0.0.1:59120}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 16 02:40:31.134  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:32.667  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: lots of echo and pingpong [INFO] [stdout] Apr 16 02:40:32.673  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: shutdown the server and the client [INFO] [stdout] Apr 16 02:40:32.673  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:32.673  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:32.674  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 16 02:40:32.674  INFO rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:32.674  INFO connection{addr=127.0.0.1:60700}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:32.674  INFO connection{addr=127.0.0.1:60708}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:32.675  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:32.675  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:32.675  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: start the client [INFO] [stdout] Apr 16 02:40:32.676  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 16 02:40:32.676  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:32.676  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 16 02:40:32.676  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:32.678  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:32.679  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 16 02:40:32.679  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 16 02:40:32.680 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 528.784341ms... [INFO] [stdout] Apr 16 02:40:32.680 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 500.891353ms... [INFO] [stdout] Apr 16 02:40:33.102  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: lots of echo and pingpong [INFO] [stdout] Apr 16 02:40:33.183 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 732.368538ms... [INFO] [stdout] Apr 16 02:40:33.210 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 695.459596ms... [INFO] [stdout] Apr 16 02:40:33.241  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: shutdown the server and the client [INFO] [stdout] Apr 16 02:40:33.242  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:33.242  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:33.242  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 16 02:40:33.242  INFO rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:33.242  INFO connection{addr=127.0.0.1:59102}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 16 02:40:33.242  INFO connection{addr=127.0.0.1:59102}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:33.243  INFO connection{addr=127.0.0.1:59120}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 16 02:40:33.243  INFO connection{addr=127.0.0.1:59120}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:33.243  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:33.243  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:33.243  INFO connection{addr=127.0.0.1:59102}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:33.244  INFO connection{addr=127.0.0.1:59120}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:33.244  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: start the client [INFO] [stdout] Apr 16 02:40:33.244  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:33.245  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:33.245  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:33.245  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:33.245  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:33.245  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:33.246  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:33.246  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:33.246  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:33.246  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:33.246  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:33.247  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:33.247  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:33.247  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:33.247  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:33.247  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:33.251  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:33.252  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 16 02:40:33.252  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 16 02:40:33.253 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 473.17306ms... [INFO] [stdout] Apr 16 02:40:33.253 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 489.890952ms... [INFO] [stdout] Apr 16 02:40:33.676  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: start the server [INFO] [stdout] Apr 16 02:40:33.679  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:33.679  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Apr 16 02:40:33.728 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 790.933963ms... [INFO] [stdout] Apr 16 02:40:33.745 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 692.576734ms... [INFO] [stdout] Apr 16 02:40:33.917  INFO connection{addr=127.0.0.1:60724}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:33.922  INFO connection{addr=127.0.0.1:60724}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 16 02:40:33.926  INFO connection{addr=127.0.0.1:60724}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 16 02:40:33.927  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:33.929  INFO connection{addr=127.0.0.1:60726}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:33.945  INFO connection{addr=127.0.0.1:60726}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 16 02:40:33.950  INFO connection{addr=127.0.0.1:60726}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 16 02:40:33.951  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:34.246  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: start the server [INFO] [stdout] Apr 16 02:40:34.248  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:34.248  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Apr 16 02:40:34.449  INFO connection{addr=127.0.0.1:59154}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:34.450  INFO connection{addr=127.0.0.1:59154}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 16 02:40:34.452  INFO connection{addr=127.0.0.1:59154}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 16 02:40:34.452  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:34.535  INFO connection{addr=127.0.0.1:59174}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:34.536  INFO connection{addr=127.0.0.1:59174}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 16 02:40:34.538  INFO connection{addr=127.0.0.1:59174}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 16 02:40:34.538  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:36.178  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Apr 16 02:40:36.230  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Apr 16 02:40:36.278  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: shutdown the client [INFO] [stdout] Apr 16 02:40:36.278  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:36.279  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:36.279  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:36.279  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: restart the client [INFO] [stdout] Apr 16 02:40:36.281  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:36.282  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 16 02:40:36.283  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 16 02:40:36.299  INFO connection{addr=127.0.0.1:60738}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:36.301  INFO connection{addr=127.0.0.1:60742}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:36.302  WARN connection{addr=127.0.0.1:60738}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Apr 16 02:40:36.302  INFO connection{addr=127.0.0.1:60738}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 16 02:40:36.302  INFO connection{addr=127.0.0.1:60724}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:36.303  INFO connection{addr=127.0.0.1:60738}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 16 02:40:36.303  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:36.304  WARN connection{addr=127.0.0.1:60742}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Apr 16 02:40:36.304  INFO connection{addr=127.0.0.1:60742}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 16 02:40:36.304  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 16 02:40:36.304  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:36.305  INFO connection{addr=127.0.0.1:60726}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:36.305  INFO connection{addr=127.0.0.1:60742}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 16 02:40:36.306  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:36.313  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 16 02:40:36.313  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:36.748  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Apr 16 02:40:36.950  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Apr 16 02:40:37.022  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: shutdown the client [INFO] [stdout] Apr 16 02:40:37.022  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:37.022  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:37.022  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:37.023  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: restart the client [INFO] [stdout] Apr 16 02:40:37.025  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:37.026  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d [INFO] [stdout] Apr 16 02:40:37.026  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b [INFO] [stdout] Apr 16 02:40:37.043  INFO connection{addr=127.0.0.1:59196}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:37.045  INFO connection{addr=127.0.0.1:59198}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:37.046  WARN connection{addr=127.0.0.1:59196}: rathole::server: Dropping previous control channel for service echo [INFO] [stdout] Apr 16 02:40:37.046  INFO connection{addr=127.0.0.1:59196}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 16 02:40:37.047  INFO connection{addr=127.0.0.1:59174}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 16 02:40:37.047  INFO connection{addr=127.0.0.1:59174}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:37.048  INFO connection{addr=127.0.0.1:59174}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:37.048  INFO connection{addr=127.0.0.1:59196}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 16 02:40:37.049  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:37.050  WARN connection{addr=127.0.0.1:59198}: rathole::server: Dropping previous control channel for service pingpong [INFO] [stdout] Apr 16 02:40:37.050  INFO connection{addr=127.0.0.1:59198}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 16 02:40:37.051  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:37.051  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:37.051  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:37.051  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:37.051  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:37.051  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:37.051  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:37.052  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:37.052  INFO connection{addr=127.0.0.1:59154}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 16 02:40:37.052  INFO connection{addr=127.0.0.1:59154}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:37.054  INFO connection{addr=127.0.0.1:59154}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:37.054  INFO connection{addr=127.0.0.1:59198}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 16 02:40:37.055  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:37.080  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:37.080  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:37.081  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:37.081  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:37.081  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:37.081  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:37.081  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:37.082  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:37.281  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: echo [INFO] [stdout] Apr 16 02:40:37.329  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: pingpong [INFO] [stdout] Apr 16 02:40:37.381  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: shutdown the server [INFO] [stdout] Apr 16 02:40:37.382  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:37.382  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 16 02:40:37.382  INFO rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:37.382  INFO connection{addr=127.0.0.1:60738}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:37.382  INFO connection{addr=127.0.0.1:60742}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:37.383  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: restart the server [INFO] [stdout] Apr 16 02:40:37.383 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 527.787886ms... [INFO] [stdout] Apr 16 02:40:37.383  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 16 02:40:37.383  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:37.384 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 527.435579ms... [INFO] [stdout] Apr 16 02:40:37.384  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file [INFO] [stdout] Apr 16 02:40:37.384  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:37.386  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:37.386  INFO rathole::server: Listening at 0.0.0.0:2332 [INFO] [stdout] Apr 16 02:40:37.929  INFO connection{addr=127.0.0.1:60752}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:37.934  INFO connection{addr=127.0.0.1:60754}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:37.935  INFO connection{addr=127.0.0.1:60752}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 16 02:40:37.935  INFO connection{addr=127.0.0.1:60752}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 16 02:40:37.936  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:37.940  INFO connection{addr=127.0.0.1:60754}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 16 02:40:37.941  INFO connection{addr=127.0.0.1:60754}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 16 02:40:37.941  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:38.024  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: echo [INFO] [stdout] Apr 16 02:40:38.235  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: pingpong [INFO] [stdout] Apr 16 02:40:38.345  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: shutdown the server [INFO] [stdout] Apr 16 02:40:38.345  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:38.345  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 16 02:40:38.345  INFO rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:38.345  INFO connection{addr=127.0.0.1:59198}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 16 02:40:38.346  INFO connection{addr=127.0.0.1:59198}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:38.346  INFO connection{addr=127.0.0.1:59196}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 16 02:40:38.346  INFO connection{addr=127.0.0.1:59196}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:38.346  INFO connection{addr=127.0.0.1:59198}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:38.346  INFO connection{addr=127.0.0.1:59196}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:38.347  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: restart the server [INFO] [stdout] Apr 16 02:40:38.347 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 511.602853ms... [INFO] [stdout] Apr 16 02:40:38.347 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 546.139186ms... [INFO] [stdout] Apr 16 02:40:38.347  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:38.347  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:38.347  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:38.348  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:38.348  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:38.348  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:38.348  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:38.348  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:38.348  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:38.349  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:38.349  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:38.349  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:38.349  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:38.349  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:38.349  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:38.350  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof [INFO] [stdout] Apr 16 02:40:38.351  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config [INFO] [stdout] Apr 16 02:40:38.352  INFO rathole::server: Listening at 0.0.0.0:2333 [INFO] [stdout] Apr 16 02:40:38.867  INFO connection{addr=127.0.0.1:59238}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:38.868  INFO connection{addr=127.0.0.1:59238}: rathole::server: Control channel established service=pingpong [INFO] [stdout] Apr 16 02:40:38.869  INFO connection{addr=127.0.0.1:59238}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335 [INFO] [stdout] Apr 16 02:40:38.869  INFO handle{service=pingpong}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:38.935  INFO connection{addr=127.0.0.1:59256}: rathole::server: Try to handshake a control channel [INFO] [stdout] Apr 16 02:40:38.936  INFO connection{addr=127.0.0.1:59256}: rathole::server: Control channel established service=echo [INFO] [stdout] Apr 16 02:40:38.937  INFO connection{addr=127.0.0.1:59256}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334 [INFO] [stdout] Apr 16 02:40:38.937  INFO handle{service=echo}:run: rathole::client: Control channel established [INFO] [stdout] Apr 16 02:40:39.883  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: lots of echo and pingpong [INFO] [stdout] Apr 16 02:40:39.986  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: shutdown the server and the client [INFO] [stdout] Apr 16 02:40:39.986  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:39.986  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:39.986  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 16 02:40:39.986  INFO rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:39.987  INFO connection{addr=127.0.0.1:60752}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:39.987  INFO connection{addr=127.0.0.1:60754}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:39.987  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:39.987  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] test udp ... ok [INFO] [stdout] Apr 16 02:40:40.848  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: lots of echo and pingpong [INFO] [stdout] Apr 16 02:40:41.350  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: shutdown the server and the client [INFO] [stdout] Apr 16 02:40:41.350  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:41.350  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting [INFO] [stdout] Apr 16 02:40:41.350  INFO rathole::server: Shuting down gracefully... [INFO] [stdout] Apr 16 02:40:41.350  INFO rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:41.350  INFO connection{addr=127.0.0.1:59238}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 16 02:40:41.351  INFO connection{addr=127.0.0.1:59238}:handle{service=pingpong}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:41.351  INFO connection{addr=127.0.0.1:59256}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown [INFO] [stdout] Apr 16 02:40:41.351  INFO connection{addr=127.0.0.1:59256}:handle{service=echo}:run: rathole::server: Control channel shutdown [INFO] [stdout] Apr 16 02:40:41.351  INFO handle{service=echo}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:41.351  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown [INFO] [stdout] Apr 16 02:40:41.351  INFO connection{addr=127.0.0.1:59238}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] Apr 16 02:40:41.352  INFO connection{addr=127.0.0.1:59256}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown [INFO] [stdout] test tcp ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 22.78s [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" "3a5dbc5ae64dc91f428920a047f85b9bf39969a41d7188a47819e20906e59465", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3a5dbc5ae64dc91f428920a047f85b9bf39969a41d7188a47819e20906e59465", kill_on_drop: false }` [INFO] [stdout] 3a5dbc5ae64dc91f428920a047f85b9bf39969a41d7188a47819e20906e59465