Error while upgrading cnx: hyper::Error(HeaderTimeout)

Describe the bug

To Reproduce

the server side:

./wstunnel server wss://0.0.0.0:8080

The client side: ./wstunnel client -L socks5://127.0.0.1:8888 --connection-min-idle 5 wss://test.myserver.com:8080

test by curl: curl -v -x socks5h://127.0.0.1:8888 https://www.google.com/

curl output:

Trying 127.0.0.1:8888...
TCP_NODELAY set
SOCKS5 communication to www.google.com:443
SOCKS5 connect to www.google.com:443 (remotely resolved)
SOCKS5 request granted.
Connected to 127.0.0.1 (127.0.0.1) port 8888 (#0)
ALPN, offering h2
ALPN, offering http/1.1
successfully set certificate verify locations:
CAfile: /etc/ssl/certs/ca-certificates.crt CApath: /etc/ssl/certs
TLSv1.3 (OUT), TLS handshake, Client hello (1):
OpenSSL SSL_connect: SSL_ERROR_SYSCALL in connection to www.google.com:443
Closing connection 0 curl: (35) OpenSSL SSL_connect: SSL_ERROR_SYSCALL in connection to www.google.com:443

Expected behavior

working

Your wstunnel setup

Paste your logs of wstunnel, started with --log-lvl=DEBUG, and with the command line used

client 2024-09-28T06:24:44.323563Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:24:44.323791Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:24:44.323875Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:24:44.323927Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:24:44.324009Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:24:44.511408Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:24:44.511693Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:24:44.511774Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:24:44.511875Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:24:44.514524Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:24:44.525597Z INFO wstunnel::protocols::socks5::tcp_server: Starting SOCKS5 server listening cnx on 127.0.0.1:8888 with credentials None 2024-09-28T06:25:44.325163Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:25:44.325263Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:25:44.325319Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:25:44.325396Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:25:44.325451Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:25:44.507196Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:25:44.507499Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:25:44.510472Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:25:44.510602Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:25:44.510688Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:26:44.324734Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:26:44.324823Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:26:44.324919Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:26:44.325019Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:26:44.325076Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:26:44.463253Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:26:44.463379Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:26:44.463463Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:26:44.466137Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:26:44.466357Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:27:11.921241Z ERROR tunnel{id="0192374f-d5b0-7483-911b-8e3e05faf97f" remote="google.com:80"}: wstunnel::tunnel::client::client: failed to do websocket handshake with the server wss://test.myserver.com:8080

Caused by: 0: operation was canceled 1: connection error 2: peer closed connection without sending TLS close_notify: https://docs.rs/rustls/latest/rustls/manual/_03_howto/index.html#unexpected-eof 2024-09-28T06:27:11.923370Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:27:11.935121Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:27:21.210279Z ERROR tunnel{id="0192374f-f9fa-7941-9636-c46c7c38a3d2" remote="google.com:80"}: wstunnel::tunnel::client::client: failed to do websocket handshake with the server wss://test.myserver.com:8080

Caused by: 0: operation was canceled 1: connection error 2: peer closed connection without sending TLS close_notify: https://docs.rs/rustls/latest/rustls/manual/_03_howto/index.html#unexpected-eof 2024-09-28T06:27:21.210341Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:27:21.221897Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:27:44.325640Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:27:44.325801Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:27:44.325886Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:27:44.325928Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:27:44.334074Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:27:44.334292Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:27:44.334392Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:27:44.337387Z INFO wstunnel::protocols::tls::server: Doing TLS handshake using SNI DnsName("test.myserver.com") with the server test.myserver.com:8080 2024-09-28T06:27:48.539727Z INFO wstunnel::protocols::tcp::server: Opening TCP connection to test.myserver.com:8080

2024-09-28T06:27:48.540096Z ERROR tunnel{id="01923750-64bb-7800-9d5d-61f24c26a43f" remote="www.google.com:443"}: wstunnel::tunnel::client::client: failed to do websocket handshake with the server wss://test.myserver.com:8080

Caused by: 0: operation was canceled 1: connection error 2: peer closed connection without sending TLS close_notify: https://docs.rs/rustls/latest/rustls/manual/_03_howto/index.html#unexpected-eof

server 2024-09-28T06:19:40.081349Z INFO wstunnel::embedded_certificate: Generating self-signed tls certificate 2024-09-28T06:19:40.084534Z INFO wstunnel: Starting wstunnel server v10.1.1 with config WsServerConfig { socket_so_mark: None, bind: 0.0.0.0:8080, websocket_ping_frequency: Some(30s), timeout_connect: 10s, websocket_mask_frame: false, restriction_config: None, tls: true, mTLS: false } 2024-09-28T06:19:40.084812Z INFO wstunnel::tunnel::server::server: Starting wstunnel server listening on 0.0.0.0:8080 2024-09-28T06:23:22.189371Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:24:44.516838Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:24:44.516872Z INFO tunnel{peer="1.2.3.4:50738"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:24:44.516910Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:24:44.516953Z INFO tunnel{peer="1.2.3.4:50742"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:24:44.517770Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:24:44.517787Z INFO tunnel{peer="1.2.3.4:50758"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:24:44.518122Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:24:44.518209Z INFO tunnel{peer="1.2.3.4:50724"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:24:44.519936Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:24:44.520097Z INFO tunnel{peer="1.2.3.4:50744"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:24:54.526058Z ERROR tunnel{peer="1.2.3.4:50742"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:24:54.526151Z ERROR tunnel{peer="1.2.3.4:50738"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:24:54.526306Z ERROR tunnel{peer="1.2.3.4:50758"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:24:54.527484Z ERROR tunnel{peer="1.2.3.4:50724"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:24:54.531688Z ERROR tunnel{peer="1.2.3.4:50744"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:25:44.512776Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:25:44.512859Z INFO tunnel{peer="1.2.3.4:34250"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:25:44.513256Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:25:44.513321Z INFO tunnel{peer="1.2.3.4:34290"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:25:44.515921Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:25:44.515988Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:25:44.516077Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:25:44.516190Z INFO tunnel{peer="1.2.3.4:34264"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:25:44.516543Z INFO tunnel{peer="1.2.3.4:34272"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:25:44.516886Z INFO tunnel{peer="1.2.3.4:34282"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:25:54.522161Z ERROR tunnel{peer="1.2.3.4:34290"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:25:54.522334Z ERROR tunnel{peer="1.2.3.4:34250"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:25:54.528916Z ERROR tunnel{peer="1.2.3.4:34282"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:25:54.529035Z ERROR tunnel{peer="1.2.3.4:34264"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:25:54.529148Z ERROR tunnel{peer="1.2.3.4:34272"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:26:44.468977Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:26:44.469028Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:26:44.469060Z INFO tunnel{peer="1.2.3.4:52086"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:26:44.469129Z INFO tunnel{peer="1.2.3.4:52078"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:26:44.469376Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:26:44.469756Z INFO tunnel{peer="1.2.3.4:52106"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:26:44.471524Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:26:44.471635Z INFO tunnel{peer="1.2.3.4:52068"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:26:44.471831Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:26:44.472171Z INFO tunnel{peer="1.2.3.4:52090"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:26:54.478147Z ERROR tunnel{peer="1.2.3.4:52086"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:26:54.479335Z ERROR tunnel{peer="1.2.3.4:52106"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:26:54.479444Z ERROR tunnel{peer="1.2.3.4:52078"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:26:54.484615Z ERROR tunnel{peer="1.2.3.4:52090"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:26:54.484717Z ERROR tunnel{peer="1.2.3.4:52068"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:27:11.940691Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:27:11.940768Z INFO tunnel{peer="1.2.3.4:41646"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:27:21.227603Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:27:21.227735Z INFO tunnel{peer="1.2.3.4:40304"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:27:21.952863Z ERROR tunnel{peer="1.2.3.4:41646"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:27:31.239985Z ERROR tunnel{peer="1.2.3.4:40304"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:27:44.339659Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:27:44.339729Z INFO tunnel{peer="1.2.3.4:47974"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:27:44.339985Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:27:44.340046Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:27:44.340074Z INFO tunnel{peer="1.2.3.4:47988"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:27:44.340381Z INFO tunnel{peer="1.2.3.4:47996"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:27:44.342906Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:27:44.343044Z INFO tunnel{peer="1.2.3.4:47984"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:27:48.557172Z INFO wstunnel::tunnel::server::server: Accepting connection 2024-09-28T06:27:48.557291Z INFO tunnel{peer="1.2.3.4:37690"}: wstunnel::tunnel::server::server: Doing TLS handshake 2024-09-28T06:27:54.352412Z ERROR tunnel{peer="1.2.3.4:47988"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:27:54.352502Z ERROR tunnel{peer="1.2.3.4:47974"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:27:54.352591Z ERROR tunnel{peer="1.2.3.4:47996"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:27:54.354742Z ERROR tunnel{peer="1.2.3.4:47984"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout) 2024-09-28T06:27:58.569277Z ERROR tunnel{peer="1.2.3.4:37690"}: wstunnel::tunnel::server::server: Error while upgrading cnx: hyper::Error(HeaderTimeout)

Note: 1.2.3.4 is the IP of test.myserver.com

Desktop (please complete the following information):

OS: Linux ubuntu
Version v10.1.1

Additional context Nothing.

erebe / wstunnel