[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 1.60.0 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 1.60.0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.60.0" "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" "+1.60.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded tonic-build v0.6.2
[INFO] [stderr]   Downloaded vergen v6.0.2
[INFO] [stderr]   Downloaded git2 v0.14.1
[INFO] [stderr]   Downloaded backoff v0.4.0
[INFO] [stderr]   Downloaded console-api v0.1.2
[INFO] [stderr]   Downloaded async-http-proxy v1.2.5
[INFO] [stderr]   Downloaded snowstorm v0.3.1
[INFO] [stderr]   Downloaded console-subscriber v0.1.3
[INFO] [stderr]   Downloaded async-socks5 v0.5.1
[INFO] [stderr]   Downloaded tonic v0.6.2
[INFO] [stderr]   Downloaded libgit2-sys v0.13.1+1.4.2
[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" "+1.60.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 4307a6952e41babb47fb63c9d22cbe8c70e2bb3ce2b4658ee4adffa9904d4d97
[INFO] running `Command { std: "docker" "start" "-a" "4307a6952e41babb47fb63c9d22cbe8c70e2bb3ce2b4658ee4adffa9904d4d97", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "4307a6952e41babb47fb63c9d22cbe8c70e2bb3ce2b4658ee4adffa9904d4d97", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4307a6952e41babb47fb63c9d22cbe8c70e2bb3ce2b4658ee4adffa9904d4d97", kill_on_drop: false }`
[INFO] [stdout] 4307a6952e41babb47fb63c9d22cbe8c70e2bb3ce2b4658ee4adffa9904d4d97
[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" "+1.60.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b1bc99d3da0280a99d4c007ccf5bca0c20ea1acf6027153bbeb629cce2dd34a5
[INFO] running `Command { std: "docker" "start" "-a" "b1bc99d3da0280a99d4c007ccf5bca0c20ea1acf6027153bbeb629cce2dd34a5", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[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 once_cell v1.10.0
[INFO] [stderr]    Compiling lazy_static v1.4.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 ucd-trie v0.1.3
[INFO] [stderr]    Compiling scopeguard v1.1.0
[INFO] [stderr]    Compiling rustversion v1.0.6
[INFO] [stderr]    Compiling matches v0.1.9
[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 byteorder v1.4.3
[INFO] [stderr]    Compiling regex-syntax v0.6.25
[INFO] [stderr]    Compiling futures-task v0.3.21
[INFO] [stderr]    Compiling foreign-types-shared v0.1.1
[INFO] [stderr]    Compiling openssl v0.10.38
[INFO] [stderr]    Compiling crossbeam-utils v0.8.7
[INFO] [stderr]    Compiling tinyvec_macros v0.1.0
[INFO] [stderr]    Compiling httparse v1.6.0
[INFO] [stderr]    Compiling futures-util v0.3.21
[INFO] [stderr]    Compiling native-tls v0.2.8
[INFO] [stderr]    Compiling serde_json v1.0.79
[INFO] [stderr]    Compiling itoa v1.0.1
[INFO] [stderr]    Compiling percent-encoding v2.1.0
[INFO] [stderr]    Compiling heck v0.4.0
[INFO] [stderr]    Compiling same-file v1.0.6
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling slab v0.4.5
[INFO] [stderr]    Compiling hashbrown v0.11.2
[INFO] [stderr]    Compiling openssl-probe v0.1.5
[INFO] [stderr]    Compiling ryu v1.0.9
[INFO] [stderr]    Compiling strsim v0.10.0
[INFO] [stderr]    Compiling textwrap v0.15.0
[INFO] [stderr]    Compiling termcolor v1.1.3
[INFO] [stderr]    Compiling base64 v0.13.0
[INFO] [stderr]    Compiling ansi_term v0.12.1
[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 lock_api v0.4.6
[INFO] [stderr]    Compiling tinyvec v1.5.1
[INFO] [stderr]    Compiling tracing-core v0.1.23
[INFO] [stderr]    Compiling sharded-slab v0.1.4
[INFO] [stderr]    Compiling log v0.4.14
[INFO] [stderr]    Compiling instant v0.1.12
[INFO] [stderr]    Compiling thread_local v1.1.4
[INFO] [stderr]    Compiling foreign-types v0.3.2
[INFO] [stderr]    Compiling walkdir v2.3.2
[INFO] [stderr]    Compiling pest v2.1.3
[INFO] [stderr]    Compiling idna v0.2.3
[INFO] [stderr]    Compiling indexmap v1.8.0
[INFO] [stderr]    Compiling os_str_bytes v6.0.0
[INFO] [stderr]    Compiling tracing-log v0.1.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 crossbeam-channel v0.5.2
[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 signal-hook-registry v1.4.0
[INFO] [stderr]    Compiling mio v0.8.0
[INFO] [stderr]    Compiling num_cpus v1.13.1
[INFO] [stderr]    Compiling socket2 v0.4.4
[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 mio v0.7.14
[INFO] [stderr]    Compiling filetime v0.2.15
[INFO] [stderr]    Compiling fdlimit v0.2.1
[INFO] [stderr]    Compiling semver v0.11.0
[INFO] [stderr]    Compiling universal-hash v0.4.1
[INFO] [stderr]    Compiling cipher v0.3.0
[INFO] [stderr]    Compiling digest v0.9.0
[INFO] [stderr]    Compiling aead v0.4.3
[INFO] [stderr]    Compiling crypto-mac v0.8.0
[INFO] [stderr]    Compiling block-buffer v0.9.0
[INFO] [stderr]    Compiling block-buffer v0.10.2
[INFO] [stderr]    Compiling crypto-common v0.1.3
[INFO] [stderr]    Compiling inotify v0.9.6
[INFO] [stderr]    Compiling rand_core v0.6.3
[INFO] [stderr]    Compiling cc v1.0.73
[INFO] [stderr]    Compiling parking_lot v0.12.0
[INFO] [stderr]    Compiling rustc_version v0.3.3
[INFO] [stderr]    Compiling polyval v0.5.3
[INFO] [stderr]    Compiling poly1305 v0.7.2
[INFO] [stderr]    Compiling blake2 v0.9.2
[INFO] [stderr]    Compiling digest v0.10.3
[INFO] [stderr]    Compiling ctr v0.8.0
[INFO] [stderr]    Compiling aes v0.7.5
[INFO] [stderr]    Compiling regex-automata v0.1.10
[INFO] [stderr]    Compiling regex v1.5.5
[INFO] [stderr]    Compiling rand_core v0.5.1
[INFO] [stderr]    Compiling ghash v0.4.4
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling notify v5.0.0-pre.13
[INFO] [stderr]    Compiling sha2 v0.9.9
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling sha2 v0.10.2
[INFO] [stderr]    Compiling snow v0.8.0
[INFO] [stderr]    Compiling aes-gcm v0.9.4
[INFO] [stderr]    Compiling rand v0.8.5
[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 synstructure v0.12.6
[INFO] [stderr]    Compiling proc-macro-error v1.0.4
[INFO] [stderr]    Compiling git2 v0.14.1
[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 futures-macro v0.3.21
[INFO] [stderr]    Compiling tracing-attributes v0.1.20
[INFO] [stderr]    Compiling clap_derive v3.1.4
[INFO] [stderr]    Compiling async-trait v0.1.52
[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 pin-project v1.0.10
[INFO] [stderr]    Compiling chacha20poly1305 v0.8.2
[INFO] [stderr]    Compiling thiserror v1.0.30
[INFO] [stderr]    Compiling tracing v0.1.31
[INFO] [stderr]    Compiling x25519-dalek v1.2.0
[INFO] [stderr]    Compiling clap v3.1.6
[INFO] [stderr]    Compiling rathole v0.4.1 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling bytes v1.1.0
[INFO] [stderr]    Compiling tracing-serde v0.1.3
[INFO] [stderr]    Compiling 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 backoff v0.4.0
[INFO] [stderr]    Compiling tokio-native-tls v0.3.0
[INFO] [stderr]    Compiling async-http-proxy v1.2.5
[INFO] [stderr]    Compiling snowstorm v0.3.1
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 38.29s
[INFO] running `Command { std: "docker" "inspect" "b1bc99d3da0280a99d4c007ccf5bca0c20ea1acf6027153bbeb629cce2dd34a5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b1bc99d3da0280a99d4c007ccf5bca0c20ea1acf6027153bbeb629cce2dd34a5", kill_on_drop: false }`
[INFO] [stdout] b1bc99d3da0280a99d4c007ccf5bca0c20ea1acf6027153bbeb629cce2dd34a5
[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" "+1.60.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] e4a3c8e7cde5bcedf4dd2d67ce5bcb69eb904263d25398161d9bf99aafd6d700
[INFO] running `Command { std: "docker" "start" "-a" "e4a3c8e7cde5bcedf4dd2d67ce5bcb69eb904263d25398161d9bf99aafd6d700", kill_on_drop: false }`
[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 strsim v0.10.0
[INFO] [stderr]    Compiling termcolor v1.1.3
[INFO] [stderr]    Compiling textwrap v0.15.0
[INFO] [stderr]    Compiling tracing-core v0.1.23
[INFO] [stderr]    Compiling regex-automata v0.1.10
[INFO] [stderr]    Compiling regex v1.5.5
[INFO] [stderr]    Compiling sharded-slab v0.1.4
[INFO] [stderr]    Compiling futures-core v0.3.21
[INFO] [stderr]    Compiling walkdir v2.3.2
[INFO] [stderr]    Compiling signal-hook-registry v1.4.0
[INFO] [stderr]    Compiling socket2 v0.4.4
[INFO] [stderr]    Compiling mio v0.8.0
[INFO] [stderr]    Compiling num_cpus v1.13.1
[INFO] [stderr]    Compiling getrandom v0.2.5
[INFO] [stderr]    Compiling getrandom v0.1.16
[INFO] [stderr]    Compiling inotify-sys v0.1.5
[INFO] [stderr]    Compiling crossbeam-utils v0.8.7
[INFO] [stderr]    Compiling futures-task v0.3.21
[INFO] [stderr]    Compiling filetime v0.2.15
[INFO] [stderr]    Compiling os_str_bytes v6.0.0
[INFO] [stderr]    Compiling httparse v1.6.0
[INFO] [stderr]    Compiling mio v0.7.14
[INFO] [stderr]    Compiling atty v0.2.14
[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 thread_local v1.1.4
[INFO] [stderr]    Compiling universal-hash v0.4.1
[INFO] [stderr]    Compiling crypto-mac v0.8.0
[INFO] [stderr]    Compiling rand_core v0.6.3
[INFO] [stderr]    Compiling inotify v0.9.6
[INFO] [stderr]    Compiling futures-util v0.3.21
[INFO] [stderr]    Compiling ctr v0.8.0
[INFO] [stderr]    Compiling aes v0.7.5
[INFO] [stderr]    Compiling parking_lot_core v0.9.1
[INFO] [stderr]    Compiling chacha20 v0.7.3
[INFO] [stderr]    Compiling polyval v0.5.3
[INFO] [stderr]    Compiling poly1305 v0.7.2
[INFO] [stderr]    Compiling clap v3.1.6
[INFO] [stderr]    Compiling digest v0.10.3
[INFO] [stderr]    Compiling rand_core v0.5.1
[INFO] [stderr]    Compiling tracing-log v0.1.2
[INFO] [stderr]    Compiling tracing v0.1.31
[INFO] [stderr]    Compiling sha2 v0.9.9
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling crossbeam-channel v0.5.2
[INFO] [stderr]    Compiling blake2 v0.9.2
[INFO] [stderr]    Compiling parking_lot v0.12.0
[INFO] [stderr]    Compiling matchers v0.0.1
[INFO] [stderr]    Compiling openssl v0.10.38
[INFO] [stderr]    Compiling sha2 v0.10.2
[INFO] [stderr]    Compiling curve25519-dalek v3.2.1
[INFO] [stderr]    Compiling ghash v0.4.4
[INFO] [stderr]    Compiling chacha20poly1305 v0.8.2
[INFO] [stderr]    Compiling notify v5.0.0-pre.13
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling aes-gcm v0.9.4
[INFO] [stderr]    Compiling x25519-dalek v1.2.0
[INFO] [stderr]    Compiling snow v0.8.0
[INFO] [stderr]    Compiling bytes v1.1.0
[INFO] [stderr]    Compiling serde_json v1.0.79
[INFO] [stderr]    Compiling tracing-serde v0.1.3
[INFO] [stderr]    Compiling url v2.2.2
[INFO] [stderr]    Compiling toml v0.5.8
[INFO] [stderr]    Compiling bincode v1.3.3
[INFO] [stderr]    Compiling native-tls v0.2.8
[INFO] [stderr]    Compiling tokio v1.17.0
[INFO] [stderr]    Compiling tracing-subscriber v0.2.25
[INFO] [stderr]    Compiling tokio-native-tls v0.3.0
[INFO] [stderr]    Compiling async-http-proxy v1.2.5
[INFO] [stderr]    Compiling snowstorm v0.3.1
[INFO] [stderr]    Compiling backoff v0.4.0
[INFO] [stderr]    Compiling async-socks5 v0.5.1
[INFO] [stderr]    Compiling rathole v0.4.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 25.57s
[INFO] running `Command { std: "docker" "inspect" "e4a3c8e7cde5bcedf4dd2d67ce5bcb69eb904263d25398161d9bf99aafd6d700", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e4a3c8e7cde5bcedf4dd2d67ce5bcb69eb904263d25398161d9bf99aafd6d700", kill_on_drop: false }`
[INFO] [stdout] e4a3c8e7cde5bcedf4dd2d67ce5bcb69eb904263d25398161d9bf99aafd6d700
[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" "+1.60.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 78fca9ecfdb15f2ec95667db86969036d0f9245cef201981a2c3876457b7f83b
[INFO] running `Command { std: "docker" "start" "-a" "78fca9ecfdb15f2ec95667db86969036d0f9245cef201981a2c3876457b7f83b", 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]     Finished test [unoptimized + debuginfo] target(s) in 0.29s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/rathole-4dd7c92bd7aec03b)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test config::tests::test_validate_client_config ... ok
[INFO] [stdout] test config::tests::test_example_config ... ok
[INFO] [stdout] test config::tests::test_validate_server_config ... ok
[INFO] [stdout] test tests::test_determine_run_mode ... ok
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/rathole-30f2fcb230216338)
[INFO] [stderr]      Running tests/integration_test.rs (/opt/rustwide/target/debug/deps/integration_test-e368145ed9e32a90)
[INFO] [stdout] test config_watcher::test::test_calculate_events ... ok
[INFO] [stdout] test config::tests::test_invalid_config ... ok
[INFO] [stdout] test config::tests::test_valid_config ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] Apr 16 02:37:51.255  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: start the client
[INFO] [stdout] Apr 16 02:37:51.256  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: start the client
[INFO] [stdout] Apr 16 02:37:51.258  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:37:51.259  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:37:51.259  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d
[INFO] [stdout] Apr 16 02:37:51.259  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b
[INFO] [stdout] Apr 16 02:37:51.259  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b
[INFO] [stdout] Apr 16 02:37:51.259  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d
[INFO] [stdout] Apr 16 02:37:51.260 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 460.10789ms...
[INFO] [stdout] Apr 16 02:37:51.260 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 477.541261ms...
[INFO] [stdout] Apr 16 02:37:51.260 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 495.91267ms...
[INFO] [stdout] Apr 16 02:37:51.260 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 531.49919ms...
[INFO] [stdout] Apr 16 02:37:51.721 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 680.265747ms...
[INFO] [stdout] Apr 16 02:37:51.738 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 810.607399ms...
[INFO] [stdout] Apr 16 02:37:51.757 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 724.913626ms...
[INFO] [stdout] Apr 16 02:37:51.793 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 742.876598ms...
[INFO] [stdout] Apr 16 02:37:52.256  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: start the server
[INFO] [stdout] Apr 16 02:37:52.257  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: start the server
[INFO] [stdout] Apr 16 02:37:52.258  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:37:52.259  INFO rathole::server: Listening at 0.0.0.0:2333
[INFO] [stdout] Apr 16 02:37:52.259  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:37:52.260  INFO rathole::server: Listening at 0.0.0.0:2332
[INFO] [stdout] Apr 16 02:37:52.404  INFO connection{addr=127.0.0.1:58456}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:37:52.404  INFO connection{addr=127.0.0.1:58456}: rathole::server: Control channel established service=echo
[INFO] [stdout] Apr 16 02:37:52.405  INFO connection{addr=127.0.0.1:58456}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334
[INFO] [stdout] Apr 16 02:37:52.405  INFO handle{service=echo}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:37:52.485  INFO connection{addr=127.0.0.1:58476}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:37:52.485  INFO connection{addr=127.0.0.1:58476}: rathole::server: Control channel established service=pingpong
[INFO] [stdout] Apr 16 02:37:52.486  INFO connection{addr=127.0.0.1:58476}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335
[INFO] [stdout] Apr 16 02:37:52.486  INFO handle{service=pingpong}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:37:52.538  INFO connection{addr=127.0.0.1:60492}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:37:52.539  INFO connection{addr=127.0.0.1:60492}: rathole::server: Control channel established service=echo
[INFO] [stdout] Apr 16 02:37:52.539  INFO connection{addr=127.0.0.1:60492}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334
[INFO] [stdout] Apr 16 02:37:52.539  INFO handle{service=echo}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:37:52.552  INFO connection{addr=127.0.0.1:60498}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:37:52.552  INFO connection{addr=127.0.0.1:60498}: rathole::server: Control channel established service=pingpong
[INFO] [stdout] Apr 16 02:37:52.552  INFO connection{addr=127.0.0.1:60498}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335
[INFO] [stdout] Apr 16 02:37:52.553  INFO handle{service=pingpong}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:37:54.758  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: echo
[INFO] [stdout] Apr 16 02:37:54.759  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: echo
[INFO] [stdout] Apr 16 02:37:54.761  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: pingpong
[INFO] [stdout] Apr 16 02:37:54.763  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: shutdown the client
[INFO] [stdout] Apr 16 02:37:54.764  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:37:54.764  INFO handle{service=echo}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:54.764  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:54.764  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: restart the client
[INFO] [stdout] Apr 16 02:37:54.766  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:37:54.766  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b
[INFO] [stdout] Apr 16 02:37:54.766  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d
[INFO] [stdout] Apr 16 02:37:54.767  INFO connection{addr=127.0.0.1:60506}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:37:54.767  INFO connection{addr=127.0.0.1:60508}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:37:54.768  WARN connection{addr=127.0.0.1:60506}: rathole::server: Dropping previous control channel for service pingpong
[INFO] [stdout] Apr 16 02:37:54.768  INFO connection{addr=127.0.0.1:60506}: rathole::server: Control channel established service=pingpong
[INFO] [stdout] Apr 16 02:37:54.768  WARN connection{addr=127.0.0.1:60508}: rathole::server: Dropping previous control channel for service echo
[INFO] [stdout] Apr 16 02:37:54.768  INFO connection{addr=127.0.0.1:60508}: rathole::server: Control channel established service=echo
[INFO] [stdout] Apr 16 02:37:54.768  INFO connection{addr=127.0.0.1:60498}:handle{service=pingpong}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:54.769  INFO connection{addr=127.0.0.1:60506}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335
[INFO] [stdout] Apr 16 02:37:54.769  INFO connection{addr=127.0.0.1:60492}:handle{service=echo}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:54.769  INFO connection{addr=127.0.0.1:60508}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334
[INFO] [stdout] Apr 16 02:37:54.770  INFO handle{service=pingpong}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:37:54.770  INFO handle{service=echo}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:37:54.770  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file
[INFO] [stdout] Apr 16 02:37:54.770  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:54.770  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file
[INFO] [stdout] Apr 16 02:37:54.770  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:54.791  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: pingpong
[INFO] [stdout] Apr 16 02:37:54.808  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: shutdown the client
[INFO] [stdout] Apr 16 02:37:54.808  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:37:54.808  INFO handle{service=echo}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:54.808  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:54.808  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: restart the client
[INFO] [stdout] Apr 16 02:37:54.810  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:37:54.810  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b
[INFO] [stdout] Apr 16 02:37:54.810  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d
[INFO] [stdout] Apr 16 02:37:54.811  INFO connection{addr=127.0.0.1:58498}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:37:54.811  INFO connection{addr=127.0.0.1:58500}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:37:54.811  WARN connection{addr=127.0.0.1:58498}: rathole::server: Dropping previous control channel for service pingpong
[INFO] [stdout] Apr 16 02:37:54.811  INFO connection{addr=127.0.0.1:58498}: rathole::server: Control channel established service=pingpong
[INFO] [stdout] Apr 16 02:37:54.812  WARN connection{addr=127.0.0.1:58500}: rathole::server: Dropping previous control channel for service echo
[INFO] [stdout] Apr 16 02:37:54.812  INFO connection{addr=127.0.0.1:58500}: rathole::server: Control channel established service=echo
[INFO] [stdout] Apr 16 02:37:54.812  INFO connection{addr=127.0.0.1:58476}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown
[INFO] [stdout] Apr 16 02:37:54.812  INFO connection{addr=127.0.0.1:58476}:handle{service=pingpong}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:54.812  INFO connection{addr=127.0.0.1:58456}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown
[INFO] [stdout] Apr 16 02:37:54.813  INFO connection{addr=127.0.0.1:58456}:handle{service=echo}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:54.813  INFO connection{addr=127.0.0.1:58476}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:37:54.813  INFO connection{addr=127.0.0.1:58498}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335
[INFO] [stdout] Apr 16 02:37:54.813  INFO connection{addr=127.0.0.1:58456}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:37:54.814  INFO connection{addr=127.0.0.1:58500}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334
[INFO] [stdout] Apr 16 02:37:54.814  INFO handle{service=pingpong}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:37:54.814  INFO handle{service=echo}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:37:54.814  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:54.814  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:54.815  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:54.815  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:54.815  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:54.815  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:54.815  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:54.815  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:54.815  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:54.816  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:54.816  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:54.816  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:54.816  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:54.816  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:54.816  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:54.817  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.766  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: echo
[INFO] [stdout] Apr 16 02:37:55.768  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: pingpong
[INFO] [stdout] Apr 16 02:37:55.771  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: shutdown the server
[INFO] [stdout] Apr 16 02:37:55.771  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:37:55.771  INFO rathole::server: Shuting down gracefully...
[INFO] [stdout] Apr 16 02:37:55.771  INFO rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:37:55.772  INFO connection{addr=127.0.0.1:60506}:handle{service=pingpong}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:55.772  INFO connection{addr=127.0.0.1:60508}:handle{service=echo}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:55.772  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: restart the server
[INFO] [stdout] Apr 16 02:37:55.773 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 549.598581ms...
[INFO] [stdout] Apr 16 02:37:55.773  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file
[INFO] [stdout] Apr 16 02:37:55.773  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.773 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 455.363206ms...
[INFO] [stdout] Apr 16 02:37:55.773  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file
[INFO] [stdout] Apr 16 02:37:55.774  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.775  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:37:55.775  INFO rathole::server: Listening at 0.0.0.0:2332
[INFO] [stdout] Apr 16 02:37:55.810  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: echo
[INFO] [stdout] Apr 16 02:37:55.839  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: pingpong
[INFO] [stdout] Apr 16 02:37:55.856  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: shutdown the server
[INFO] [stdout] Apr 16 02:37:55.857  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:37:55.857  INFO rathole::server: Shuting down gracefully...
[INFO] [stdout] Apr 16 02:37:55.857  INFO rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:37:55.857  INFO connection{addr=127.0.0.1:58500}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown
[INFO] [stdout] Apr 16 02:37:55.857  INFO connection{addr=127.0.0.1:58500}:handle{service=echo}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:55.857  INFO connection{addr=127.0.0.1:58498}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown
[INFO] [stdout] Apr 16 02:37:55.857  INFO connection{addr=127.0.0.1:58498}:handle{service=pingpong}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:55.857  INFO connection{addr=127.0.0.1:58500}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:37:55.858  INFO connection{addr=127.0.0.1:58498}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:37:55.858  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: restart the server
[INFO] [stdout] Apr 16 02:37:55.858 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 452.259611ms...
[INFO] [stdout] Apr 16 02:37:55.858 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 514.871566ms...
[INFO] [stdout] Apr 16 02:37:55.858  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.858  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.859  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.859  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.859  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.859  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.859  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.859  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.859  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.859  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.859  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.860  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.860  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.860  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.860  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.860  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:55.861  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:37:55.861  INFO rathole::server: Listening at 0.0.0.0:2333
[INFO] [stdout] Apr 16 02:37:56.230  INFO connection{addr=127.0.0.1:60518}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:37:56.230  INFO connection{addr=127.0.0.1:60518}: rathole::server: Control channel established service=echo
[INFO] [stdout] Apr 16 02:37:56.231  INFO connection{addr=127.0.0.1:60518}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334
[INFO] [stdout] Apr 16 02:37:56.231  INFO handle{service=echo}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:37:56.313  INFO connection{addr=127.0.0.1:58538}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:37:56.314  INFO connection{addr=127.0.0.1:58538}: rathole::server: Control channel established service=echo
[INFO] [stdout] Apr 16 02:37:56.314  INFO connection{addr=127.0.0.1:58538}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334
[INFO] [stdout] Apr 16 02:37:56.314  INFO handle{service=echo}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:37:56.324  INFO connection{addr=127.0.0.1:60524}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:37:56.325  INFO connection{addr=127.0.0.1:60524}: rathole::server: Control channel established service=pingpong
[INFO] [stdout] Apr 16 02:37:56.325  INFO connection{addr=127.0.0.1:60524}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335
[INFO] [stdout] Apr 16 02:37:56.325  INFO handle{service=pingpong}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:37:56.376  INFO connection{addr=127.0.0.1:58560}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:37:56.377  INFO connection{addr=127.0.0.1:58560}: rathole::server: Control channel established service=pingpong
[INFO] [stdout] Apr 16 02:37:56.377  INFO connection{addr=127.0.0.1:58560}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335
[INFO] [stdout] Apr 16 02:37:56.378  INFO handle{service=pingpong}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:37:58.274  INFO test{config_path="tests/for_udp/tcp_transport.toml" t=Udp}: integration_test: lots of echo and pingpong
[INFO] [stdout] Apr 16 02:37:58.280  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:37:58.280  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:37:58.281  INFO config_watcher{path="tests/for_udp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:37:58.281  INFO rathole::server: Shuting down gracefully...
[INFO] [stdout] Apr 16 02:37:58.281  INFO rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:37:58.281  INFO connection{addr=127.0.0.1:60518}:handle{service=echo}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:58.281  INFO connection{addr=127.0.0.1:60524}:handle{service=pingpong}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:58.282  INFO handle{service=echo}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:58.282  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:58.282  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: start the client
[INFO] [stdout] Apr 16 02:37:58.282  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file
[INFO] [stdout] Apr 16 02:37:58.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:37:58.283  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file
[INFO] [stdout] Apr 16 02:37:58.283  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:58.286  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:37:58.297  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b
[INFO] [stdout] Apr 16 02:37:58.298  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d
[INFO] [stdout] Apr 16 02:37:58.298 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to connect to 127.0.0.1:2332: Connection refused (os error 111). Retry in 504.708582ms...
[INFO] [stdout] Apr 16 02:37:58.298 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 539.387097ms...
[INFO] [stdout] Apr 16 02:37:58.359  INFO test{config_path="tests/for_tcp/tcp_transport.toml" t=Tcp}: integration_test: lots of echo and pingpong
[INFO] [stdout] Apr 16 02:37:58.431  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:37:58.431  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:37:58.431  INFO config_watcher{path="tests/for_tcp/tcp_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:37:58.431  INFO rathole::server: Shuting down gracefully...
[INFO] [stdout] Apr 16 02:37:58.431  INFO rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:37:58.432  INFO connection{addr=127.0.0.1:58538}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown
[INFO] [stdout] Apr 16 02:37:58.432  INFO connection{addr=127.0.0.1:58538}:handle{service=echo}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:58.432  INFO connection{addr=127.0.0.1:58560}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown
[INFO] [stdout] Apr 16 02:37:58.432  INFO connection{addr=127.0.0.1:58560}:handle{service=pingpong}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:58.432  INFO handle{service=echo}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:58.433  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:37:58.433  INFO connection{addr=127.0.0.1:58538}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:37:58.433  INFO connection{addr=127.0.0.1:58560}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:37:58.433  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: start the client
[INFO] [stdout] Apr 16 02:37:58.434  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:58.434  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:58.434  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:58.434  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:58.434  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:58.435  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:58.435  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:58.435  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:58.435  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:58.435  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:58.435  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:58.436  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:58.436  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:58.436  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:58.436  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:58.436  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:37:58.438  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:37:58.444  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d
[INFO] [stdout] Apr 16 02:37:58.444  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b
[INFO] [stdout] Apr 16 02:37:58.445 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 481.91475ms...
[INFO] [stdout] Apr 16 02:37:58.445 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 520.796907ms...
[INFO] [stdout] Apr 16 02:37:58.805 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 737.143312ms...
[INFO] [stdout] Apr 16 02:37:58.839 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 716.042477ms...
[INFO] [stdout] Apr 16 02:37:58.928 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 804.259116ms...
[INFO] [stdout] Apr 16 02:37:58.967 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 755.884442ms...
[INFO] [stdout] Apr 16 02:37:59.283  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: start the server
[INFO] [stdout] Apr 16 02:37:59.285  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:37:59.288  INFO rathole::server: Listening at 0.0.0.0:2332
[INFO] [stdout] Apr 16 02:37:59.435  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: start the server
[INFO] [stdout] Apr 16 02:37:59.437  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:37:59.439  INFO rathole::server: Listening at 0.0.0.0:2333
[INFO] [stdout] Apr 16 02:37:59.553  INFO connection{addr=127.0.0.1:60538}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:37:59.554  INFO connection{addr=127.0.0.1:60538}: rathole::server: Control channel established service=pingpong
[INFO] [stdout] Apr 16 02:37:59.554  INFO connection{addr=127.0.0.1:60538}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335
[INFO] [stdout] Apr 16 02:37:59.555  INFO handle{service=pingpong}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:37:59.572  INFO connection{addr=127.0.0.1:60546}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:37:59.572  INFO connection{addr=127.0.0.1:60546}: rathole::server: Control channel established service=echo
[INFO] [stdout] Apr 16 02:37:59.573  INFO connection{addr=127.0.0.1:60546}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334
[INFO] [stdout] Apr 16 02:37:59.573  INFO handle{service=echo}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:37:59.734  INFO connection{addr=127.0.0.1:58596}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:37:59.741  INFO connection{addr=127.0.0.1:58596}: rathole::server: Control channel established service=pingpong
[INFO] [stdout] Apr 16 02:37:59.742  INFO connection{addr=127.0.0.1:58596}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335
[INFO] [stdout] Apr 16 02:37:59.742  INFO handle{service=pingpong}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:37:59.745  INFO connection{addr=127.0.0.1:58600}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:37:59.752  INFO connection{addr=127.0.0.1:58600}: rathole::server: Control channel established service=echo
[INFO] [stdout] Apr 16 02:37:59.753  INFO connection{addr=127.0.0.1:58600}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334
[INFO] [stdout] Apr 16 02:37:59.753  INFO handle{service=echo}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:01.785  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: echo
[INFO] [stdout] Apr 16 02:38:01.787  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: pingpong
[INFO] [stdout] Apr 16 02:38:01.790  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: shutdown the client
[INFO] [stdout] Apr 16 02:38:01.790  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:38:01.790  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:01.790  INFO handle{service=echo}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:01.791  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: restart the client
[INFO] [stdout] Apr 16 02:38:01.793  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:38:01.799  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b
[INFO] [stdout] Apr 16 02:38:01.799  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d
[INFO] [stdout] Apr 16 02:38:01.810  INFO connection{addr=127.0.0.1:60552}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:01.811  WARN connection{addr=127.0.0.1:60552}: rathole::server: Dropping previous control channel for service pingpong
[INFO] [stdout] Apr 16 02:38:01.811  INFO connection{addr=127.0.0.1:60552}: rathole::server: Control channel established service=pingpong
[INFO] [stdout] Apr 16 02:38:01.811  INFO connection{addr=127.0.0.1:60538}:handle{service=pingpong}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:01.812  INFO connection{addr=127.0.0.1:60552}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335
[INFO] [stdout] Apr 16 02:38:01.812  INFO handle{service=pingpong}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:01.812  INFO connection{addr=127.0.0.1:60554}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:01.813  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file
[INFO] [stdout] Apr 16 02:38:01.813  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:01.814  WARN connection{addr=127.0.0.1:60554}: rathole::server: Dropping previous control channel for service echo
[INFO] [stdout] Apr 16 02:38:01.814  INFO connection{addr=127.0.0.1:60554}: rathole::server: Control channel established service=echo
[INFO] [stdout] Apr 16 02:38:01.819  INFO connection{addr=127.0.0.1:60546}:handle{service=echo}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:01.819  INFO connection{addr=127.0.0.1:60554}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334
[INFO] [stdout] Apr 16 02:38:01.819  INFO handle{service=echo}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:01.820  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file
[INFO] [stdout] Apr 16 02:38:01.820  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:01.936  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: echo
[INFO] [stdout] Apr 16 02:38:01.989  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: pingpong
[INFO] [stdout] Apr 16 02:38:02.027  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: shutdown the client
[INFO] [stdout] Apr 16 02:38:02.028  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:38:02.028  INFO handle{service=echo}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:02.028  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:02.028  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: restart the client
[INFO] [stdout] Apr 16 02:38:02.030  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:38:02.036  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b
[INFO] [stdout] Apr 16 02:38:02.037  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d
[INFO] [stdout] Apr 16 02:38:02.051  INFO connection{addr=127.0.0.1:58640}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:02.053  WARN connection{addr=127.0.0.1:58640}: rathole::server: Dropping previous control channel for service pingpong
[INFO] [stdout] Apr 16 02:38:02.053  INFO connection{addr=127.0.0.1:58640}: rathole::server: Control channel established service=pingpong
[INFO] [stdout] Apr 16 02:38:02.053  INFO connection{addr=127.0.0.1:58596}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown
[INFO] [stdout] Apr 16 02:38:02.053  INFO connection{addr=127.0.0.1:58596}:handle{service=pingpong}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:02.054  INFO connection{addr=127.0.0.1:58596}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:02.054  INFO connection{addr=127.0.0.1:58640}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335
[INFO] [stdout] Apr 16 02:38:02.054  INFO handle{service=pingpong}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:02.055  INFO connection{addr=127.0.0.1:58642}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:02.055  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:02.055  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:02.055  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:02.056  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:02.056  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:02.056  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:02.056  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:02.057  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:02.059  WARN connection{addr=127.0.0.1:58642}: rathole::server: Dropping previous control channel for service echo
[INFO] [stdout] Apr 16 02:38:02.059  INFO connection{addr=127.0.0.1:58642}: rathole::server: Control channel established service=echo
[INFO] [stdout] Apr 16 02:38:02.066  INFO connection{addr=127.0.0.1:58600}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown
[INFO] [stdout] Apr 16 02:38:02.066  INFO connection{addr=127.0.0.1:58600}:handle{service=echo}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:02.066  INFO connection{addr=127.0.0.1:58600}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:02.067  INFO connection{addr=127.0.0.1:58642}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334
[INFO] [stdout] Apr 16 02:38:02.067  INFO handle{service=echo}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:02.068  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:02.068  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:02.068  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:02.068  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:02.069  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:02.069  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:02.069  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:02.070  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:02.792  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: echo
[INFO] [stdout] Apr 16 02:38:02.795  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: pingpong
[INFO] [stdout] Apr 16 02:38:02.843  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: shutdown the server
[INFO] [stdout] Apr 16 02:38:02.843  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:38:02.844  INFO rathole::server: Shuting down gracefully...
[INFO] [stdout] Apr 16 02:38:02.844  INFO rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:02.844  INFO connection{addr=127.0.0.1:60552}:handle{service=pingpong}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:02.844  INFO connection{addr=127.0.0.1:60554}:handle{service=echo}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:02.845  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: restart the server
[INFO] [stdout] Apr 16 02:38:02.845 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 487.335581ms...
[INFO] [stdout] Apr 16 02:38:02.845  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file
[INFO] [stdout] Apr 16 02:38:02.845  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:02.846 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 525.041416ms...
[INFO] [stdout] Apr 16 02:38:02.846  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file
[INFO] [stdout] Apr 16 02:38:02.846  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:02.848  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:38:02.850  INFO rathole::server: Listening at 0.0.0.0:2332
[INFO] [stdout] Apr 16 02:38:03.030  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: echo
[INFO] [stdout] Apr 16 02:38:03.093  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: pingpong
[INFO] [stdout] Apr 16 02:38:03.139  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: shutdown the server
[INFO] [stdout] Apr 16 02:38:03.139  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:38:03.140  INFO rathole::server: Shuting down gracefully...
[INFO] [stdout] Apr 16 02:38:03.140  INFO rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:03.140  INFO connection{addr=127.0.0.1:58642}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown
[INFO] [stdout] Apr 16 02:38:03.140  INFO connection{addr=127.0.0.1:58642}:handle{service=echo}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:03.140  INFO connection{addr=127.0.0.1:58640}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown
[INFO] [stdout] Apr 16 02:38:03.140  INFO connection{addr=127.0.0.1:58640}:handle{service=pingpong}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:03.140  INFO connection{addr=127.0.0.1:58642}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:03.141  INFO connection{addr=127.0.0.1:58640}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:03.141  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: restart the server
[INFO] [stdout] Apr 16 02:38:03.142 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 505.015162ms...
[INFO] [stdout] Apr 16 02:38:03.142 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 452.923516ms...
[INFO] [stdout] Apr 16 02:38:03.142  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:03.142  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:03.142  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:03.143  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:03.143  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:03.143  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:03.143  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:03.143  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:03.144  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:03.144  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:03.144  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:03.144  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:03.144  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:03.145  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:03.145  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:03.145  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:03.147  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:38:03.149  INFO rathole::server: Listening at 0.0.0.0:2333
[INFO] [stdout] Apr 16 02:38:03.344  INFO connection{addr=127.0.0.1:60566}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:03.344  INFO connection{addr=127.0.0.1:60566}: rathole::server: Control channel established service=pingpong
[INFO] [stdout] Apr 16 02:38:03.344  INFO connection{addr=127.0.0.1:60566}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335
[INFO] [stdout] Apr 16 02:38:03.345  INFO handle{service=pingpong}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:03.381  INFO connection{addr=127.0.0.1:60572}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:03.381  INFO connection{addr=127.0.0.1:60572}: rathole::server: Control channel established service=echo
[INFO] [stdout] Apr 16 02:38:03.382  INFO connection{addr=127.0.0.1:60572}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334
[INFO] [stdout] Apr 16 02:38:03.382  INFO handle{service=echo}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:03.607  INFO connection{addr=127.0.0.1:58680}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:03.608  INFO connection{addr=127.0.0.1:58680}: rathole::server: Control channel established service=pingpong
[INFO] [stdout] Apr 16 02:38:03.609  INFO connection{addr=127.0.0.1:58680}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335
[INFO] [stdout] Apr 16 02:38:03.609  INFO handle{service=pingpong}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:03.678  INFO connection{addr=127.0.0.1:58700}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:03.679  INFO connection{addr=127.0.0.1:58700}: rathole::server: Control channel established service=echo
[INFO] [stdout] Apr 16 02:38:03.679  INFO connection{addr=127.0.0.1:58700}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334
[INFO] [stdout] Apr 16 02:38:03.680  INFO handle{service=echo}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:05.346  INFO test{config_path="tests/for_udp/tls_transport.toml" t=Udp}: integration_test: lots of echo and pingpong
[INFO] [stdout] Apr 16 02:38:05.353  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:38:05.353  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:38:05.353  INFO config_watcher{path="tests/for_udp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:38:05.354  INFO rathole::server: Shuting down gracefully...
[INFO] [stdout] Apr 16 02:38:05.354  INFO rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:05.354  INFO connection{addr=127.0.0.1:60566}:handle{service=pingpong}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:05.354  INFO connection{addr=127.0.0.1:60572}:handle{service=echo}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:05.355  INFO handle{service=echo}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:05.355  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:05.355  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: start the client
[INFO] [stdout] Apr 16 02:38:05.356  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file
[INFO] [stdout] Apr 16 02:38:05.356  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:05.356  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file
[INFO] [stdout] Apr 16 02:38:05.356  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:05.365  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:38:05.366  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b
[INFO] [stdout] Apr 16 02:38:05.366  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d
[INFO] [stdout] Apr 16 02:38:05.367 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 465.696465ms...
[INFO] [stdout] Apr 16 02:38:05.367 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 462.06013ms...
[INFO] [stdout] Apr 16 02:38:05.645  INFO test{config_path="tests/for_tcp/tls_transport.toml" t=Tcp}: integration_test: lots of echo and pingpong
[INFO] [stdout] Apr 16 02:38:05.797  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:38:05.798  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:38:05.798  INFO config_watcher{path="tests/for_tcp/tls_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:38:05.798  INFO rathole::server: Shuting down gracefully...
[INFO] [stdout] Apr 16 02:38:05.798  INFO rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:05.798  INFO connection{addr=127.0.0.1:58700}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown
[INFO] [stdout] Apr 16 02:38:05.799  INFO connection{addr=127.0.0.1:58700}:handle{service=echo}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:05.799  INFO connection{addr=127.0.0.1:58680}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown
[INFO] [stdout] Apr 16 02:38:05.799  INFO connection{addr=127.0.0.1:58680}:handle{service=pingpong}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:05.799  INFO handle{service=echo}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:05.800  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:05.800  INFO connection{addr=127.0.0.1:58700}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:05.800  INFO connection{addr=127.0.0.1:58680}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:05.801  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: start the client
[INFO] [stdout] Apr 16 02:38:05.801  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:05.802  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:05.802  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:05.802  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:05.802  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:05.803  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:05.803  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:05.803  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:05.803  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:05.804  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:05.804  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:05.804  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:05.804  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:05.805  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:05.805  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:05.805  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:05.821  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:38:05.822  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d
[INFO] [stdout] Apr 16 02:38:05.822  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b
[INFO] [stdout] Apr 16 02:38:05.825 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 512.870201ms...
[INFO] [stdout] Apr 16 02:38:05.825 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 549.908811ms...
[INFO] [stdout] Apr 16 02:38:05.830 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 774.773589ms...
[INFO] [stdout] Apr 16 02:38:05.834 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 719.619676ms...
[INFO] [stdout] Apr 16 02:38:06.339 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 741.54966ms...
[INFO] [stdout] Apr 16 02:38:06.356  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: start the server
[INFO] [stdout] Apr 16 02:38:06.359  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:38:06.359  INFO rathole::server: Listening at 0.0.0.0:2332
[INFO] [stdout] Apr 16 02:38:06.377 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 773.607096ms...
[INFO] [stdout] Apr 16 02:38:06.572  INFO connection{addr=127.0.0.1:60586}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:06.573  INFO connection{addr=127.0.0.1:60586}: rathole::server: Control channel established service=pingpong
[INFO] [stdout] Apr 16 02:38:06.573  INFO connection{addr=127.0.0.1:60586}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335
[INFO] [stdout] Apr 16 02:38:06.574  INFO handle{service=pingpong}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:06.623  INFO connection{addr=127.0.0.1:60592}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:06.625  INFO connection{addr=127.0.0.1:60592}: rathole::server: Control channel established service=echo
[INFO] [stdout] Apr 16 02:38:06.625  INFO connection{addr=127.0.0.1:60592}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334
[INFO] [stdout] Apr 16 02:38:06.626  INFO handle{service=echo}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:06.802  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: start the server
[INFO] [stdout] Apr 16 02:38:06.805  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:38:06.805  INFO rathole::server: Listening at 0.0.0.0:2333
[INFO] [stdout] Apr 16 02:38:07.093  INFO connection{addr=127.0.0.1:58738}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:07.094  INFO connection{addr=127.0.0.1:58738}: rathole::server: Control channel established service=echo
[INFO] [stdout] Apr 16 02:38:07.095  INFO connection{addr=127.0.0.1:58738}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334
[INFO] [stdout] Apr 16 02:38:07.096  INFO handle{service=echo}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:07.196  INFO connection{addr=127.0.0.1:58758}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:07.197  INFO connection{addr=127.0.0.1:58758}: rathole::server: Control channel established service=pingpong
[INFO] [stdout] Apr 16 02:38:07.199  INFO connection{addr=127.0.0.1:58758}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335
[INFO] [stdout] Apr 16 02:38:07.199  INFO handle{service=pingpong}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:08.858  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: echo
[INFO] [stdout] Apr 16 02:38:08.908  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: pingpong
[INFO] [stdout] Apr 16 02:38:08.961  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: shutdown the client
[INFO] [stdout] Apr 16 02:38:08.962  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:38:08.962  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:08.963  INFO handle{service=echo}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:08.963  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: restart the client
[INFO] [stdout] Apr 16 02:38:08.969  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:38:08.971  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b
[INFO] [stdout] Apr 16 02:38:08.971  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d
[INFO] [stdout] Apr 16 02:38:08.999  INFO connection{addr=127.0.0.1:60598}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:09.003  INFO connection{addr=127.0.0.1:60600}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:09.004  WARN connection{addr=127.0.0.1:60598}: rathole::server: Dropping previous control channel for service pingpong
[INFO] [stdout] Apr 16 02:38:09.004  INFO connection{addr=127.0.0.1:60598}: rathole::server: Control channel established service=pingpong
[INFO] [stdout] Apr 16 02:38:09.005  INFO connection{addr=127.0.0.1:60586}:handle{service=pingpong}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:09.006  INFO connection{addr=127.0.0.1:60598}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335
[INFO] [stdout] Apr 16 02:38:09.006  INFO handle{service=pingpong}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:09.007  WARN connection{addr=127.0.0.1:60600}: rathole::server: Dropping previous control channel for service echo
[INFO] [stdout] Apr 16 02:38:09.008  INFO connection{addr=127.0.0.1:60600}: rathole::server: Control channel established service=echo
[INFO] [stdout] Apr 16 02:38:09.008  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file
[INFO] [stdout] Apr 16 02:38:09.008  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:09.008  INFO connection{addr=127.0.0.1:60592}:handle{service=echo}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:09.009  INFO connection{addr=127.0.0.1:60600}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334
[INFO] [stdout] Apr 16 02:38:09.010  INFO handle{service=echo}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:09.026  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file
[INFO] [stdout] Apr 16 02:38:09.026  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:09.303  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: echo
[INFO] [stdout] Apr 16 02:38:09.504  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: pingpong
[INFO] [stdout] Apr 16 02:38:09.590  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: shutdown the client
[INFO] [stdout] Apr 16 02:38:09.590  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:38:09.591  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:09.591  INFO handle{service=echo}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:09.591  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: restart the client
[INFO] [stdout] Apr 16 02:38:09.596  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:38:09.598  INFO handle{service=echo}: rathole::client: Starting 092c79e8f80e559e404bcf660c48f3522b67aba9ff1484b0367e1a4ddef7431d
[INFO] [stdout] Apr 16 02:38:09.598  INFO handle{service=pingpong}: rathole::client: Starting c78862c4afddaa20fd3ff12e5e270480706499341ca5d1d7437ec9668556805b
[INFO] [stdout] Apr 16 02:38:09.626  INFO connection{addr=127.0.0.1:58780}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:09.629  INFO connection{addr=127.0.0.1:58782}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:09.630  WARN connection{addr=127.0.0.1:58780}: rathole::server: Dropping previous control channel for service echo
[INFO] [stdout] Apr 16 02:38:09.630  INFO connection{addr=127.0.0.1:58780}: rathole::server: Control channel established service=echo
[INFO] [stdout] Apr 16 02:38:09.631  INFO connection{addr=127.0.0.1:58738}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown
[INFO] [stdout] Apr 16 02:38:09.631  INFO connection{addr=127.0.0.1:58738}:handle{service=echo}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:09.633  INFO connection{addr=127.0.0.1:58738}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:09.633  INFO connection{addr=127.0.0.1:58780}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334
[INFO] [stdout] Apr 16 02:38:09.634  INFO handle{service=echo}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:09.636  WARN connection{addr=127.0.0.1:58782}: rathole::server: Dropping previous control channel for service pingpong
[INFO] [stdout] Apr 16 02:38:09.636  INFO connection{addr=127.0.0.1:58782}: rathole::server: Control channel established service=pingpong
[INFO] [stdout] Apr 16 02:38:09.636  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:09.636  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:09.637  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:09.637  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:09.637  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:09.637  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:09.637  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:09.638  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:09.638  INFO connection{addr=127.0.0.1:58758}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown
[INFO] [stdout] Apr 16 02:38:09.638  INFO connection{addr=127.0.0.1:58758}:handle{service=pingpong}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:09.640  INFO connection{addr=127.0.0.1:58758}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:09.640  INFO connection{addr=127.0.0.1:58782}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335
[INFO] [stdout] Apr 16 02:38:09.641  INFO handle{service=pingpong}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:09.673  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:09.674  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:09.674  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:09.674  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:09.674  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:09.674  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:09.675  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:09.675  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:09.965  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: echo
[INFO] [stdout] Apr 16 02:38:10.014  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: pingpong
[INFO] [stdout] Apr 16 02:38:10.066  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: shutdown the server
[INFO] [stdout] Apr 16 02:38:10.066  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:38:10.067  INFO rathole::server: Shuting down gracefully...
[INFO] [stdout] Apr 16 02:38:10.067  INFO rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:10.067  INFO connection{addr=127.0.0.1:60598}:handle{service=pingpong}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:10.067  INFO connection{addr=127.0.0.1:60600}:handle{service=echo}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:10.068  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: restart the server
[INFO] [stdout] Apr 16 02:38:10.068 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 460.772611ms...
[INFO] [stdout] Apr 16 02:38:10.068  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: unexpected end of file
[INFO] [stdout] Apr 16 02:38:10.068  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:10.068 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 493.631069ms...
[INFO] [stdout] Apr 16 02:38:10.069  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: unexpected end of file
[INFO] [stdout] Apr 16 02:38:10.069  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:10.071  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:38:10.071  INFO rathole::server: Listening at 0.0.0.0:2332
[INFO] [stdout] Apr 16 02:38:10.540  INFO connection{addr=127.0.0.1:60610}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:10.541  INFO connection{addr=127.0.0.1:60610}: rathole::server: Control channel established service=pingpong
[INFO] [stdout] Apr 16 02:38:10.542  INFO connection{addr=127.0.0.1:60610}:handle{service=pingpong}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2335
[INFO] [stdout] Apr 16 02:38:10.542  INFO handle{service=pingpong}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:10.573  INFO connection{addr=127.0.0.1:60616}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:10.574  INFO connection{addr=127.0.0.1:60616}: rathole::server: Control channel established service=echo
[INFO] [stdout] Apr 16 02:38:10.574  INFO connection{addr=127.0.0.1:60616}:handle{service=echo}:run_udp_connection_pool: rathole::server: Listening at 0.0.0.0:2334
[INFO] [stdout] Apr 16 02:38:10.575  INFO handle{service=echo}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:10.591  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: echo
[INFO] [stdout] Apr 16 02:38:10.765  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: pingpong
[INFO] [stdout] Apr 16 02:38:11.055  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: shutdown the server
[INFO] [stdout] Apr 16 02:38:11.055  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:38:11.056  INFO rathole::server: Shuting down gracefully...
[INFO] [stdout] Apr 16 02:38:11.056  INFO rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:11.056  INFO connection{addr=127.0.0.1:58780}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown
[INFO] [stdout] Apr 16 02:38:11.056  INFO connection{addr=127.0.0.1:58780}:handle{service=echo}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:11.056  INFO connection{addr=127.0.0.1:58782}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown
[INFO] [stdout] Apr 16 02:38:11.056  INFO connection{addr=127.0.0.1:58782}:handle{service=pingpong}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:11.056  INFO connection{addr=127.0.0.1:58780}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:11.057  INFO connection{addr=127.0.0.1:58782}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:11.057  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: restart the server
[INFO] [stdout] Apr 16 02:38:11.057 ERROR handle{service=echo}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 544.176699ms...
[INFO] [stdout] Apr 16 02:38:11.058 ERROR handle{service=pingpong}: rathole::client: Failed to run the control channel: Failed to read cmd: early eof. Retry in 453.816347ms...
[INFO] [stdout] Apr 16 02:38:11.058  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:11.058  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:11.058  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:11.058  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:11.058  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:11.058  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:11.059  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:11.059  WARN handle{service=echo}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:11.059  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:11.059  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:11.059  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:11.059  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:11.060  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:11.060  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:11.060  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:11.060  WARN handle{service=pingpong}:run: rathole::client: Failed to run the data channel: Failed to read cmd: early eof
[INFO] [stdout] Apr 16 02:38:11.062  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Start watching the config
[INFO] [stdout] Apr 16 02:38:11.063  INFO rathole::server: Listening at 0.0.0.0:2333
[INFO] [stdout] Apr 16 02:38:11.521  INFO connection{addr=127.0.0.1:58820}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:11.522  INFO connection{addr=127.0.0.1:58820}: rathole::server: Control channel established service=pingpong
[INFO] [stdout] Apr 16 02:38:11.523  INFO connection{addr=127.0.0.1:58820}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2335
[INFO] [stdout] Apr 16 02:38:11.523  INFO handle{service=pingpong}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:11.611  INFO connection{addr=127.0.0.1:58840}: rathole::server: Try to handshake a control channel
[INFO] [stdout] Apr 16 02:38:11.612  INFO connection{addr=127.0.0.1:58840}: rathole::server: Control channel established service=echo
[INFO] [stdout] Apr 16 02:38:11.613  INFO connection{addr=127.0.0.1:58840}:handle{service=echo}:run_tcp_connection_pool: rathole::server: Listening at 0.0.0.0:2334
[INFO] [stdout] Apr 16 02:38:11.613  INFO handle{service=echo}:run: rathole::client: Control channel established
[INFO] [stdout] Apr 16 02:38:12.568  INFO test{config_path="tests/for_udp/noise_transport.toml" t=Udp}: integration_test: lots of echo and pingpong
[INFO] [stdout] Apr 16 02:38:12.672  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:38:12.672  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:38:12.672  INFO config_watcher{path="tests/for_udp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:38:12.672  INFO rathole::server: Shuting down gracefully...
[INFO] [stdout] Apr 16 02:38:12.672  INFO rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:12.673  INFO connection{addr=127.0.0.1:60616}:handle{service=echo}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:12.673  INFO connection{addr=127.0.0.1:60610}:handle{service=pingpong}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:12.673  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:12.674  INFO handle{service=echo}:run: rathole::client: Control channel shutdown
[INFO] [stdout] test udp ... ok
[INFO] [stdout] Apr 16 02:38:13.559  INFO test{config_path="tests/for_tcp/noise_transport.toml" t=Tcp}: integration_test: lots of echo and pingpong
[INFO] [stdout] Apr 16 02:38:14.143  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:38:14.143  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:38:14.143  INFO config_watcher{path="tests/for_tcp/noise_transport.toml"}: rathole::config_watcher: Config watcher exiting
[INFO] [stdout] Apr 16 02:38:14.143  INFO rathole::server: Shuting down gracefully...
[INFO] [stdout] Apr 16 02:38:14.143  INFO rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:14.144  INFO connection{addr=127.0.0.1:58820}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: TCPListener shutdown
[INFO] [stdout] Apr 16 02:38:14.144  INFO connection{addr=127.0.0.1:58820}:handle{service=pingpong}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:14.144  INFO connection{addr=127.0.0.1:58840}:handle{service=echo}:run_tcp_connection_pool: rathole::server: TCPListener shutdown
[INFO] [stdout] Apr 16 02:38:14.144  INFO connection{addr=127.0.0.1:58840}:handle{service=echo}:run: rathole::server: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:14.145  INFO handle{service=pingpong}:run: rathole::client: Control channel shutdown
[INFO] [stderr]    Doc-tests rathole
[INFO] [stdout] Apr 16 02:38:14.145  INFO handle{service=echo}:run: rathole::client: Control channel shutdown
[INFO] [stdout] Apr 16 02:38:14.145  INFO connection{addr=127.0.0.1:58820}:handle{service=pingpong}:run_tcp_connection_pool: rathole::server: Shutdown
[INFO] [stdout] Apr 16 02:38:14.146  INFO connection{addr=127.0.0.1:58840}: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.91s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "78fca9ecfdb15f2ec95667db86969036d0f9245cef201981a2c3876457b7f83b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "78fca9ecfdb15f2ec95667db86969036d0f9245cef201981a2c3876457b7f83b", kill_on_drop: false }`
[INFO] [stdout] 78fca9ecfdb15f2ec95667db86969036d0f9245cef201981a2c3876457b7f83b