[INFO] cloning repository https://github.com/dkmstr/udstunnel
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/dkmstr/udstunnel" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdkmstr%2Fudstunnel", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdkmstr%2Fudstunnel'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] be54ec0ac67b020a211fdb61dceb755a6299f499
[INFO] testing dkmstr/udstunnel against try#a7ce54db9b096bd1098d07c645b489195a667c9f for pr-137940-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdkmstr%2Fudstunnel" "/workspace/builds/worker-4-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc2/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/dkmstr/udstunnel on toolchain a7ce54db9b096bd1098d07c645b489195a667c9f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+a7ce54db9b096bd1098d07c645b489195a667c9f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] removed /workspace/builds/worker-4-tc2/source/.cargo/config.toml
[INFO] started tweaking git repo https://github.com/dkmstr/udstunnel
[INFO] finished tweaking git repo https://github.com/dkmstr/udstunnel
[INFO] tweaked toml for git repo https://github.com/dkmstr/udstunnel written to /workspace/builds/worker-4-tc2/source/Cargo.toml
[INFO] crate git repo https://github.com/dkmstr/udstunnel already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+a7ce54db9b096bd1098d07c645b489195a667c9f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:1680c8fbd8dfb46fcd6d22e546df16ab0e007457dc29fcd9e987cdd5077fd1af" "/opt/rustwide/cargo-home/bin/cargo" "+a7ce54db9b096bd1098d07c645b489195a667c9f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 1cec7ed8c8100fa73504405a94d4cb4ef6fc91addd8e52f2345719cccf8c6aba
[INFO] running `Command { std: "docker" "start" "-a" "1cec7ed8c8100fa73504405a94d4cb4ef6fc91addd8e52f2345719cccf8c6aba", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "1cec7ed8c8100fa73504405a94d4cb4ef6fc91addd8e52f2345719cccf8c6aba", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1cec7ed8c8100fa73504405a94d4cb4ef6fc91addd8e52f2345719cccf8c6aba", kill_on_drop: false }`
[INFO] [stdout] 1cec7ed8c8100fa73504405a94d4cb4ef6fc91addd8e52f2345719cccf8c6aba
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:1680c8fbd8dfb46fcd6d22e546df16ab0e007457dc29fcd9e987cdd5077fd1af" "/opt/rustwide/cargo-home/bin/cargo" "+a7ce54db9b096bd1098d07c645b489195a667c9f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6794b4a76b2d484a3b95af99b480e06ad5e503ce972a6e73155cae67a94bad6f
[INFO] running `Command { std: "docker" "start" "-a" "6794b4a76b2d484a3b95af99b480e06ad5e503ce972a6e73155cae67a94bad6f", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.89
[INFO] [stderr]    Compiling unicode-ident v1.0.13
[INFO] [stderr]    Compiling libc v0.2.164
[INFO] [stderr]    Compiling once_cell v1.20.2
[INFO] [stderr]    Compiling shlex v1.3.0
[INFO] [stderr]    Compiling autocfg v1.4.0
[INFO] [stderr]    Compiling serde v1.0.215
[INFO] [stderr]    Compiling bytes v1.8.0
[INFO] [stderr]    Compiling stable_deref_trait v1.2.0
[INFO] [stderr]    Compiling pin-project-lite v0.2.15
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling fs_extra v1.3.0
[INFO] [stderr]    Compiling dunce v1.0.5
[INFO] [stderr]    Compiling pkg-config v0.3.31
[INFO] [stderr]    Compiling hashbrown v0.15.1
[INFO] [stderr]    Compiling vcpkg v0.2.15
[INFO] [stderr]    Compiling crunchy v0.2.2
[INFO] [stderr]    Compiling litemap v0.7.3
[INFO] [stderr]    Compiling lock_api v0.4.12
[INFO] [stderr]    Compiling writeable v0.5.5
[INFO] [stderr]    Compiling http v1.1.0
[INFO] [stderr]    Compiling slab v0.4.9
[INFO] [stderr]    Compiling ahash v0.8.11
[INFO] [stderr]    Compiling quote v1.0.37
[INFO] [stderr]    Compiling aws-lc-rs v1.11.0
[INFO] [stderr]    Compiling tiny-keccak v2.0.2
[INFO] [stderr]    Compiling indexmap v2.6.0
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling syn v2.0.87
[INFO] [stderr]    Compiling tracing-core v0.1.32
[INFO] [stderr]    Compiling futures-sink v0.3.31
[INFO] [stderr]    Compiling rustls-pki-types v1.10.0
[INFO] [stderr]    Compiling httparse v1.9.5
[INFO] [stderr]    Compiling futures-task v0.3.31
[INFO] [stderr]    Compiling icu_locid_transform_data v1.5.0
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling jobserver v0.1.32
[INFO] [stderr]    Compiling getrandom v0.2.15
[INFO] [stderr]    Compiling parking_lot_core v0.9.10
[INFO] [stderr]    Compiling socket2 v0.5.7
[INFO] [stderr]    Compiling parking_lot v0.12.3
[INFO] [stderr]    Compiling mio v1.0.2
[INFO] [stderr]    Compiling cc v1.2.16
[INFO] [stderr]    Compiling signal-hook-registry v1.4.2
[INFO] [stderr]    Compiling http-body v1.0.1
[INFO] [stderr]    Compiling tracing v0.1.40
[INFO] [stderr]    Compiling foreign-types-shared v0.1.1
[INFO] [stderr]    Compiling mirai-annotations v1.12.0
[INFO] [stderr]    Compiling zeroize v1.8.1
[INFO] [stderr]    Compiling openssl v0.10.70
[INFO] [stderr]    Compiling allocator-api2 v0.2.20
[INFO] [stderr]    Compiling untrusted v0.9.0
[INFO] [stderr]    Compiling icu_properties_data v1.5.0
[INFO] [stderr]    Compiling ucd-trie v0.1.7
[INFO] [stderr]    Compiling try-lock v0.2.5
[INFO] [stderr]    Compiling cmake v0.1.51
[INFO] [stderr]    Compiling atomic-waker v1.1.2
[INFO] [stderr]    Compiling want v0.3.1
[INFO] [stderr]    Compiling const-random-macro v0.1.16
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling foreign-types v0.3.2
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling native-tls v0.2.12
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling icu_normalizer_data v1.5.0
[INFO] [stderr]    Compiling utf8_iter v1.0.4
[INFO] [stderr]    Compiling write16 v1.0.0
[INFO] [stderr]    Compiling rustls v0.23.18
[INFO] [stderr]    Compiling utf16_iter v1.0.5
[INFO] [stderr]    Compiling anstyle-parse v0.2.6
[INFO] [stderr]    Compiling const-random v0.1.18
[INFO] [stderr]    Compiling regex-syntax v0.8.5
[INFO] [stderr]    Compiling anstyle-query v1.1.2
[INFO] [stderr]    Compiling openssl-probe v0.1.5
[INFO] [stderr]    Compiling colorchoice v1.0.3
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.1
[INFO] [stderr]    Compiling anstyle v1.0.10
[INFO] [stderr]    Compiling serde_json v1.0.133
[INFO] [stderr]    Compiling dlv-list v0.5.2
[INFO] [stderr]    Compiling encoding_rs v0.8.35
[INFO] [stderr]    Compiling winnow v0.6.20
[INFO] [stderr]    Compiling aws-lc-sys v0.23.0
[INFO] [stderr]    Compiling openssl-sys v0.9.105
[INFO] [stderr]    Compiling ring v0.17.13
[INFO] [stderr]    Compiling anstream v0.6.18
[INFO] [stderr]    Compiling ordered-multimap v0.7.3
[INFO] [stderr]    Compiling hashlink v0.8.4
[INFO] [stderr]    Compiling http-body-util v0.1.2
[INFO] [stderr]    Compiling webpki-roots v0.26.6
[INFO] [stderr]    Compiling regex-automata v0.4.9
[INFO] [stderr]    Compiling heck v0.5.0
[INFO] [stderr]    Compiling clap_lex v0.7.3
[INFO] [stderr]    Compiling synstructure v0.13.1
[INFO] [stderr]    Compiling unicode-segmentation v1.12.0
[INFO] [stderr]    Compiling strsim v0.11.1
[INFO] [stderr]    Compiling arraydeque v0.5.1
[INFO] [stderr]    Compiling convert_case v0.6.0
[INFO] [stderr]    Compiling clap_builder v4.5.21
[INFO] [stderr]    Compiling yaml-rust2 v0.8.1
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling rust-ini v0.20.0
[INFO] [stderr]    Compiling rustls-pemfile v2.2.0
[INFO] [stderr]    Compiling sync_wrapper v1.0.1
[INFO] [stderr]    Compiling cpufeatures v0.2.15
[INFO] [stderr]    Compiling ipnet v2.10.1
[INFO] [stderr]    Compiling pathdiff v0.2.2
[INFO] [stderr]    Compiling sha2 v0.10.8
[INFO] [stderr]    Compiling uuid v1.11.0
[INFO] [stderr]    Compiling serde_derive v1.0.215
[INFO] [stderr]    Compiling zerofrom-derive v0.1.4
[INFO] [stderr]    Compiling yoke-derive v0.7.4
[INFO] [stderr]    Compiling zerovec-derive v0.10.3
[INFO] [stderr]    Compiling displaydoc v0.2.5
[INFO] [stderr]    Compiling zerofrom v0.1.4
[INFO] [stderr]    Compiling tokio-macros v2.4.0
[INFO] [stderr]    Compiling yoke v0.7.4
[INFO] [stderr]    Compiling zerovec v0.10.4
[INFO] [stderr]    Compiling icu_provider_macros v1.5.0
[INFO] [stderr]    Compiling tokio v1.41.1
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling openssl-macros v0.1.1
[INFO] [stderr]    Compiling regex v1.11.1
[INFO] [stderr]    Compiling async-trait v0.1.83
[INFO] [stderr]    Compiling tinystr v0.7.6
[INFO] [stderr]    Compiling icu_collections v1.5.0
[INFO] [stderr]    Compiling env_filter v0.1.2
[INFO] [stderr]    Compiling icu_locid v1.5.0
[INFO] [stderr]    Compiling pest v2.7.14
[INFO] [stderr]    Compiling clap_derive v4.5.18
[INFO] [stderr]    Compiling env_logger v0.11.5
[INFO] [stderr]    Compiling icu_provider v1.5.0
[INFO] [stderr]    Compiling icu_locid_transform v1.5.0
[INFO] [stderr]    Compiling pest_meta v2.7.14
[INFO] [stderr]    Compiling clap v4.5.21
[INFO] [stderr]    Compiling icu_properties v1.5.1
[INFO] [stderr]    Compiling pest_generator v2.7.14
[INFO] [stderr]    Compiling pest_derive v2.7.14
[INFO] [stderr]    Compiling bitflags v2.6.0
[INFO] [stderr]    Compiling serde_spanned v0.6.8
[INFO] [stderr]    Compiling toml_datetime v0.6.8
[INFO] [stderr]    Compiling tokio-util v0.7.12
[INFO] [stderr]    Compiling toml_edit v0.22.22
[INFO] [stderr]    Compiling ron v0.8.1
[INFO] [stderr]    Compiling h2 v0.4.6
[INFO] [stderr]    Compiling icu_normalizer v1.5.0
[INFO] [stderr]    Compiling json5 v0.4.1
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling idna_adapter v1.2.0
[INFO] [stderr]    Compiling idna v1.0.3
[INFO] [stderr]    Compiling toml v0.8.19
[INFO] [stderr]    Compiling hyper v1.5.0
[INFO] [stderr]    Compiling url v2.5.3
[INFO] [stderr]    Compiling config v0.14.1
[INFO] [stderr]    Compiling hyper-util v0.1.10
[INFO] [stderr]    Compiling tokio-native-tls v0.3.1
[INFO] [stderr]    Compiling hyper-tls v0.6.0
[INFO] [stderr]    Compiling rustls-webpki v0.102.8
[INFO] [stderr]    Compiling tokio-rustls v0.26.0
[INFO] [stderr]    Compiling hyper-rustls v0.27.3
[INFO] [stderr]    Compiling reqwest v0.12.9
[INFO] [stderr]    Compiling udstunnel v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 2m 18s
[INFO] running `Command { std: "docker" "inspect" "6794b4a76b2d484a3b95af99b480e06ad5e503ce972a6e73155cae67a94bad6f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6794b4a76b2d484a3b95af99b480e06ad5e503ce972a6e73155cae67a94bad6f", kill_on_drop: false }`
[INFO] [stdout] 6794b4a76b2d484a3b95af99b480e06ad5e503ce972a6e73155cae67a94bad6f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:1680c8fbd8dfb46fcd6d22e546df16ab0e007457dc29fcd9e987cdd5077fd1af" "/opt/rustwide/cargo-home/bin/cargo" "+a7ce54db9b096bd1098d07c645b489195a667c9f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 3fef4b286a7beb01750f39bb73c70e2b341fc83712769fce3de6eba144956ea7
[INFO] running `Command { std: "docker" "start" "-a" "3fef4b286a7beb01750f39bb73c70e2b341fc83712769fce3de6eba144956ea7", kill_on_drop: false }`
[INFO] [stderr]    Compiling tokio v1.41.1
[INFO] [stderr]    Compiling mockall_derive v0.13.1
[INFO] [stderr]    Compiling predicates-core v1.0.8
[INFO] [stderr]    Compiling async-stream-impl v0.3.6
[INFO] [stderr]    Compiling termtree v0.4.1
[INFO] [stderr]    Compiling fragile v2.0.0
[INFO] [stderr]    Compiling downcast v0.11.0
[INFO] [stderr]    Compiling predicates v3.1.2
[INFO] [stderr]    Compiling predicates-tree v1.0.11
[INFO] [stderr]    Compiling async-stream v0.3.6
[INFO] [stderr]    Compiling mockall v0.13.1
[INFO] [stderr]    Compiling tokio-util v0.7.12
[INFO] [stderr]    Compiling tokio-native-tls v0.3.1
[INFO] [stderr]    Compiling tokio-rustls v0.26.0
[INFO] [stderr]    Compiling tokio-stream v0.1.16
[INFO] [stderr]    Compiling h2 v0.4.6
[INFO] [stderr]    Compiling tokio-test v0.4.4
[INFO] [stderr]    Compiling hyper v1.5.0
[INFO] [stderr]    Compiling hyper-util v0.1.10
[INFO] [stderr]    Compiling hyper-rustls v0.27.3
[INFO] [stderr]    Compiling hyper-tls v0.6.0
[INFO] [stderr]    Compiling reqwest v0.12.9
[INFO] [stderr]    Compiling udstunnel v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 28.14s
[INFO] running `Command { std: "docker" "inspect" "3fef4b286a7beb01750f39bb73c70e2b341fc83712769fce3de6eba144956ea7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3fef4b286a7beb01750f39bb73c70e2b341fc83712769fce3de6eba144956ea7", kill_on_drop: false }`
[INFO] [stdout] 3fef4b286a7beb01750f39bb73c70e2b341fc83712769fce3de6eba144956ea7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:1680c8fbd8dfb46fcd6d22e546df16ab0e007457dc29fcd9e987cdd5077fd1af" "/opt/rustwide/cargo-home/bin/cargo" "+a7ce54db9b096bd1098d07c645b489195a667c9f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 920dd401b2abb4cb34f7f7e46a591df22e0bd66fa31ae970f2f4d7bf59447534
[INFO] running `Command { std: "docker" "start" "-a" "920dd401b2abb4cb34f7f7e46a591df22e0bd66fa31ae970f2f4d7bf59447534", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.42s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/udstunnel-c1107b7628528a56)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test tls::crypto_provider::test_empty_cipher_list ... ok
[INFO] [stdout] test tls::crypto_provider::test_valid_cipher_list ... ok
[INFO] [stdout] test tls::crypto_provider::test_invalid_cipher_list ... ok
[INFO] [stdout] test tunnel::types::tests::test_response_to_string ... ok
[INFO] [stdout] test tunnel::types::tests::test_command_from_str ... ok
[INFO] [stdout] test tls::crypto_provider::test_some_valid_cipher_list ... ok
[INFO] [stdout] test tunnel::event::test_event_wakes_all_wakers ... ok
[INFO] [stdout] test tunnel::event::test_event_cleans_up_wakers ... ok
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/udstunnel-84f0ebb0af3bdf15)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.02s
[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] [stderr]      Running tests/test_config.rs (/opt/rustwide/target/debug/deps/test_config-80505f815cbb913c)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test tests::test_load_config_default ... ok
[INFO] [stdout] test tests::test_load_config_from_file ... ok
[INFO] [stdout] test tests::test_load_from_file_overrided_by_env ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_server_commands.rs (/opt/rustwide/target/debug/deps/test_server_commands-28271a5f055677b7)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stderr] DEBUG - udstunnel::tls::crypto_provider 59 cipher_suites: [TLS13_AES_256_GCM_SHA384, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256]
[INFO] [stderr] DEBUG - udstunnel::tunnel::config 264 Configuration loaded: Config { pidfile: "/tmp/udstunnel.pid", user: "dkmaster", loglevel: "DEBUG", logfile: Some("/tmp/tunnel.log"), logsize: 125829120, lognumber: 3, listen_address: "[::]", listen_port: 7777, ipv6: true, workers: 2, ssl_min_tls_version: "1.3", ssl_certificate: "tests/certs/cert.pem", ssl_certificate_key: "tests/certs/key.pem", ssl_ciphers: "TLS_AES_256_GCM_SHA384:TLS_CHACHA20_POLY1305_SHA256:ECDHE-ECDSA-CHACHA20-POLY1305-SHA256", uds_server: "http://127.0.0.1:8000/uds/rest/tunnel/ticket", uds_token: "uds_token", uds_timeout: 4s, uds_verify_ssl: false, handshake_timeout: 1s, command_timeout: 1s, secret: "49562cfc3b17139ea01c480b9c86a2ddacb38ff1b2e9db1bf66bab7a4e3f1fb5", allow: ["127.0.0.1", "127.0.0.2", "::1"] }
[INFO] [stderr] DEBUG - udstunnel::tunnel::config 264 Configuration loaded: Config { pidfile: "/tmp/udstunnel.pid", user: "dkmaster", loglevel: "DEBUG", logfile: Some("/tmp/tunnel.log"), logsize: 125829120, lognumber: 3, listen_address: "[::]", listen_port: 7777, ipv6: true, workers: 2, ssl_min_tls_version: "1.3", ssl_certificate: "tests/certs/cert.pem", ssl_certificate_key: "tests/certs/key.pem", ssl_ciphers: "TLS_AES_256_GCM_SHA384:TLS_CHACHA20_POLY1305_SHA256:ECDHE-ECDSA-CHACHA20-POLY1305-SHA256", uds_server: "http://127.0.0.1:8000/uds/rest/tunnel/ticket", uds_token: "uds_token", uds_timeout: 4s, uds_verify_ssl: false, handshake_timeout: 1s, command_timeout: 1s, secret: "49562cfc3b17139ea01c480b9c86a2ddacb38ff1b2e9db1bf66bab7a4e3f1fb5", allow: ["127.0.0.1", "127.0.0.2", "::1"] }
[INFO] [stderr] DEBUG - udstunnel::tunnel::config 264 Configuration loaded: Config { pidfile: "/tmp/udstunnel.pid", user: "dkmaster", loglevel: "DEBUG", logfile: Some("/tmp/tunnel.log"), logsize: 125829120, lognumber: 3, listen_address: "[::]", listen_port: 7777, ipv6: true, workers: 2, ssl_min_tls_version: "1.3", ssl_certificate: "tests/certs/cert.pem", ssl_certificate_key: "tests/certs/key.pem", ssl_ciphers: "TLS_AES_256_GCM_SHA384:TLS_CHACHA20_POLY1305_SHA256:ECDHE-ECDSA-CHACHA20-POLY1305-SHA256", uds_server: "http://127.0.0.1:8000/uds/rest/tunnel/ticket", uds_token: "uds_token", uds_timeout: 4s, uds_verify_ssl: false, handshake_timeout: 1s, command_timeout: 1s, secret: "49562cfc3b17139ea01c480b9c86a2ddacb38ff1b2e9db1bf66bab7a4e3f1fb5", allow: ["127.0.0.1", "127.0.0.2", "::1"] }
[INFO] [stderr] DEBUG - udstunnel::tls::crypto_provider 59 cipher_suites: [TLS13_AES_256_GCM_SHA384, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256]
[INFO] [stderr] DEBUG - udstunnel::tls::crypto_provider 59 cipher_suites: [TLS13_AES_256_GCM_SHA384, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256]
[INFO] [stderr] DEBUG - udstunnel::tls::crypto_provider 59 cipher_suites: [TLS13_AES_256_GCM_SHA384, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256]
[INFO] [stderr] DEBUG - udstunnel::tunnel::server 68 cipher_suites: [TLS13_AES_256_GCM_SHA384, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256]
[INFO] [stderr] INFO - udstunnel::tunnel::server 87 Tunnel server running on [::]:38601
[INFO] [stderr] DEBUG - udstunnel::tls::crypto_provider 59 cipher_suites: [TLS13_AES_256_GCM_SHA384, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256]
[INFO] [stderr] DEBUG - udstunnel::tunnel::server 68 cipher_suites: [TLS13_AES_256_GCM_SHA384, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256]
[INFO] [stderr] INFO - udstunnel::tunnel::server 87 Tunnel server running on [::]:38955
[INFO] [stderr] DEBUG - udstunnel::tunnel::server 68 cipher_suites: [TLS13_AES_256_GCM_SHA384, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256]
[INFO] [stderr] INFO - udstunnel::tunnel::server 87 Tunnel server running on [::]:43555
[INFO] [stderr] DEBUG - udstunnel::tunnel::server 68 cipher_suites: [TLS13_AES_256_GCM_SHA384, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256]
[INFO] [stderr] DEBUG - udstunnel::tunnel::server 68 cipher_suites: [TLS13_AES_256_GCM_SHA384, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256]
[INFO] [stderr] INFO - udstunnel::tunnel::server 87 Tunnel server running on [::]:39057
[INFO] [stderr] INFO - udstunnel::tunnel::server 87 Tunnel server running on [::]:43789
[INFO] [stderr] DEBUG - udstunnel::tls::client 66 Connecting to localhost:38955
[INFO] [stderr] DEBUG - udstunnel::tls::client 71 Loading certificates from: /etc/ssl/certs/ca-certificates.crt
[INFO] [stderr] DEBUG - udstunnel::tls::client 66 Connecting to localhost:43555
[INFO] [stderr] DEBUG - udstunnel::tls::client 71 Loading certificates from: /etc/ssl/certs/ca-certificates.crt
[INFO] [stderr] DEBUG - udstunnel::tls::client 66 Connecting to localhost:39057
[INFO] [stderr] DEBUG - udstunnel::tls::client 71 Loading certificates from: /etc/ssl/certs/ca-certificates.crt
[INFO] [stderr] DEBUG - udstunnel::tls::client 66 Connecting to localhost:43789
[INFO] [stderr] DEBUG - udstunnel::tls::client 71 Loading certificates from: /etc/ssl/certs/ca-certificates.crt
[INFO] [stderr] INFO - udstunnel::tunnel::server 116 CONNECTION (e4d28539-9426) from [::1]:34596
[INFO] [stderr] DEBUG - rustls::webpki::anchors 54 add_parsable_certificates processed 146 valid and 0 invalid certs
[INFO] [stderr] DEBUG - udstunnel::tunnel::client 24 Hook for connection established: PollEvented { io: Some(TcpStream { addr: [::1]:53466, peer: [::1]:38955, fd: 35 }) }
[INFO] [stderr] DEBUG - rustls::client::hs 83 No cached session for DnsName("localhost")
[INFO] [stderr] DEBUG - rustls::client::hs 141 Not resuming any session
[INFO] [stderr] DEBUG - udstunnel::tls::noverify 58 Supported verify schemes
[INFO] [stderr] INFO - udstunnel::tunnel::server 116 CONNECTION (c056fb4f-a184) from [::1]:53466
[INFO] [stderr] DEBUG - udstunnel::tunnel::server 146 HANDSHAKE (c056fb4f-a184) from [::1]:53466
[INFO] [stderr] DEBUG - rustls::server::hs 441 decided upon suite TLS13_AES_256_GCM_SHA384
[INFO] [stderr] DEBUG - rustls::client::hs 851 Using ciphersuite TLS13_AES_256_GCM_SHA384
[INFO] [stderr] DEBUG - rustls::client::tls13 141 Not resuming
[INFO] [stderr] DEBUG - rustls::client::tls13 423 TLS1.3 encrypted extensions: [ServerNameAck]
[INFO] [stderr] DEBUG - rustls::client::hs 662 ALPN protocol is None
[INFO] [stderr] DEBUG - udstunnel::tls::noverify 22 Skipping server verification
[INFO] [stderr] DEBUG - udstunnel::tls::noverify 50 Skipping TLS 1.3 signature verification
[INFO] [stderr] INFO - udstunnel::tunnel::server 264 COMMAND (c056fb4f-a184) OPEN xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx from [::1]:53466
[INFO] [stderr] DEBUG - test_server_commands::fake::tunnel_server 49 Mocked request: ticket: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx msg: ::1 param:
[INFO] [stderr] DEBUG - udstunnel::tunnel::relay 78 UDS Response: UdsTicketResponse { host: "[::1]", port: 36117, notify: "notify_012345678901234567890123456789012" }
[INFO] [stderr] INFO - udstunnel::tunnel::relay 105 OPEN TUNNEL (c056fb4f-a184) FROM [::1]:53466 to [::1]:36117
[INFO] [stderr] DEBUG - udstunnel::tunnel::relay 238 Waiting for any to complete
[INFO] [stderr] DEBUG - udstunnel::tunnel::relay 146 Starting server_to_client task
[INFO] [stderr] DEBUG - udstunnel::tunnel::relay 196 Starting client_to_server task
[INFO] [stderr] DEBUG - rustls::common_state 574 Sending warning alert CloseNotify
[INFO] [stderr] DEBUG - udstunnel::tunnel::relay 200 Stopping client_to_server task
[INFO] [stderr] DEBUG - udstunnel::tunnel::relay 150 Stopping server_to_client task
[INFO] [stderr] DEBUG - udstunnel::tunnel::relay 241 client_to_server task completed: Ok(())
[INFO] [stderr] DEBUG - udstunnel::tunnel::relay 258 Notifying end to UDS
[INFO] [stderr] INFO - udstunnel::tunnel::relay 265 TERMINATED (c056fb4f-a184) [::1]:53466 to [::1]:36117, s:0, r:0, t:0
[INFO] [stderr] DEBUG - test_server_commands::fake::tunnel_server 49 Mocked request: ticket: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx msg: stop param:sent=0&recv=0&elapsed=0
[INFO] [stderr] DEBUG - udstunnel::tunnel::relay 260 End of tunnel relay
[INFO] [stdout] test test_server_open_command ... ok
[INFO] [stderr] DEBUG - rustls::webpki::anchors 54 add_parsable_certificates processed 146 valid and 0 invalid certs
[INFO] [stderr] DEBUG - rustls::webpki::anchors 54 add_parsable_certificates processed 146 valid and 0 invalid certs
[INFO] [stderr] DEBUG - udstunnel::tunnel::client 24 Hook for connection established: PollEvented { io: Some(TcpStream { addr: [::1]:35928, peer: [::1]:43789, fd: 8 }) }
[INFO] [stderr] DEBUG - rustls::client::hs 83 No cached session for DnsName("localhost")
[INFO] [stderr] DEBUG - rustls::client::hs 141 Not resuming any session
[INFO] [stderr] DEBUG - udstunnel::tls::noverify 58 Supported verify schemes
[INFO] [stderr] INFO - udstunnel::tunnel::server 116 CONNECTION (d6e0ebbe-7c3a) from [::1]:35928
[INFO] [stderr] DEBUG - udstunnel::tunnel::server 146 HANDSHAKE (d6e0ebbe-7c3a) from [::1]:35928
[INFO] [stderr] DEBUG - rustls::server::hs 441 decided upon suite TLS13_AES_256_GCM_SHA384
[INFO] [stderr] DEBUG - udstunnel::tunnel::client 24 Hook for connection established: PollEvented { io: Some(TcpStream { addr: [::1]:50802, peer: [::1]:43555, fd: 10 }) }
[INFO] [stderr] DEBUG - rustls::client::hs 83 No cached session for DnsName("localhost")
[INFO] [stderr] DEBUG - rustls::client::hs 141 Not resuming any session
[INFO] [stderr] DEBUG - udstunnel::tls::noverify 58 Supported verify schemes
[INFO] [stderr] INFO - udstunnel::tunnel::server 116 CONNECTION (57f94a1c-519c) from [::1]:50802
[INFO] [stderr] DEBUG - udstunnel::tunnel::server 146 HANDSHAKE (57f94a1c-519c) from [::1]:50802
[INFO] [stderr] DEBUG - rustls::server::hs 441 decided upon suite TLS13_AES_256_GCM_SHA384
[INFO] [stderr] DEBUG - rustls::client::hs 851 Using ciphersuite TLS13_AES_256_GCM_SHA384
[INFO] [stderr] DEBUG - rustls::client::tls13 141 Not resuming
[INFO] [stderr] DEBUG - rustls::client::tls13 423 TLS1.3 encrypted extensions: [ServerNameAck]
[INFO] [stderr] DEBUG - rustls::client::hs 662 ALPN protocol is None
[INFO] [stderr] DEBUG - udstunnel::tls::noverify 22 Skipping server verification
[INFO] [stderr] DEBUG - udstunnel::tls::noverify 50 Skipping TLS 1.3 signature verification
[INFO] [stderr] INFO - udstunnel::tunnel::server 264 COMMAND (d6e0ebbe-7c3a) TEST from [::1]:35928
[INFO] [stderr] INFO - udstunnel::tunnel::server 175 TEST (d6e0ebbe-7c3a) from [::1]:35928
[INFO] [stderr] DEBUG - rustls::common_state 574 Sending warning alert CloseNotify
[INFO] [stderr] DEBUG - rustls::common_state 574 Sending warning alert CloseNotify
[INFO] [stderr] DEBUG - rustls::webpki::anchors 54 add_parsable_certificates processed 146 valid and 0 invalid certs
[INFO] [stderr] DEBUG - udstunnel::tunnel::client 24 Hook for connection established: PollEvented { io: Some(TcpStream { addr: [::1]:58398, peer: [::1]:39057, fd: 8 }) }
[INFO] [stderr] DEBUG - rustls::client::hs 83 No cached session for DnsName("localhost")
[INFO] [stderr] DEBUG - rustls::client::hs 141 Not resuming any session
[INFO] [stderr] DEBUG - udstunnel::tls::noverify 58 Supported verify schemes
[INFO] [stderr] INFO - udstunnel::tunnel::server 116 CONNECTION (af06d756-753a) from [::1]:58398
[INFO] [stderr] DEBUG - udstunnel::tunnel::server 146 HANDSHAKE (af06d756-753a) from [::1]:58398
[INFO] [stderr] DEBUG - rustls::server::hs 441 decided upon suite TLS13_AES_256_GCM_SHA384
[INFO] [stderr] DEBUG - rustls::client::hs 851 Using ciphersuite TLS13_AES_256_GCM_SHA384
[INFO] [stderr] DEBUG - rustls::client::tls13 141 Not resuming
[INFO] [stderr] DEBUG - rustls::client::tls13 423 TLS1.3 encrypted extensions: [ServerNameAck]
[INFO] [stderr] DEBUG - rustls::client::hs 662 ALPN protocol is None
[INFO] [stderr] DEBUG - udstunnel::tls::noverify 22 Skipping server verification
[INFO] [stderr] DEBUG - udstunnel::tls::noverify 50 Skipping TLS 1.3 signature verification
[INFO] [stderr] INFO - udstunnel::tunnel::server 264 COMMAND (57f94a1c-519c) UNKNOWN from [::1]:50802
[INFO] [stderr] ERROR - udstunnel::tunnel::server 306 COMMAND (57f94a1c-519c) invalid from [::1]:50802: 5a4d4742a50100
[INFO] [stderr] DEBUG - rustls::common_state 574 Sending warning alert CloseNotify
[INFO] [stderr] DEBUG - rustls::common_state 574 Sending warning alert CloseNotify
[INFO] [stdout] test test_server_test_command ... ok
[INFO] [stdout] test test_invalid_command ... ok
[INFO] [stderr] DEBUG - rustls::client::hs 851 Using ciphersuite TLS13_AES_256_GCM_SHA384
[INFO] [stderr] DEBUG - rustls::client::tls13 141 Not resuming
[INFO] [stderr] DEBUG - rustls::client::tls13 423 TLS1.3 encrypted extensions: [ServerNameAck]
[INFO] [stderr] DEBUG - rustls::client::hs 662 ALPN protocol is None
[INFO] [stderr] DEBUG - udstunnel::tls::noverify 22 Skipping server verification
[INFO] [stderr] DEBUG - udstunnel::tls::noverify 50 Skipping TLS 1.3 signature verification
[INFO] [stderr] INFO - udstunnel::tunnel::server 264 COMMAND (af06d756-753a) STAT 49562cfc3b17139ea01c480b9c86a2ddacb38ff1b2e9db1bf66bab7a4e3f1fb5 from [::1]:58398
[INFO] [stderr] INFO - udstunnel::tunnel::server 185 STATS (af06d756-753a) from [::1]:58398
[INFO] [stderr] DEBUG - rustls::common_state 574 Sending warning alert CloseNotify
[INFO] [stdout] test test_server_stats ... ok
[INFO] [stderr] ERROR - udstunnel::tunnel::server 300 HANDSHAKE (e4d28539-9426) error from [::1]:34596: timed out
[INFO] [stderr]      Running tests/test_server_connectivity.rs (/opt/rustwide/target/debug/deps/test_server_connectivity-89d860c0b18d115d)
[INFO] [stdout] test test_timedout_command ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.22s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stderr] DEBUG - udstunnel::tunnel::config 264 Configuration loaded: Config { pidfile: "/tmp/udstunnel.pid", user: "dkmaster", loglevel: "DEBUG", logfile: Some("/tmp/tunnel.log"), logsize: 125829120, lognumber: 3, listen_address: "[::]", listen_port: 7777, ipv6: true, workers: 2, ssl_min_tls_version: "1.3", ssl_certificate: "tests/certs/cert.pem", ssl_certificate_key: "tests/certs/key.pem", ssl_ciphers: "TLS_AES_256_GCM_SHA384:TLS_CHACHA20_POLY1305_SHA256:ECDHE-ECDSA-CHACHA20-POLY1305-SHA256", uds_server: "http://127.0.0.1:8000/uds/rest/tunnel/ticket", uds_token: "uds_token", uds_timeout: 4s, uds_verify_ssl: false, handshake_timeout: 1s, command_timeout: 1s, secret: "49562cfc3b17139ea01c480b9c86a2ddacb38ff1b2e9db1bf66bab7a4e3f1fb5", allow: ["127.0.0.1", "127.0.0.2", "::1"] }
[INFO] [stderr] DEBUG - udstunnel::tls::crypto_provider 59 cipher_suites: [TLS13_AES_256_GCM_SHA384, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256]
[INFO] [stderr] DEBUG - udstunnel::tls::crypto_provider 59 cipher_suites: [TLS13_AES_256_GCM_SHA384, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256]
[INFO] [stderr] DEBUG - udstunnel::tunnel::server 68 cipher_suites: [TLS13_AES_256_GCM_SHA384, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256]
[INFO] [stderr] INFO - udstunnel::tunnel::server 87 Tunnel server running on [::]:36725
[INFO] [stderr] DEBUG - udstunnel::tunnel::server 68 cipher_suites: [TLS13_AES_256_GCM_SHA384, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256]
[INFO] [stderr] INFO - udstunnel::tunnel::server 87 Tunnel server running on [::]:42329
[INFO] [stderr] DEBUG - udstunnel::tunnel::config 264 Configuration loaded: Config { pidfile: "/tmp/udstunnel.pid", user: "dkmaster", loglevel: "DEBUG", logfile: Some("/tmp/tunnel.log"), logsize: 125829120, lognumber: 3, listen_address: "[::]", listen_port: 7777, ipv6: true, workers: 2, ssl_min_tls_version: "1.3", ssl_certificate: "tests/certs/cert.pem", ssl_certificate_key: "tests/certs/key.pem", ssl_ciphers: "TLS_AES_256_GCM_SHA384:TLS_CHACHA20_POLY1305_SHA256:ECDHE-ECDSA-CHACHA20-POLY1305-SHA256", uds_server: "http://127.0.0.1:8000/uds/rest/tunnel/ticket", uds_token: "uds_token", uds_timeout: 4s, uds_verify_ssl: false, handshake_timeout: 1s, command_timeout: 1s, secret: "49562cfc3b17139ea01c480b9c86a2ddacb38ff1b2e9db1bf66bab7a4e3f1fb5", allow: ["127.0.0.1", "127.0.0.2", "::1"] }
[INFO] [stderr] DEBUG - udstunnel::tls::crypto_provider 59 cipher_suites: [TLS13_AES_256_GCM_SHA384, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256]
[INFO] [stderr] DEBUG - udstunnel::tunnel::server 68 cipher_suites: [TLS13_AES_256_GCM_SHA384, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256]
[INFO] [stderr] INFO - udstunnel::tunnel::server 87 Tunnel server running on [::]:35107
[INFO] [stderr] DEBUG - udstunnel::tls::client 66 Connecting to localhost:36725
[INFO] [stderr] DEBUG - udstunnel::tls::client 71 Loading certificates from: /etc/ssl/certs/ca-certificates.crt
[INFO] [stderr] INFO - udstunnel::tunnel::server 116 CONNECTION (e0abd48a-d7a7) from [::1]:41630
[INFO] [stdout] test test_server_listens ... ok
[INFO] [stderr] INFO - udstunnel::tunnel::server 116 CONNECTION (cddcd6ee-5ec6) from [::1]:55924
[INFO] [stderr] DEBUG - rustls::webpki::anchors 54 add_parsable_certificates processed 146 valid and 0 invalid certs
[INFO] [stderr] DEBUG - udstunnel::tunnel::client 24 Hook for connection established: PollEvented { io: Some(TcpStream { addr: [::1]:36752, peer: [::1]:36725, fd: 8 }) }
[INFO] [stderr] DEBUG - rustls::client::hs 83 No cached session for DnsName("localhost")
[INFO] [stderr] DEBUG - rustls::client::hs 141 Not resuming any session
[INFO] [stderr] DEBUG - udstunnel::tls::noverify 58 Supported verify schemes
[INFO] [stderr] INFO - udstunnel::tunnel::server 116 CONNECTION (724601b1-ad1a) from [::1]:36752
[INFO] [stderr] DEBUG - udstunnel::tunnel::server 146 HANDSHAKE (724601b1-ad1a) from [::1]:36752
[INFO] [stderr] DEBUG - rustls::server::hs 441 decided upon suite TLS13_AES_256_GCM_SHA384
[INFO] [stderr] DEBUG - rustls::client::hs 851 Using ciphersuite TLS13_AES_256_GCM_SHA384
[INFO] [stderr] DEBUG - rustls::client::tls13 141 Not resuming
[INFO] [stderr] DEBUG - rustls::client::tls13 423 TLS1.3 encrypted extensions: [ServerNameAck]
[INFO] [stderr] DEBUG - rustls::client::hs 662 ALPN protocol is None
[INFO] [stderr] DEBUG - udstunnel::tls::noverify 22 Skipping server verification
[INFO] [stderr] DEBUG - udstunnel::tls::noverify 50 Skipping TLS 1.3 signature verification
[INFO] [stderr] DEBUG - rustls::common_state 574 Sending warning alert CloseNotify
[INFO] [stdout] test test_server_handshake ... ok
[INFO] [stderr] ERROR - udstunnel::tunnel::server 300 HANDSHAKE (cddcd6ee-5ec6) error from [::1]:55924: timed out
[INFO] [stdout] test test_server_handshake_timeout ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.41s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_server_to_remote.rs (/opt/rustwide/target/debug/deps/test_server_to_remote-f4558977e7ee3c7f)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr] DEBUG - udstunnel::tls::crypto_provider 59 cipher_suites: [TLS13_AES_256_GCM_SHA384, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256]
[INFO] [stderr] DEBUG - udstunnel::tunnel::server 68 cipher_suites: [TLS13_AES_256_GCM_SHA384, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256]
[INFO] [stderr] INFO - udstunnel::tunnel::server 87 Tunnel server running on [::]:41533
[INFO] [stderr] DEBUG - udstunnel::tls::client 66 Connecting to localhost:41533
[INFO] [stderr] DEBUG - udstunnel::tls::client 71 Loading certificates from: /etc/ssl/certs/ca-certificates.crt
[INFO] [stderr] DEBUG - rustls::webpki::anchors 54 add_parsable_certificates processed 146 valid and 0 invalid certs
[INFO] [stderr] DEBUG - udstunnel::tunnel::client 24 Hook for connection established: PollEvented { io: Some(TcpStream { addr: [::1]:35474, peer: [::1]:41533, fd: 11 }) }
[INFO] [stderr] DEBUG - rustls::client::hs 83 No cached session for DnsName("localhost")
[INFO] [stderr] DEBUG - rustls::client::hs 141 Not resuming any session
[INFO] [stderr] DEBUG - udstunnel::tls::noverify 58 Supported verify schemes
[INFO] [stderr] INFO - udstunnel::tunnel::server 116 CONNECTION (52181e11-65e1) from [::1]:35474
[INFO] [stderr] DEBUG - udstunnel::tunnel::server 146 HANDSHAKE (52181e11-65e1) from [::1]:35474
[INFO] [stderr] DEBUG - rustls::server::hs 441 decided upon suite TLS13_AES_256_GCM_SHA384
[INFO] [stderr] DEBUG - rustls::client::hs 851 Using ciphersuite TLS13_AES_256_GCM_SHA384
[INFO] [stderr] DEBUG - rustls::client::tls13 141 Not resuming
[INFO] [stderr] DEBUG - rustls::client::tls13 423 TLS1.3 encrypted extensions: [ServerNameAck]
[INFO] [stderr] DEBUG - rustls::client::hs 662 ALPN protocol is None
[INFO] [stderr] DEBUG - udstunnel::tls::noverify 22 Skipping server verification
[INFO] [stderr] DEBUG - udstunnel::tls::noverify 50 Skipping TLS 1.3 signature verification
[INFO] [stderr] INFO - udstunnel::tunnel::server 264 COMMAND (52181e11-65e1) OPEN xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx from [::1]:35474
[INFO] [stderr] DEBUG - test_server_to_remote::fake::tunnel_server 49 Mocked request: ticket: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx msg: ::1 param:
[INFO] [stderr] DEBUG - udstunnel::tunnel::relay 78 UDS Response: UdsTicketResponse { host: "[::1]", port: 44911, notify: "notify_012345678901234567890123456789012" }
[INFO] [stderr] INFO - udstunnel::tunnel::relay 105 OPEN TUNNEL (52181e11-65e1) FROM [::1]:35474 to [::1]:44911
[INFO] [stderr] DEBUG - udstunnel::tunnel::relay 238 Waiting for any to complete
[INFO] [stderr] DEBUG - udstunnel::tunnel::relay 146 Starting server_to_client task
[INFO] [stderr] DEBUG - udstunnel::tunnel::relay 196 Starting client_to_server task
[INFO] [stderr] DEBUG - udstunnel::tunnel::relay 200 Stopping client_to_server task
[INFO] [stderr] DEBUG - udstunnel::tunnel::relay 150 Stopping server_to_client task
[INFO] [stderr] DEBUG - udstunnel::tunnel::relay 241 client_to_server task completed: Ok(())
[INFO] [stderr] DEBUG - udstunnel::tunnel::relay 258 Notifying end to UDS
[INFO] [stderr] INFO - udstunnel::tunnel::relay 265 TERMINATED (52181e11-65e1) [::1]:35474 to [::1]:44911, s:128, r:128, t:0
[INFO] [stderr] DEBUG - test_server_to_remote::fake::tunnel_server 49 Mocked request: ticket: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx msg: stop param:sent=128&recv=128&elapsed=0
[INFO] [stderr] DEBUG - udstunnel::tunnel::relay 260 End of tunnel relay
[INFO] [stdout] test test_server_to_remote ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.24s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests udstunnel
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test src/tunnel/config.rs - tunnel::config::Config (line 32) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.94s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "920dd401b2abb4cb34f7f7e46a591df22e0bd66fa31ae970f2f4d7bf59447534", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "920dd401b2abb4cb34f7f7e46a591df22e0bd66fa31ae970f2f4d7bf59447534", kill_on_drop: false }`
[INFO] [stdout] 920dd401b2abb4cb34f7f7e46a591df22e0bd66fa31ae970f2f4d7bf59447534