Skip to content

FTP file upload to MS FTP server fails in libcurl 8.13 #17130

New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom
Closed
rmja opened this issue Apr 22, 2025 · 9 comments
Closed

FTP file upload to MS FTP server fails in libcurl 8.13 #17130

rmja opened this issue Apr 22, 2025 · 9 comments
Labels
FTP

Comments

@rmja
Copy link

rmja commented Apr 22, 2025 •
edited
Loading

I did this

I am using libcurl to upload a file to a Microsoft FTP Server, but the upload fails in the recent libcurl 8.13. This has previously worked fine. The call to perform() finally returns CURL_PARTIAL_FILE as the error after a while.
The file is actually written to the FTP server - but if I add a POSTQUOTE sequence, then it is not executed.

Click here to see the output from the curl trace 
CURLINFO_TEXT: 12.35.46.888 [FTP] [STOP] setup connection -> 0
CURLINFO_TEXT: 12.35.46.891   Trying 11.22.33.44:1080...
CURLINFO_TEXT: 12.35.46.892 [TCP] cf_socket_open() -> 0, fd=1660
CURLINFO_TEXT: 12.35.46.894 [TCP] local address 0.0.0.0 port 54854...
CURLINFO_TEXT: 12.35.46.895 [TCP] adjust_pollset, !connected, POLLOUT fd=1660
CURLINFO_TEXT: 12.35.46.916 [TCP] connected
CURLINFO_TEXT: 12.35.46.918 [TCP] send(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.46.919 [TCP] recv(len=2) -> -1, err=81
CURLINFO_TEXT: 12.35.46.920 [TCP] adjust_pollset, !active, POLLIN fd=1660
CURLINFO_TEXT: 12.35.46.939 [TCP] recv(len=2) -> 2, err=0
CURLINFO_TEXT: 12.35.46.941 [TCP] send(len=43) -> 43, err=0
CURLINFO_TEXT: 12.35.46.943 [TCP] recv(len=2) -> -1, err=81
CURLINFO_TEXT: 12.35.46.944 [TCP] adjust_pollset, !active, POLLIN fd=1660
CURLINFO_TEXT: 12.35.46.964 [TCP] recv(len=2) -> 2, err=0
CURLINFO_TEXT: 12.35.46.968 [TCP] adjust_pollset, !active, POLLIN fd=1660
CURLINFO_TEXT: 12.35.46.970 [TCP] adjust_pollset, !active, POLLIN fd=1660
CURLINFO_TEXT: 12.35.46.973 [TCP] adjust_pollset, !active, POLLIN fd=1660
CURLINFO_TEXT: 12.35.46.979 [TCP] adjust_pollset, !active, POLLIN fd=1660
CURLINFO_TEXT: 12.35.46.994 [TCP] adjust_pollset, !active, POLLIN fd=1660
CURLINFO_TEXT: 12.35.47.025 Host some.ftpserver.com:21 was resolved.
CURLINFO_TEXT: 12.35.47.027 IPv6: (none)
CURLINFO_TEXT: 12.35.47.028 IPv4: 55.66.77.88
CURLINFO_TEXT: 12.35.47.029 SOCKS5 connect to 55.66.77.88:21 (locally resolved)
CURLINFO_TEXT: 12.35.47.031 [TCP] send(len=10) -> 10, err=0
CURLINFO_TEXT: 12.35.47.033 [TCP] recv(len=10) -> -1, err=81
CURLINFO_TEXT: 12.35.47.034 [TCP] adjust_pollset, !active, POLLIN fd=1660
CURLINFO_TEXT: 12.35.47.060 [TCP] recv(len=10) -> 10, err=0
CURLINFO_TEXT: 12.35.47.061 SOCKS5 request granted.
CURLINFO_TEXT: 12.35.47.063 Connected to 11.22.33.44 () port 1080
CURLINFO_TEXT: 12.35.47.065 Connected to 11.22.33.44 (11.22.33.44) port 1080
CURLINFO_TEXT: 12.35.47.066 [FTP] [STOP] -> [WAIT220]
CURLINFO_TEXT: 12.35.47.068 [TCP] recv(len=900) -> 27, err=0
CURLINFO_HEADER_IN: 12.35.47.070 220 Microsoft FTP Service
CURLINFO_TEXT: 12.35.47.072 [TCP] send(len=10) -> 10, err=0
CURLINFO_HEADER_OUT: 12.35.47.074 AUTH SSL
CURLINFO_TEXT: 12.35.47.075 [FTP] [WAIT220] -> [AUTH]
CURLINFO_TEXT: 12.35.47.103 [TCP] recv(len=900) -> 49, err=0
CURLINFO_HEADER_IN: 12.35.47.105 234 AUTH command ok. Expecting TLS Negotiation.
CURLINFO_TEXT: 12.35.47.106 [SSL] added
CURLINFO_TEXT: 12.35.47.108 [SSL] cf_connect()
CURLINFO_TEXT: 12.35.47.110 [SSL] ossl_connect, step1
CURLINFO_TEXT: 12.35.47.111 [SSL] ossl_connect, step2
CURLINFO_TEXT: 12.35.47.113 [TCP] send(len=235) -> 235, err=0
CURLINFO_TEXT: 12.35.47.115 [SSL] ossl_bio_cf_out_write(len=235) -> 235, err=0
CURLINFO_TEXT: 12.35.47.116 TLSv1.2 (OUT), TLS handshake, Client hello (1):
CURLINFO_SSL_DATA_OUT: 12.35.47.118 �CURLINFO_TEXT: 12.35.47.120 [TCP] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.47.122 [SSL] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.47.123 [SSL] ossl_populate_x509_store, path=none, blob=0
CURLINFO_TEXT: 12.35.47.125 [SSL] SSL_connect() -> err=-1, detail=2
CURLINFO_TEXT: 12.35.47.126 [SSL] SSL_connect() -> want recv
CURLINFO_TEXT: 12.35.47.128 [SSL] cf_connect() -> 0, done=0
CURLINFO_TEXT: 12.35.47.130 [SSL] Curl_conn_connect(block=1) -> 0, done=0
CURLINFO_TEXT: 12.35.47.132 [SSL] Curl_conn_connect(block=1), do poll
CURLINFO_TEXT: 12.35.47.134 [SSL] adjust_pollset, POLLIN fd=1660
CURLINFO_TEXT: 12.35.47.143 [SSL] Curl_conn_connect(block=1), Curl_poll() -> 1
CURLINFO_TEXT: 12.35.47.145 [SSL] cf_connect()
CURLINFO_TEXT: 12.35.47.147 [SSL] ossl_connect, step2
CURLINFO_TEXT: 12.35.47.149 [TCP] recv(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.150 [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.152 [TCP] recv(len=3361) -> 3361, err=0
CURLINFO_TEXT: 12.35.47.154 [SSL] ossl_bio_cf_in_read(len=3361) -> 3361, err=0
CURLINFO_TEXT: 12.35.47.156 TLSv1.2 (IN), TLS handshake, Server hello (2):
CURLINFO_SSL_DATA_IN: 12.35.47.157 �CURLINFO_TEXT: 12.35.47.159 TLSv1.2 (IN), TLS handshake, Certificate (11):
CURLINFO_SSL_DATA_IN: 12.35.47.161 �CURLINFO_TEXT: 12.35.47.163 TLSv1.2 (IN), TLS handshake, Server key exchange (12):
CURLINFO_SSL_DATA_IN: 12.35.47.165 CURLINFO_TEXT: 12.35.47.167 TLSv1.2 (IN), TLS handshake, Server finished (14):
CURLINFO_SSL_DATA_IN: 12.35.47.169 �CURLINFO_TEXT: 12.35.47.172 TLSv1.2 (OUT), TLS handshake, Client key exchange (16):
CURLINFO_SSL_DATA_OUT: 12.35.47.174 �CURLINFO_TEXT: 12.35.47.175 TLSv1.2 (OUT), TLS change cipher, Change cipher spec (1):
CURLINFO_SSL_DATA_OUT: 12.35.47.177 �CURLINFO_TEXT: 12.35.47.178 TLSv1.2 (OUT), TLS handshake, Finished (20):
CURLINFO_SSL_DATA_OUT: 12.35.47.180 �CURLINFO_TEXT: 12.35.47.182 [TCP] send(len=93) -> 93, err=0
CURLINFO_TEXT: 12.35.47.183 [SSL] ossl_bio_cf_out_write(len=93) -> 93, err=0
CURLINFO_TEXT: 12.35.47.185 [TCP] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.47.187 [SSL] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.47.188 [SSL] SSL_connect() -> err=-1, detail=2
CURLINFO_TEXT: 12.35.47.189 [SSL] SSL_connect() -> want recv
CURLINFO_TEXT: 12.35.47.191 [SSL] cf_connect() -> 0, done=0
CURLINFO_TEXT: 12.35.47.192 [SSL] Curl_conn_connect(block=1) -> 0, done=0
CURLINFO_TEXT: 12.35.47.193 [SSL] Curl_conn_connect(block=1), do poll
CURLINFO_TEXT: 12.35.47.195 [SSL] adjust_pollset, POLLIN fd=1660
CURLINFO_TEXT: 12.35.47.211 [SSL] Curl_conn_connect(block=1), Curl_poll() -> 1
CURLINFO_TEXT: 12.35.47.213 [SSL] cf_connect()
CURLINFO_TEXT: 12.35.47.215 [SSL] ossl_connect, step2
CURLINFO_TEXT: 12.35.47.216 [TCP] recv(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.218 [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.219 [TCP] recv(len=1) -> 1, err=0
CURLINFO_TEXT: 12.35.47.221 [SSL] ossl_bio_cf_in_read(len=1) -> 1, err=0
CURLINFO_TEXT: 12.35.47.222 TLSv1.2 (IN), TLS change cipher, Change cipher spec (1):
CURLINFO_SSL_DATA_IN: 12.35.47.224 �CURLINFO_TEXT: 12.35.47.226 [TCP] recv(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.227 [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.230 [TCP] recv(len=40) -> 40, err=0
CURLINFO_TEXT: 12.35.47.232 [SSL] ossl_bio_cf_in_read(len=40) -> 40, err=0
CURLINFO_TEXT: 12.35.47.233 TLSv1.2 (IN), TLS handshake, Finished (20):
CURLINFO_SSL_DATA_IN: 12.35.47.235 �CURLINFO_TEXT: 12.35.47.237 SSL connection using TLSv1.2 / ECDHE-RSA-AES256-GCM-SHA384 / [blank] / UNDEF
CURLINFO_TEXT: 12.35.47.239 [SSL] ossl_connect, step3
CURLINFO_TEXT: 12.35.47.240 Server certificate:
CURLINFO_TEXT: 12.35.47.242  subject: CN=some.ftpserver.com
CURLINFO_TEXT: 12.35.47.243  start date: Oct  3 08:42:13 2024 GMT
CURLINFO_TEXT: 12.35.47.245  expire date: Oct 13 12:32:19 2025 GMT
CURLINFO_TEXT: 12.35.47.246  subjectAltName: host "some.ftpserver.com" matched cert's "some.ftpserver.com"
CURLINFO_TEXT: 12.35.47.273  issuer: C=US; ST=Arizona; L=Scottsdale; O=GoDaddy.com, Inc.; OU=http://certs.godaddy.com/repository/; CN=Go Daddy Secure Certificate Authority - G2
CURLINFO_TEXT: 12.35.47.303  SSL certificate verify result: unable to get local issuer certificate (20), continuing anyway.
CURLINFO_TEXT: 12.35.47.305   Certificate level 0: Public key type ? (2048/112 Bits/secBits), signed using sha256WithRSAEncryption
CURLINFO_TEXT: 12.35.47.306   Certificate level 1: Public key type ? (2048/112 Bits/secBits), signed using sha256WithRSAEncryption
CURLINFO_TEXT: 12.35.47.308 [SSL] ossl_connect, done
CURLINFO_TEXT: 12.35.47.310 [SSL] cf_connect() -> 0, done=1
CURLINFO_TEXT: 12.35.47.312 [SSL] Curl_conn_connect(block=1) -> 0, done=1
CURLINFO_TEXT: 12.35.47.313 Connected to 11.22.33.44 (11.22.33.44) port 1080
CURLINFO_TEXT: 12.35.47.315 [TCP] send(len=48) -> 48, err=0
CURLINFO_TEXT: 12.35.47.317 [SSL] ossl_bio_cf_out_write(len=48) -> 48, err=0
CURLINFO_HEADER_OUT: 12.35.47.319 USER username
CURLINFO_TEXT: 12.35.47.321 [FTP] [AUTH] -> [USER]
CURLINFO_TEXT: 12.35.47.345 [TCP] recv(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.347 [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.349 [TCP] recv(len=47) -> 47, err=0
CURLINFO_TEXT: 12.35.47.351 [SSL] ossl_bio_cf_in_read(len=47) -> 47, err=0
CURLINFO_TEXT: 12.35.47.352 [SSL] cf_recv(len=900) -> 23, 0
CURLINFO_HEADER_IN: 12.35.47.355 331 Password required
CURLINFO_TEXT: 12.35.47.357 [TCP] send(len=47) -> 47, err=0
CURLINFO_TEXT: 12.35.47.360 [SSL] ossl_bio_cf_out_write(len=47) -> 47, err=0
CURLINFO_HEADER_OUT: 12.35.47.362 PASS password
CURLINFO_TEXT: 12.35.47.365 [FTP] [USER] -> [PASS]
CURLINFO_TEXT: 12.35.47.399 [TCP] recv(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.401 [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.403 [TCP] recv(len=45) -> 45, err=0
CURLINFO_TEXT: 12.35.47.405 [SSL] ossl_bio_cf_in_read(len=45) -> 45, err=0
CURLINFO_TEXT: 12.35.47.406 [SSL] cf_recv(len=900) -> 21, 0
CURLINFO_HEADER_IN: 12.35.47.408 230 User logged in.
CURLINFO_TEXT: 12.35.47.410 [TCP] send(len=37) -> 37, err=0
CURLINFO_TEXT: 12.35.47.411 [SSL] ossl_bio_cf_out_write(len=37) -> 37, err=0
CURLINFO_HEADER_OUT: 12.35.47.413 PBSZ 0
CURLINFO_TEXT: 12.35.47.414 [FTP] [PASS] -> [PBSZ]
CURLINFO_TEXT: 12.35.47.438 [TCP] recv(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.441 [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.443 [TCP] recv(len=54) -> 54, err=0
CURLINFO_TEXT: 12.35.47.445 [SSL] ossl_bio_cf_in_read(len=54) -> 54, err=0
CURLINFO_TEXT: 12.35.47.446 [SSL] cf_recv(len=900) -> 30, 0
CURLINFO_HEADER_IN: 12.35.47.448 200 PBSZ command successful.
CURLINFO_TEXT: 12.35.47.450 [TCP] send(len=37) -> 37, err=0
CURLINFO_TEXT: 12.35.47.452 [SSL] ossl_bio_cf_out_write(len=37) -> 37, err=0
CURLINFO_HEADER_OUT: 12.35.47.454 PROT P
CURLINFO_TEXT: 12.35.47.455 [FTP] [PBSZ] -> [PROT]
CURLINFO_TEXT: 12.35.47.478 [TCP] recv(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.480 [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.482 [TCP] recv(len=54) -> 54, err=0
CURLINFO_TEXT: 12.35.47.484 [SSL] ossl_bio_cf_in_read(len=54) -> 54, err=0
CURLINFO_TEXT: 12.35.47.486 [SSL] cf_recv(len=900) -> 30, 0
CURLINFO_HEADER_IN: 12.35.47.488 200 PROT command successful.
CURLINFO_TEXT: 12.35.47.489 [TCP] send(len=34) -> 34, err=0
CURLINFO_TEXT: 12.35.47.491 [SSL] ossl_bio_cf_out_write(len=34) -> 34, err=0
CURLINFO_HEADER_OUT: 12.35.47.492 PWD
CURLINFO_TEXT: 12.35.47.494 [FTP] [PROT] -> [PWD]
CURLINFO_TEXT: 12.35.47.517 [TCP] recv(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.519 [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.521 [TCP] recv(len=55) -> 55, err=0
CURLINFO_TEXT: 12.35.47.522 [SSL] ossl_bio_cf_in_read(len=55) -> 55, err=0
CURLINFO_TEXT: 12.35.47.524 [SSL] cf_recv(len=900) -> 31, 0
CURLINFO_HEADER_IN: 12.35.47.525 257 "/" is current directory.
CURLINFO_TEXT: 12.35.47.527 Entry path is '/'
CURLINFO_TEXT: 12.35.47.528 [FTP] [PWD] -> [STOP]
CURLINFO_TEXT: 12.35.47.530 [FTP] [STOP] protocol connect phase DONE
CURLINFO_TEXT: 12.35.47.532 Request has same path as previous transfer
CURLINFO_TEXT: 12.35.47.533 [FTP] [STOP] DO phase starts
CURLINFO_TEXT: 12.35.47.535 [TCP] send(len=35) -> 35, err=0
CURLINFO_TEXT: 12.35.47.537 [SSL] ossl_bio_cf_out_write(len=35) -> 35, err=0
CURLINFO_HEADER_OUT: 12.35.47.538 EPSV
CURLINFO_TEXT: 12.35.47.540 [FTP] [STOP] -> [PASV]
CURLINFO_TEXT: 12.35.47.541 Connect data stream passively
CURLINFO_TEXT: 12.35.47.542 [FTP] [PASV] perform, awaiting DATA connect
CURLINFO_TEXT: 12.35.47.564 [TCP] recv(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.566 [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.568 [TCP] recv(len=72) -> 72, err=0
CURLINFO_TEXT: 12.35.47.571 [SSL] ossl_bio_cf_in_read(len=72) -> 72, err=0
CURLINFO_TEXT: 12.35.47.573 [SSL] cf_recv(len=900) -> 48, 0
CURLINFO_HEADER_IN: 12.35.47.575 229 Entering Extended Passive Mode (|||16766|)
CURLINFO_TEXT: 12.35.47.577 Hostname 11.22.33.44 was found in DNS cache
CURLINFO_TEXT: 12.35.47.579 Connecting to some.ftpserver.com (11.22.33.44) port 1080
CURLINFO_TEXT: 12.35.47.581 [FTP] [PASV] -> [STOP]
CURLINFO_TEXT: 12.35.47.583 [FTP] [STOP] DO phase is complete2
CURLINFO_TEXT: 12.35.47.586   Trying 11.22.33.44:1080...
CURLINFO_TEXT: 12.35.47.588 [TCP-1] cf_socket_open() -> 0, fd=1684
CURLINFO_TEXT: 12.35.47.591 [TCP-1] local address 0.0.0.0 port 54857...
CURLINFO_TEXT: 12.35.47.593 [FTP] [STOP] ftp_domore_getsock()
CURLINFO_TEXT: 12.35.47.596 [TCP-1] adjust_pollset, !connected, POLLOUT fd=1684
CURLINFO_TEXT: 12.35.47.613 [TCP-1] connected
CURLINFO_TEXT: 12.35.47.615 [TCP-1] send(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.617 [TCP-1] recv(len=2) -> -1, err=81
CURLINFO_TEXT: 12.35.47.619 [FTP] [STOP] ftp_domore_getsock()
CURLINFO_TEXT: 12.35.47.621 [TCP-1] adjust_pollset, !active, POLLIN fd=1684
CURLINFO_TEXT: 12.35.47.636 [TCP-1] recv(len=2) -> 2, err=0
CURLINFO_TEXT: 12.35.47.638 [TCP-1] send(len=43) -> 43, err=0
CURLINFO_TEXT: 12.35.47.644 [TCP-1] recv(len=2) -> -1, err=81
CURLINFO_TEXT: 12.35.47.647 [FTP] [STOP] ftp_domore_getsock()
CURLINFO_TEXT: 12.35.47.649 [TCP-1] adjust_pollset, !active, POLLIN fd=1684
CURLINFO_TEXT: 12.35.47.663 [TCP-1] recv(len=2) -> 2, err=0
CURLINFO_TEXT: 12.35.47.667 [FTP] [STOP] ftp_domore_getsock()
CURLINFO_TEXT: 12.35.47.669 [TCP-1] adjust_pollset, !active, POLLIN fd=1684
CURLINFO_TEXT: 12.35.47.703 Host some.ftpserver.com:16766 was resolved.
CURLINFO_TEXT: 12.35.47.705 IPv6: (none)
CURLINFO_TEXT: 12.35.47.707 IPv4: 55.66.77.88
CURLINFO_TEXT: 12.35.47.712 SOCKS5 connect to 55.66.77.88:16766 (locally resolved)
CURLINFO_TEXT: 12.35.47.714 [TCP-1] send(len=10) -> 10, err=0
CURLINFO_TEXT: 12.35.47.717 [TCP-1] recv(len=10) -> -1, err=81
CURLINFO_TEXT: 12.35.47.719 [FTP] [STOP] ftp_domore_getsock()
CURLINFO_TEXT: 12.35.47.721 [TCP-1] adjust_pollset, !active, POLLIN fd=1684
CURLINFO_TEXT: 12.35.47.745 [TCP-1] recv(len=10) -> 10, err=0
CURLINFO_TEXT: 12.35.47.747 SOCKS5 request granted.
CURLINFO_TEXT: 12.35.47.749 Connected 2nd connection to  port 0
CURLINFO_TEXT: 12.35.47.751 [SSL-1] cf_connect()
CURLINFO_TEXT: 12.35.47.754 [SSL-1] ossl_connect, step1
CURLINFO_TEXT: 12.35.47.756 SSL reusing session with ALPN '-'
CURLINFO_TEXT: 12.35.47.758 [SSL-1] ossl_connect, step2
CURLINFO_TEXT: 12.35.47.760 [TCP-1] send(len=267) -> 267, err=0
CURLINFO_TEXT: 12.35.47.762 [SSL-1] ossl_bio_cf_out_write(len=267) -> 267, err=0
CURLINFO_TEXT: 12.35.47.764 TLSv1.2 (OUT), TLS handshake, Client hello (1):
CURLINFO_SSL_DATA_OUT: 12.35.47.766 �CURLINFO_TEXT: 12.35.47.768 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.47.770 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.47.772 [SSL-1] ossl_populate_x509_store, path=none, blob=0
CURLINFO_TEXT: 12.35.47.773 [SSL-1] SSL_connect() -> err=-1, detail=2
CURLINFO_TEXT: 12.35.47.775 [SSL-1] SSL_connect() -> want recv
CURLINFO_TEXT: 12.35.47.776 [SSL-1] cf_connect() -> 0, done=0
CURLINFO_TEXT: 12.35.47.778 [TCP] send(len=37) -> 37, err=0
CURLINFO_TEXT: 12.35.47.780 [SSL] ossl_bio_cf_out_write(len=37) -> 37, err=0
CURLINFO_HEADER_OUT: 12.35.47.782 TYPE I
CURLINFO_TEXT: 12.35.47.784 [FTP] [STOP] -> [STOR_TYPE]
CURLINFO_TEXT: 12.35.47.785 [FTP] [STOR_TYPE] ftp_domore_getsock()
CURLINFO_TEXT: 12.35.47.787 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.35.47.789 [TCP-1] adjust_pollset, !active, POLLIN fd=1684
CURLINFO_TEXT: 12.35.47.807 [SSL-1] cf_connect()
CURLINFO_TEXT: 12.35.47.809 [SSL-1] ossl_connect, step2
CURLINFO_TEXT: 12.35.47.811 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.47.813 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.47.814 [SSL-1] SSL_connect() -> err=-1, detail=2
CURLINFO_TEXT: 12.35.47.816 [SSL-1] SSL_connect() -> want recv
CURLINFO_TEXT: 12.35.47.817 [SSL-1] cf_connect() -> 0, done=0
CURLINFO_TEXT: 12.35.47.819 [TCP] recv(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.820 [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.822 [TCP] recv(len=44) -> 44, err=0
CURLINFO_TEXT: 12.35.47.823 [SSL] ossl_bio_cf_in_read(len=44) -> 44, err=0
CURLINFO_TEXT: 12.35.47.824 [SSL] cf_recv(len=900) -> 20, 0
CURLINFO_HEADER_IN: 12.35.47.826 200 Type set to I.
CURLINFO_TEXT: 12.35.47.827 [TCP] send(len=96) -> 96, err=0
CURLINFO_TEXT: 12.35.47.829 [SSL] ossl_bio_cf_out_write(len=96) -> 96, err=0
CURLINFO_HEADER_OUT: 12.35.47.830 STOR upload-testfile-da6365b9-6177-41dd-9a5f-eb82066f7f5b.txt.tmp
CURLINFO_TEXT: 12.35.47.832 [FTP] [STOR_TYPE] -> [STOR]
CURLINFO_TEXT: 12.35.47.833 [FTP] [STOR] ftp_domore_getsock()
CURLINFO_TEXT: 12.35.47.834 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.35.47.836 [TCP-1] adjust_pollset, !active, POLLIN fd=1684
CURLINFO_TEXT: 12.35.47.872 [SSL-1] cf_connect()
CURLINFO_TEXT: 12.35.47.873 [SSL-1] ossl_connect, step2
CURLINFO_TEXT: 12.35.47.875 [TCP-1] recv(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.877 [SSL-1] ossl_bio_cf_in_read(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.878 [TCP-1] recv(len=3361) -> 3361, err=0
CURLINFO_TEXT: 12.35.47.880 [SSL-1] ossl_bio_cf_in_read(len=3361) -> 3361, err=0
CURLINFO_TEXT: 12.35.47.882 TLSv1.2 (IN), TLS handshake, Server hello (2):
CURLINFO_SSL_DATA_IN: 12.35.47.883 �CURLINFO_TEXT: 12.35.47.885 TLSv1.2 (IN), TLS handshake, Certificate (11):
CURLINFO_SSL_DATA_IN: 12.35.47.886 �CURLINFO_TEXT: 12.35.47.889 TLSv1.2 (IN), TLS handshake, Server key exchange (12):
CURLINFO_SSL_DATA_IN: 12.35.47.890 CURLINFO_TEXT: 12.35.47.892 TLSv1.2 (IN), TLS handshake, Server finished (14):
CURLINFO_SSL_DATA_IN: 12.35.47.893 �CURLINFO_TEXT: 12.35.47.895 TLSv1.2 (OUT), TLS handshake, Client key exchange (16):
CURLINFO_SSL_DATA_OUT: 12.35.47.897 �CURLINFO_TEXT: 12.35.47.898 TLSv1.2 (OUT), TLS change cipher, Change cipher spec (1):
CURLINFO_SSL_DATA_OUT: 12.35.47.899 �CURLINFO_TEXT: 12.35.47.901 TLSv1.2 (OUT), TLS handshake, Finished (20):
CURLINFO_SSL_DATA_OUT: 12.35.47.932 �CURLINFO_TEXT: 12.35.47.934 [TCP-1] send(len=93) -> 93, err=0
CURLINFO_TEXT: 12.35.47.936 [SSL-1] ossl_bio_cf_out_write(len=93) -> 93, err=0
CURLINFO_TEXT: 12.35.47.937 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.47.938 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.47.939 [SSL-1] SSL_connect() -> err=-1, detail=2
CURLINFO_TEXT: 12.35.47.940 [SSL-1] SSL_connect() -> want recv
CURLINFO_TEXT: 12.35.47.942 [SSL-1] cf_connect() -> 0, done=0
CURLINFO_TEXT: 12.35.47.943 [TCP] recv(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.944 [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.945 [TCP] recv(len=78) -> 78, err=0
CURLINFO_TEXT: 12.35.47.946 [SSL] ossl_bio_cf_in_read(len=78) -> 78, err=0
CURLINFO_TEXT: 12.35.47.948 [SSL] cf_recv(len=900) -> 54, 0
CURLINFO_HEADER_IN: 12.35.47.949 125 Data connection already open; Transfer starting.
CURLINFO_TEXT: 12.35.47.951 [FTP] InitiateTransfer()
CURLINFO_TEXT: 12.35.47.952 [SSL-1] cf_connect()
CURLINFO_TEXT: 12.35.47.953 [SSL-1] ossl_connect, step2
CURLINFO_TEXT: 12.35.47.954 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.47.955 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.47.957 [SSL-1] SSL_connect() -> err=-1, detail=2
CURLINFO_TEXT: 12.35.47.958 [SSL-1] SSL_connect() -> want recv
CURLINFO_TEXT: 12.35.47.959 [SSL-1] cf_connect() -> 0, done=0
CURLINFO_TEXT: 12.35.47.960 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.35.47.961 [TCP-1] adjust_pollset, !active, POLLIN fd=1684
CURLINFO_TEXT: 12.35.47.964 [SSL-1] cf_connect()
CURLINFO_TEXT: 12.35.47.965 [SSL-1] ossl_connect, step2
CURLINFO_TEXT: 12.35.47.966 [TCP-1] recv(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.968 [SSL-1] ossl_bio_cf_in_read(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.969 [TCP-1] recv(len=1) -> 1, err=0
CURLINFO_TEXT: 12.35.47.970 [SSL-1] ossl_bio_cf_in_read(len=1) -> 1, err=0
CURLINFO_TEXT: 12.35.47.972 TLSv1.2 (IN), TLS change cipher, Change cipher spec (1):
CURLINFO_SSL_DATA_IN: 12.35.47.973 �CURLINFO_TEXT: 12.35.47.975 [TCP-1] recv(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.976 [SSL-1] ossl_bio_cf_in_read(len=5) -> 5, err=0
CURLINFO_TEXT: 12.35.47.977 [TCP-1] recv(len=40) -> 40, err=0
CURLINFO_TEXT: 12.35.47.978 [SSL-1] ossl_bio_cf_in_read(len=40) -> 40, err=0
CURLINFO_TEXT: 12.35.47.980 TLSv1.2 (IN), TLS handshake, Finished (20):
CURLINFO_SSL_DATA_IN: 12.35.47.981 �CURLINFO_TEXT: 12.35.47.983 SSL connection using TLSv1.2 / ECDHE-RSA-AES256-GCM-SHA384 / [blank] / UNDEF
CURLINFO_TEXT: 12.35.47.984 [SSL-1] ossl_connect, step3
CURLINFO_TEXT: 12.35.47.986 Server certificate:
CURLINFO_TEXT: 12.35.47.987  subject: CN=some.ftpserver.com
CURLINFO_TEXT: 12.35.47.988  start date: Oct  3 08:42:13 2024 GMT
CURLINFO_TEXT: 12.35.47.989  expire date: Oct 13 12:32:19 2025 GMT
CURLINFO_TEXT: 12.35.47.991  subjectAltName: host "some.ftpserver.com" matched cert's "some.ftpserver.com"
CURLINFO_TEXT: 12.35.47.992  issuer: C=US; ST=Arizona; L=Scottsdale; O=GoDaddy.com, Inc.; OU=http://certs.godaddy.com/repository/; CN=Go Daddy Secure Certificate Authority - G2
CURLINFO_TEXT: 12.35.47.994  SSL certificate verify result: unable to get local issuer certificate (20), continuing anyway.
CURLINFO_TEXT: 12.35.47.995   Certificate level 0: Public key type ? (2048/112 Bits/secBits), signed using sha256WithRSAEncryption
CURLINFO_TEXT: 12.35.47.997   Certificate level 1: Public key type ? (2048/112 Bits/secBits), signed using sha256WithRSAEncryption
CURLINFO_TEXT: 12.35.47.998 [SSL-1] ossl_connect, done
CURLINFO_TEXT: 12.35.48.000 [SSL-1] cf_connect() -> 0, done=1
CURLINFO_TEXT: 12.35.48.001 Connected 2nd connection to 11.22.33.44 port 1080
CURLINFO_TEXT: 12.35.48.003 [FTP] [STOR] -> [STOP]
CURLINFO_TEXT: 12.35.48.005 [TCP-1] send(len=95) -> 95, err=0
CURLINFO_TEXT: 12.35.48.006 [SSL-1] ossl_bio_cf_out_write(len=95) -> 95, err=0
CURLINFO_DATA_OUT: 12.35.48.007 This is a testfile created by the Utiliread integration test suiteCURLINFO_TEXT: 12.35.48.009 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.48.010 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.48.012 [SSL-1] SSL shutdown not sent, read -> -1
CURLINFO_TEXT: 12.35.48.014 [SSL-1] send SSL close notify
CURLINFO_TEXT: 12.35.48.016 [TCP-1] send(len=31) -> 31, err=0
CURLINFO_TEXT: 12.35.48.017 [SSL-1] ossl_bio_cf_out_write(len=31) -> 31, err=0
CURLINFO_TEXT: 12.35.48.019 TLSv1.2 (OUT), TLS alert, close notify (256):
CURLINFO_SSL_DATA_OUT: 12.35.48.020 �CURLINFO_TEXT: 12.35.48.021 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.48.022 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.48.023 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.35.48.025 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.35.48.026 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.35.48.028 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.35.48.029 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.35.49.043 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.49.046 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.49.048 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.35.49.050 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.35.49.052 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.35.49.054 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.35.49.056 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.35.50.064 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.50.066 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.50.069 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.35.50.071 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.35.50.072 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.35.50.074 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.35.50.075 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.35.51.088 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.51.090 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.51.092 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.35.51.093 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.35.51.095 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.35.51.096 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.35.51.097 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.35.52.111 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.52.115 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.52.116 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.35.52.117 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.35.52.118 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.35.52.119 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.35.52.120 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.35.53.133 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.53.134 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.53.136 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.35.53.137 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.35.53.139 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.35.53.140 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.35.53.141 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.35.54.149 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.54.151 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.54.152 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.35.54.153 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.35.54.155 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.35.54.156 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.35.54.157 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.35.55.162 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.55.164 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.55.165 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.35.55.167 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.35.55.168 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.35.55.169 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.35.55.170 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.35.56.172 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.56.174 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.56.176 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.35.56.177 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.35.56.178 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.35.56.179 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.35.56.180 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.35.57.185 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.57.189 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.57.190 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.35.57.192 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.35.57.193 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.35.57.194 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.35.57.195 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.35.58.209 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.58.210 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.58.211 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.35.58.213 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.35.58.214 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.35.58.215 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.35.58.216 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.35.59.228 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.59.230 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.35.59.231 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.35.59.232 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.35.59.233 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.35.59.235 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.35.59.236 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.00.247 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.00.248 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.00.250 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.00.251 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.00.253 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.00.254 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.00.255 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.01.271 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.01.273 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.01.274 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.01.275 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.01.277 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.01.278 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.01.279 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.02.282 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.02.283 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.02.285 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.02.286 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.02.287 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.02.288 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.02.290 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.03.295 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.03.297 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.03.299 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.03.300 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.03.301 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.03.303 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.03.304 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.04.317 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.04.320 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.04.321 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.04.322 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.04.323 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.04.324 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.04.325 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.05.342 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.05.343 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.05.344 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.05.345 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.05.346 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.05.347 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.05.348 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.06.364 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.06.366 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.06.368 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.06.369 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.06.371 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.06.372 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.06.373 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.07.377 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.07.379 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.07.380 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.07.382 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.07.383 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.07.384 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.07.385 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.08.396 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.08.397 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.08.399 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.08.400 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.08.401 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.08.402 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.08.404 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.09.419 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.09.421 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.09.422 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.09.424 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.09.425 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.09.426 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.09.427 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.10.431 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.10.432 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.10.433 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.10.435 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.10.436 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.10.437 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.10.438 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.11.449 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.11.450 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.11.452 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.11.454 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.11.455 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.11.457 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.11.458 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.12.467 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.12.469 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.12.470 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.12.471 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.12.472 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.12.473 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.12.474 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.13.488 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.13.490 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.13.491 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.13.492 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.13.494 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.13.495 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.13.496 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.14.499 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.14.501 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.14.502 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.14.504 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.14.505 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.14.506 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.14.508 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.15.521 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.15.523 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.15.524 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.15.525 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.15.527 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.15.528 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.15.529 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.16.541 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.16.543 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.16.544 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.16.545 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.16.547 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.16.548 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.16.550 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.17.563 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.17.565 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.17.566 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.17.568 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.17.569 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.17.570 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.17.572 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.18.586 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.18.588 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.18.589 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.18.590 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.18.592 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.18.593 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.18.594 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.19.607 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.19.609 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.19.610 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.19.611 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.19.611 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.19.612 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.19.613 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.20.619 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.20.621 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.20.622 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.20.623 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.20.624 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.20.626 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.20.627 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.21.629 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.21.631 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.21.632 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.21.633 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.21.634 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.21.635 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.21.636 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.22.651 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.22.652 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.22.654 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.22.655 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.22.656 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.22.657 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.22.658 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.23.663 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.23.665 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.23.666 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.23.667 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.23.668 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.23.669 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.23.670 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.24.686 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.24.688 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.24.689 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.24.691 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.24.692 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.24.693 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.24.694 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.25.709 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.25.712 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.25.713 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.25.715 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.25.716 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.25.717 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.25.718 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.26.731 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.26.732 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.26.733 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.26.735 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.26.736 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.26.737 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.26.739 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.27.742 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.27.744 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.27.746 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.27.747 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.27.749 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.27.750 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.27.752 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.28.754 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.28.756 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.28.757 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.28.759 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.28.760 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.28.761 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.28.763 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.29.765 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.29.767 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.29.768 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.29.769 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.29.771 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.29.772 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.29.773 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.30.783 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.30.785 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.30.786 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.30.787 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.30.788 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.30.789 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.30.791 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.31.794 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.31.796 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.31.797 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.31.798 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.31.800 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.31.801 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.31.802 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.32.817 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.32.818 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.32.819 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.32.821 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.32.822 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.32.823 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.32.824 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.33.839 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.33.840 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.33.841 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.33.842 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.33.843 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.33.844 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.33.845 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.34.846 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.34.848 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.34.849 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.34.850 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.34.851 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.34.852 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.34.853 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.35.856 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.35.857 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.35.859 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.35.860 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.35.861 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.35.862 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.35.863 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.36.866 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.36.868 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.36.869 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.36.870 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.36.871 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.36.872 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.36.873 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.37.882 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.37.883 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.37.885 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.37.886 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.37.887 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.37.888 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.37.889 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.38.903 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.38.907 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.38.908 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.38.910 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.38.911 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.38.912 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.38.913 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.39.929 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.39.931 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.39.932 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.39.934 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.39.935 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.39.936 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.39.938 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.40.943 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.40.945 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.40.947 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.40.948 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.40.950 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.40.951 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.40.952 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.41.969 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.41.970 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.41.971 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.41.973 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.41.974 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.41.975 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.41.976 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.42.983 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.42.984 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.42.986 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.42.988 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.42.989 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.42.991 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.42.992 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.44.008 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.44.010 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.44.011 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.44.012 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.44.014 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.44.015 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.44.016 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.45.031 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.45.032 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.45.034 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.45.035 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.45.036 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.45.037 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.45.038 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.46.041 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.46.042 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.46.044 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.46.045 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.46.046 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.46.048 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.46.049 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.47.059 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.47.060 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.47.062 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.47.063 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.47.065 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.47.066 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.47.068 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.48.082 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.48.085 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.48.086 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.48.088 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.48.090 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.48.092 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.48.094 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.49.105 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.49.107 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.49.109 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.49.111 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.49.112 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.49.114 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.49.115 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.50.128 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.50.130 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.50.132 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.50.133 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.50.134 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.50.135 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.50.136 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.51.139 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.51.141 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.51.142 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.51.144 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.51.145 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.51.146 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.51.147 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.52.163 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.52.164 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.52.165 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.52.167 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.52.168 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.52.169 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.52.170 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.53.185 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.53.187 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.53.188 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.53.189 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.53.190 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.53.191 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.53.192 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.54.208 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.54.210 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.54.211 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.54.213 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.54.214 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.54.215 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.54.216 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.55.219 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.55.221 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.55.222 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.55.223 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.55.224 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.55.226 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.55.227 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.56.236 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.56.237 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.56.239 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.56.240 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.56.242 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.56.243 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.56.244 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.57.250 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.57.252 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.57.253 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.57.254 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.57.255 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.57.257 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.57.258 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.58.266 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.58.268 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.58.270 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.58.271 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.58.273 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.58.274 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.58.275 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.36.59.286 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.59.288 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.36.59.289 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.36.59.290 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.36.59.294 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.36.59.296 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.36.59.297 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.37.00.301 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.37.00.303 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.37.00.305 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.37.00.306 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.37.00.307 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.37.00.309 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.37.00.310 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.37.01.326 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.37.01.327 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.37.01.329 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.37.01.330 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.37.01.331 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.37.01.332 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.37.01.333 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.37.02.337 [TCP-1] recv(len=5) -> -1, err=81
CURLINFO_TEXT: 12.37.02.339 [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
CURLINFO_TEXT: 12.37.02.340 [SSL-1] SSL shutdown read -> -1
CURLINFO_TEXT: 12.37.02.341 [SSL-1] SSL shutdown sent, want receive
CURLINFO_TEXT: 12.37.02.343 [SSL-1] cf_shutdown -> 0, done=0
CURLINFO_TEXT: 12.37.02.344 [SSL-1] shut down not done yet
CURLINFO_TEXT: 12.37.02.346 [SSL-1] adjust_pollset, POLLIN fd=1684
CURLINFO_TEXT: 12.37.02.573 [TCP-1] recv(len=5) -> 0, err=0
CURLINFO_TEXT: 12.37.02.575 [SSL-1] ossl_bio_cf_in_read(len=5) -> 0, err=0
CURLINFO_TEXT: 12.37.02.577 [SSL-1] SSL shutdown read -> 0
CURLINFO_TEXT: 12.37.02.579 [SSL-1] SSL shutdown, ignore recv error: 'SSL_ERROR_SYSCALL', errno 0
CURLINFO_TEXT: 12.37.02.581 [SSL-1] cf_shutdown -> 0, done=1
CURLINFO_TEXT: 12.37.02.583 [SSL-1] shut down successfully
CURLINFO_TEXT: 12.37.02.584 [TCP-1] cf_socket_shutdown, fd=1684
CURLINFO_TEXT: 12.37.02.586 [TCP-1] shut down successfully
CURLINFO_TEXT: 12.37.02.587 upload completely sent off: 66 bytes
CURLINFO_TEXT: 12.37.02.589 Remembering we are in dir ""
CURLINFO_TEXT: 12.37.02.590 [FTP] [STOP] closing DATA connection
CURLINFO_TEXT: 12.37.02.591 [TCP-1] cf_socket_close, fd=1684
CURLINFO_TEXT: 12.37.02.593 [TCP-1] destroy
CURLINFO_TEXT: 12.37.02.595 [FTP] getFTPResponse start
CURLINFO_TEXT: 12.37.02.596 [TCP] recv(len=5) -> 5, err=0
CURLINFO_TEXT: 12.37.02.597 [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
CURLINFO_TEXT: 12.37.02.598 [TCP] recv(len=106) -> 106, err=0
CURLINFO_TEXT: 12.37.02.599 [SSL] ossl_bio_cf_in_read(len=106) -> 106, err=0
CURLINFO_TEXT: 12.37.02.600 [SSL] cf_recv(len=900) -> 82, 0
CURLINFO_HEADER_IN: 12.37.02.601 425 Data channel timed out due to not meeting the minimum bandwidth requirement.
CURLINFO_TEXT: 12.37.02.603 [FTP] getFTPResponse -> result=0, nread=82, ftpcode=425
CURLINFO_TEXT: 12.37.02.604 server did not report OK, got 425
CURLINFO_TEXT: 12.37.02.605 [FTP] [STOP] done, result=18
CURLINFO_TEXT: 12.37.02.606 Connection #0 to host 11.22.33.44 left intact

I expected the following

I expect CURL_OK=0 to be returned from perform() and if a POSTQUOTE is included in the perform operation, I expect it to be executed.

curl/libcurl version

libcurl/8.13.0 LibreSSL/4.0.0 zlib/1.3.1 brotli/1.1.0 zstd/1.5.7 WinIDN libpsl/0.21.5 libssh2/1.11.1 nghttp2/1.65.0 ngtcp2/1.12.0 nghttp3/1.9.0

operating system

Windows 11
@icing
Copy link
Contributor

icing commented Apr 22, 2025

Can you produce a trace of such a failed upload with curl -vvvv? That would help us analyse this. Thanks.

@rmja
Copy link
Author

rmja commented Apr 22, 2025 •
edited
Loading

Yes, I am able to reproduce it with a simple curl binary call:

.\curl.exe -vvvv --upload-file .\testfile.txt --proxy socks5://proxyuser:proxypass@proxyip:1080 ftp://ftpuser:ftppass@ftp.server.com/testfile.txt --ftp-ssl

The file is written to the server (a very short textfile, so dont know if it holds for larger files), but it seems as if curl still fails.

See the following output for the details 
PS C:\Users\rmja\Downloads\curl-8.13.0_2-win64-mingw\curl-8.13.0_2-win64-mingw\bin> .\curl.exe -vvvv --upload-file .\testfile.txt --proxy socks5://proxyuser:proxypass@proxyip:1080 ftp://ftpuser:ftppass@ftp.server.com/testfile.txt --ftp-ssl
Warning: --ftp-ssl is an insecure option, consider --ssl-reqd instead
Note: Using embedded CA bundle, for proxies (233263 bytes)
14:07:43.232000 [0-x] == Info: [MULTI] [INIT] added, transfers=1
14:07:43.237000 [0-x] == Info: [MULTI] [INIT] multi_perform(running=1)
14:07:43.242000 [0-x] == Info: [MULTI] [INIT] -> [SETUP]
14:07:43.248000 [0-x] == Info: [MULTI] [SETUP] -> [CONNECT]
14:07:43.253000 [0-x] == Info: [READ] client_reset, clear readers
14:07:43.258000 [0-x] == Info: [FTP] [STOP] setup connection -> 0
14:07:43.266000 [0-0] == Info: [MULTI] [CONNECT] [CPOOL] added connection 0. The cache now contains 1 members
14:07:43.275000 [0-0] == Info: [SETUP] added
14:07:43.280000 [0-0] == Info: [MULTI] [CONNECT] -> [CONNECTING]
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed
  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     014:07:43.306000 [0-0] == Info: [HAPPY-EYEBALLS] created ipv4 (timeout 299943ms)
14:07:43.313000 [0-0] == Info: [HAPPY-EYEBALLS] ipv4 starting (timeout=299935ms)
14:07:43.320000 [0-0] == Info:   Trying proxyip:1080...
14:07:43.326000 [0-0] == Info: [TCP] cf_socket_open() -> 0, fd=360
14:07:43.332000 [0-0] == Info: [TCP] local address 0.0.0.0 port 57632...
14:07:43.339000 [0-0] == Info: [HAPPY-EYEBALLS] ipv4 connect -> 0, connected=0
14:07:43.347000 [0-0] == Info: [SETUP] Curl_conn_connect(block=0) -> 0, done=0
  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     014:07:43.361000 [0-0] == Info: [TCP] adjust_pollset, !connected, POLLOUT fd=360
14:07:43.369000 [0-0] == Info: [HAPPY-EYEBALLS] adjust_pollset -> 1 socks
14:07:43.377000 [0-0] == Info: [MULTI] [CONNECTING] multi_wait pollset[fd=360 OUT], timeouts=0
14:07:43.384000 [0-0] == Info: [MULTI] [CONNECTING] multi_perform(running=1)
14:07:43.392000 [0-0] == Info: [TCP] connected
14:07:43.396000 [0-0] == Info: [HAPPY-EYEBALLS] ipv4 connect -> 0, connected=1
14:07:43.404000 [0-0] == Info: [HAPPY-EYEBALLS] Connected to ftp.server.com (proxyip) port 1080
14:07:43.416000 [0-0] == Info: [TCP] send(len=5) -> 5, err=0
14:07:43.422000 [0-0] == Info: [TCP] recv(len=2) -> -1, err=81
14:07:43.428000 [0-0] == Info: [SETUP] Curl_conn_connect(block=0) -> 0, done=0
14:07:43.436000 [0-0] == Info: [TCP] adjust_pollset, !active, POLLIN fd=360
14:07:43.443000 [0-0] == Info: [MULTI] [CONNECTING] multi_wait pollset[fd=360 IN], timeouts=0
14:07:43.451000 [0-0] == Info: [MULTI] [CONNECTING] multi_perform(running=1)
14:07:43.458000 [0-0] == Info: [TCP] recv(len=2) -> 2, err=0
14:07:43.464000 [0-0] == Info: [TCP] send(len=43) -> 43, err=0
14:07:43.469000 [0-0] == Info: [TCP] recv(len=2) -> -1, err=81
14:07:43.475000 [0-0] == Info: [SETUP] Curl_conn_connect(block=0) -> 0, done=0
14:07:43.483000 [0-0] == Info: [TCP] adjust_pollset, !active, POLLIN fd=360
14:07:43.489000 [0-0] == Info: [MULTI] [CONNECTING] multi_wait pollset[fd=360 IN], timeouts=0
14:07:43.499000 [0-0] == Info: [MULTI] [CONNECTING] multi_perform(running=1)
14:07:43.507000 [0-0] == Info: [TCP] recv(len=2) -> 2, err=0
14:07:43.514000 [0-0] == Info: [SETUP] Curl_conn_connect(block=0) -> 0, done=0
14:07:43.521000 [0-0] == Info: [TCP] adjust_pollset, !active, POLLIN fd=360
14:07:43.529000 [0-0] == Info: [MULTI] [CONNECTING] multi_wait pollset[fd=360 IN], timeouts=1
14:07:43.537000 [0-0] == Info: [MULTI] [CONNECTING] multi_perform(running=1)
14:07:43.545000 [0-0] == Info: [MULTI] [CONNECTING] Curl_multi_will_close fd=380
14:07:43.553000 [0-0] == Info: Host ftp.server.com:21 was resolved.
14:07:43.562000 [0-0] == Info: IPv6: (none)
14:07:43.566000 [0-0] == Info: IPv4: 52.174.49.89
14:07:43.570000 [0-0] == Info: SOCKS5 connect to 52.174.49.89:21 (locally resolved)
14:07:43.579000 [0-0] == Info: [TCP] send(len=10) -> 10, err=0
14:07:43.585000 [0-0] == Info: [TCP] recv(len=10) -> -1, err=81
14:07:43.591000 [0-0] == Info: [SETUP] Curl_conn_connect(block=0) -> 0, done=0
14:07:43.598000 [0-0] == Info: [TCP] adjust_pollset, !active, POLLIN fd=360
14:07:43.604000 [0-0] == Info: [MULTI] [CONNECTING] multi_wait pollset[fd=360 IN], timeouts=0
14:07:43.612000 [0-0] == Info: [MULTI] [CONNECTING] multi_perform(running=1)
14:07:43.618000 [0-0] == Info: [TCP] recv(len=10) -> 10, err=0
14:07:43.623000 [0-0] == Info: SOCKS5 request granted.
14:07:43.628000 [0-0] == Info: Connected to proxyip () port 1080
14:07:43.634000 [0-0] == Info: [SETUP] Curl_conn_connect(block=0) -> 0, done=1
14:07:43.641000 [0-0] == Info: Connected to proxyip (proxyip) port 1080
14:07:43.648000 [0-0] == Info: [MULTI] [CONNECTING] -> [PROTOCONNECT]
14:07:43.655000 [0-0] == Info: [FTP] [STOP] -> [WAIT220]
14:07:43.661000 [0-0] == Info: [TCP] recv(len=900) -> 27, err=0
14:07:43.669000 [0-0] <= Recv header, 27 bytes (0x1b)
0000: 220 Microsoft FTP Service
14:07:43.678000 [0-0] == Info: [WRITE] [OUT] wrote 27 header bytes -> 27
14:07:43.686000 [0-0] == Info: [WRITE] [PAUSE] writing 27/27 bytes of type 2 -> 0
14:07:43.697000 [0-0] == Info: [WRITE] download_write header(type=2, blen=27) -> 0
14:07:43.706000 [0-0] == Info: [WRITE] client_write(type=2, len=27) -> 0
14:07:43.715000 [0-0] == Info: [TCP] send(len=10) -> 10, err=0
14:07:43.721000 [0-0] => Send header, 10 bytes (0xa)
0000: AUTH SSL
14:07:43.729000 [0-0] == Info: [FTP] [WAIT220] -> [AUTH]
14:07:43.734000 [0-0] == Info: [MULTI] [PROTOCONNECT] -> [PROTOCONNECTING]
14:07:43.742000 [0-0] == Info: [MULTI] [PROTOCONNECTING] multi_wait pollset[fd=360 IN], timeouts=0
14:07:43.751000 [0-0] == Info: [MULTI] [PROTOCONNECTING] multi_perform(running=1)
14:07:43.759000 [0-0] == Info: [TCP] recv(len=900) -> 49, err=0
14:07:43.766000 [0-0] <= Recv header, 49 bytes (0x31)
0000: 234 AUTH command ok. Expecting TLS Negotiation.
14:07:43.776000 [0-0] == Info: [WRITE] [OUT] wrote 49 header bytes -> 49
14:07:43.783000 [0-0] == Info: [WRITE] [PAUSE] writing 49/49 bytes of type 2 -> 0
14:07:43.791000 [0-0] == Info: [WRITE] download_write header(type=2, blen=49) -> 0
14:07:43.799000 [0-0] == Info: [WRITE] client_write(type=2, len=49) -> 0
14:07:43.806000 [0-0] == Info: [SSL] added
14:07:43.811000 [0-0] == Info: [SSL] cf_connect()
14:07:43.816000 [0-0] == Info: [SSL] ossl_connect, step1
14:07:43.822000 [0-0] == Info: [SSLS] find peer slot for ftp.server.com:21:CA-C:\Users\rmja\Downloads\curl-8.13.0_2-win64-mingw\curl-8.13.0_2-win64-mingw\bin\curl-ca-bundle.crt:IMPL-LibreSSL/4.0.0:G among 25 slots
14:07:43.844000 [0-0] == Info: [SSLS] peer not found for ftp.server.com:21:CA-C:\Users\rmja\Downloads\curl-8.13.0_2-win64-mingw\curl-8.13.0_2-win64-mingw\bin\curl-ca-bundle.crt:IMPL-LibreSSL/4.0.0:G
14:07:43.866000 [0-0] == Info: [SSLS] no cached session for ftp.server.com:21:CA-C:\Users\rmja\Downloads\curl-8.13.0_2-win64-mingw\curl-8.13.0_2-win64-mingw\bin\curl-ca-bundle.crt:IMPL-LibreSSL/4.0.0:G
14:07:43.889000 [0-0] == Info: [SSL] ossl_connect, step2
14:07:43.895000 [0-0] == Info: [TCP] send(len=324) -> 324, err=0
14:07:43.901000 [0-0] == Info: [SSL] ossl_bio_cf_out_write(len=324) -> 324, err=0
14:07:43.909000 [0-0] == Info: TLSv1.3 (OUT), TLS handshake, Client hello (1):
14:07:43.916000 [0-0] => Send SSL data, 319 bytes (0x13f)
0000: ...;..s....f..q...N....~.#.@..."O.Z... .D[~@..A....i....+...T...
0040: ..Hc..l.\.......0.,.(.$.......k.9.............=.5...../.+.'.#...
0080: ....g.3...E...<./...A......................./.-..*redacted.w
00c0: esteurope.cloudapp.azure.com....................................
0100: .............+........3.&.$... ...x...C..j.Xd[:....d.y....N..)*
14:07:43.958000 [0-0] == Info: [TCP] recv(len=5) -> 5, err=0
14:07:43.963000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
14:07:43.971000 [0-0] == Info: [SSL] ossl_populate_x509_store, path=C:\Users\rmja\Downloads\curl-8.13.0_2-win64-mingw\curl-8.13.0_2-win64-mingw\bin\curl-ca-bundle.crt, blob=0
14:07:43.999000 [0-0] == Info:  CAfile: C:\Users\rmja\Downloads\curl-8.13.0_2-win64-mingw\curl-8.13.0_2-win64-mingw\bin\curl-ca-bundle.crt
14:07:44.013000 [0-0] == Info:  CApath: none
14:07:44.018000 [0-0] == Info: [TCP] recv(len=3361) -> 3361, err=0
14:07:44.024000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=3361) -> 3361, err=0
14:07:44.032000 [0-0] == Info: TLSv1.3 (IN), TLS handshake, Server hello (2):
14:07:44.037000 [0-0] <= Recv SSL data, 81 bytes (0x51)
0000: ...M..h.....B{.X.7..7%z.:.....F..MS..L ....p..O.Ig..>`t....lt.;.
0040: ...u.J:.0........
14:07:44.052000 [0-0] == Info: TLSv1.2 (IN), TLS handshake, Certificate (11):
14:07:44.059000 [0-0] <= Recv SSL data, 2976 bytes (0xba0)
0000: ..........0...0..........].....{.0...*.H........0..1.0...U....US
0040: 1.0...U....Arizona1.0...U....Scottsdale1.0...U....GoDaddy.com, I
0080: nc.1-0+..U...$http://certs.godaddy.com/repository/1301..U...*Go
00c0: Daddy Secure Certificate Authority - G20...241003084213Z..251013
0100: 123219Z051301..U...*ftp.server.com0.
0140: ."0...*.H.............0..........h<_.S.mY....O...........2e..N..
0180: .9.+....~..GV%.:.R........D..5.7.N./..[.)....{=..`h...n7.>R2...{
01c0: .o....{.....UM.4..6..$.....&$z...H|s...r..W[...`n27M........r&.%
0200: .l.....l..e..../s.bd..q2C...M....g.,Lb....:..C..7.gk.D....=..M..
0240: M.yF.w...`..i .....V....!.....Q........M0..I0...U.......0.0...U.
0280: %..0...+.........+.......0...U...........09..U...2000..,.*.(http
02c0: ://crl.godaddy.com/gdig2s1-31057.crl0]..U. .V0T0H..`.H...m....09
0300: 07..+........+http://certificates.godaddy.com/repository/0...g..
0340: ...0v..+........j0h0$..+.....0...http://ocsp.godaddy.com/0@..+..
0380: ...0..4http://certificates.godaddy.com/repository/gdig2.crt0...U
03c0: .#..0...@..'..4.0.3..l...,..05..U....0,.*redacted.westeurope
0400: .cloudapp.azure.com0...U..............Tf?I&s..(?..0.....+.....y.
0440: .....o...k.i.v...N4.SrL.....?z....b...m0.....&:....Q.A......G0E.
0480:  MX;......tMA.L=#..4uS.P..h.......!...W\Q...Z.=....).&..i.......
04c0: .....w.}Y...x*{.ag|^....\..N..../....y.....Q.B-.....H0F.!..]....
0500: ..j..F.......#\.Ds.9..b..n.!...a.../.......W&...@z..G.R...;{..v.
0540: ...j.q.e...S...|"..\.....~T..L......Q.Cw.....G0E.!..^..n..:..1..
0580: ..........#Y.@..Si . .e(.?(....(.oh.ih.D,......".J[O.0...*.H....
05c0: .........]..9..n.x.-&..z.mT..m_.)..fp..H.l...;R.5.....2/..|.=..y
0600: .....t..x....wA.4...b.bQ......N9..G.l.....$../D.q...'[..G`f.-H.
0640: .;..07....s..x......-...d.Y.!...f............@05.&.*..t.c.....~'
0680: <..A..uw.gvHC..Xn-...U.....3....G....R.*.F.K.~..>z.H..B.l-Hj,...
06c0: ..G..}......0...0...........0...*.H........0..1.0...U....US1.0..
0700: .U....Arizona1.0...U....Scottsdale1.0...U....GoDaddy.com, Inc.11
0740: 0/..U...(Go Daddy Root Certificate Authority - G20...11050307000
0780: 0Z..310503070000Z0..1.0...U....US1.0...U....Arizona1.0...U....Sc
07c0: ottsdale1.0...U....GoDaddy.com, Inc.1-0+..U...$http://certs.goda
0800: ddy.com/repository/1301..U...*Go Daddy Secure Certificate Author
0840: ity - G20.."0...*.H.............0..............v...b.0d...l...b.
0880: ./.>e...b.<R...EKU.xkc.b...il.....L.E3......+..a.yW....?0<]G....
08c0: .7.AQ..KT.(.....08...&.fGcm.q&G.8GS.F......E...q..o....0:yO_LG..
0900: .[...`;..C...3N...'O.%........t.dW..DU.-*:>....2...d..:P.J..y3..
0940:  .....9.i.lcR.w...t.....PT5KiN.;.I.....R..........0...0...U.....
0980: ..0....0...U...........0...U......@..'..4.0.3..l...,..0...U.#..0
09c0: ...:....g(.....An .....04..+........(0&0$..+.....0...http://ocsp
0a00: .godaddy.com/05..U....0,0*.(.&.$http://crl.godaddy.com/gdroot-g2
0a40: .crl0F..U. .?0=0;..U. .0301..+........%https://certs.godaddy.com
0a80: /repository/0...*.H..............~l...8....K.._O..l>....P..s.W1.
0ac0: .../....[....yb....jcs1...H.;.-.]...|%O.V0..D.{,..^...a...*.....
0b00: .}.C.D..p........`..r.s$..."....bXD...%......b.Q...Q*...s.v.6...
0b40: .,....*...Mu..?.A.#}[K...X.F..``..}PA......../..T.D......3..-v6&
0b80: .....a....o.F.(..&}...........0.
14:07:44.343000 [0-0] == Info: TLSv1.2 (IN), TLS handshake, Server key exchange (12):
14:07:44.351000 [0-0] <= Recv SSL data, 300 bytes (0x12c)
0000: ...(... .Ns..D$.t?.._...b0 ......Y'.@./x.....`o..q....I.a..Hk...
0040: ......r.k........t..l....k..n....hVr..d.T.~+]...9...b..f...79...
0080: ....Fow.).LFVV...x).,.bK...K....e:...t...W...6.{N.......^.....=1
00c0: .P*..P+..m1.5....Gb.....@..s0..rw.N. M.J.... ).Z.......&g..S@...
0100: ...:[..g}ja...7.....jI.....N...ZU.L....y1..h
14:07:44.388000 [0-0] == Info: TLSv1.2 (IN), TLS handshake, Server finished (14):
14:07:44.395000 [0-0] <= Recv SSL data, 4 bytes (0x4)
0000: ....
14:07:44.401000 [0-0] == Info: TLSv1.2 (OUT), TLS handshake, Client key exchange (16):
14:07:44.407000 [0-0] => Send SSL data, 37 bytes (0x25)
0000: ...! ..~..;...*..z..a~LiH.3....b...Tv
14:07:44.416000 [0-0] == Info: TLSv1.2 (OUT), TLS change cipher, Change cipher spec (1):
14:07:44.424000 [0-0] => Send SSL data, 1 bytes (0x1)
0000: .
14:07:44.429000 [0-0] == Info: TLSv1.2 (OUT), TLS handshake, Finished (20):
14:07:44.436000 [0-0] => Send SSL data, 16 bytes (0x10)
0000: ......k.tj.'....
14:07:44.442000 [0-0] == Info: [TCP] send(len=93) -> 93, err=0
14:07:44.447000 [0-0] == Info: [SSL] ossl_bio_cf_out_write(len=93) -> 93, err=0
14:07:44.454000 [0-0] == Info: [TCP] recv(len=5) -> -1, err=81
14:07:44.459000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:07:44.465000 [0-0] == Info: [SSL] SSL_connect() -> err=-1, detail=2
14:07:44.471000 [0-0] == Info: [SSL] SSL_connect() -> want recv
14:07:44.476000 [0-0] == Info: [SSL] cf_connect() -> 0, done=0
14:07:44.481000 [0-0] == Info: [SSL] Curl_conn_connect(block=1) -> 0, done=0
14:07:44.487000 [0-0] == Info: [SSL] Curl_conn_connect(block=1), do poll
14:07:44.493000 [0-0] == Info: [SSL] adjust_pollset, POLLIN fd=360
14:07:44.498000 [0-0] == Info: [SSL] Curl_conn_connect(block=1), Curl_poll() -> 1
14:07:44.504000 [0-0] == Info: [SSL] cf_connect()
14:07:44.508000 [0-0] == Info: [SSL] ossl_connect, step2
14:07:44.513000 [0-0] == Info: [TCP] recv(len=5) -> 5, err=0
14:07:44.518000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
14:07:44.525000 [0-0] == Info: [TCP] recv(len=1) -> 1, err=0
14:07:44.531000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=1) -> 1, err=0
14:07:44.536000 [0-0] == Info: TLSv1.2 (IN), TLS change cipher, Change cipher spec (1):
14:07:44.542000 [0-0] <= Recv SSL data, 1 bytes (0x1)
0000: .
14:07:44.548000 [0-0] == Info: [TCP] recv(len=5) -> 5, err=0
14:07:44.553000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
14:07:44.559000 [0-0] == Info: [TCP] recv(len=40) -> 40, err=0
14:07:44.565000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=40) -> 40, err=0
14:07:44.570000 [0-0] == Info: TLSv1.2 (IN), TLS handshake, Finished (20):
14:07:44.578000 [0-0] <= Recv SSL data, 16 bytes (0x10)
0000: ......KZl.l7Fu1.
14:07:44.587000 [0-0] == Info: [SSLS] find peer slot for ftp.server.com:21:CA-C:\Users\rmja\Downloads\curl-8.13.0_2-win64-mingw\curl-8.13.0_2-win64-mingw\bin\curl-ca-bundle.crt:IMPL-LibreSSL/4.0.0:G among 25 slots
14:07:44.608000 [0-0] == Info: [SSLS] peer not found for ftp.server.com:21:CA-C:\Users\rmja\Downloads\curl-8.13.0_2-win64-mingw\curl-8.13.0_2-win64-mingw\bin\curl-ca-bundle.crt:IMPL-LibreSSL/4.0.0:G
14:07:44.628000 [0-0] == Info: [SSLS] added session for ftp.server.com:21:CA-C:\Users\rmja\Downloads\curl-8.13.0_2-win64-mingw\curl-8.13.0_2-win64-mingw\bin\curl-ca-bundle.crt:IMPL-LibreSSL/4.0.0:G [proto=0x303, valid_secs=7200, alpn=(nil), earlydata=0, quic_tp=no], peer has 1 sessions now
14:07:44.660000 [0-0] == Info: SSL connection using TLSv1.2 / ECDHE-RSA-AES256-GCM-SHA384 / [blank] / UNDEF
14:07:44.671000 [0-0] == Info: [SSL] ossl_connect, step3
14:07:44.676000 [0-0] == Info: Server certificate:
14:07:44.680000 [0-0] == Info:  subject: CN=ftp.server.com
14:07:44.691000 [0-0] == Info:  start date: Oct  3 08:42:13 2024 GMT
14:07:44.697000 [0-0] == Info:  expire date: Oct 13 12:32:19 2025 GMT
14:07:44.703000 [0-0] == Info:  subjectAltName: host "ftp.server.com" matched cert's "ftp.server.com"
14:07:44.718000 [0-0] == Info:  issuer: C=US; ST=Arizona; L=Scottsdale; O=GoDaddy.com, Inc.; OU=http://certs.godaddy.com/repository/; CN=Go Daddy Secure Certificate Authority - G2
14:07:44.734000 [0-0] == Info:  SSL certificate verify ok.
14:07:44.740000 [0-0] == Info:   Certificate level 0: Public key type ? (2048/112 Bits/secBits), signed using sha256WithRSAEncryption
14:07:44.753000 [0-0] == Info:   Certificate level 1: Public key type ? (2048/112 Bits/secBits), signed using sha256WithRSAEncryption
14:07:44.766000 [0-0] == Info:   Certificate level 2: Public key type ? (2048/112 Bits/secBits), signed using sha256WithRSAEncryption
14:07:44.779000 [0-0] == Info: [SSL] ossl_connect, done
14:07:44.783000 [0-0] == Info: [SSL] cf_connect() -> 0, done=1
14:07:44.790000 [0-0] == Info: [SSL] Curl_conn_connect(block=1) -> 0, done=1
14:07:44.796000 [0-0] == Info: Connected to proxyip (proxyip) port 1080
14:07:44.804000 [0-0] == Info: [TCP] send(len=48) -> 48, err=0
14:07:44.810000 [0-0] == Info: [SSL] ossl_bio_cf_out_write(len=48) -> 48, err=0
14:07:44.817000 [0-0] => Send header, 19 bytes (0x13)
0000: USER ftpuser
14:07:44.824000 [0-0] == Info: [FTP] [AUTH] -> [USER]
  0     0    0     0    0     0      0      0 --:--:--  0:00:01 --:--:--     014:07:44.836000 [0-0] == Info: [MULTI] [PROTOCONNECTING] multi_wait pollset[fd=360 IN], timeouts=0
14:07:44.844000 [0-0] == Info: [MULTI] [PROTOCONNECTING] multi_perform(running=1)
14:07:44.852000 [0-0] == Info: [TCP] recv(len=5) -> 5, err=0
14:07:44.857000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
14:07:44.865000 [0-0] == Info: [TCP] recv(len=47) -> 47, err=0
14:07:44.871000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=47) -> 47, err=0
14:07:44.878000 [0-0] == Info: [SSL] cf_recv(len=900) -> 23, 0
14:07:44.884000 [0-0] <= Recv header, 23 bytes (0x17)
0000: 331 Password required
14:07:44.892000 [0-0] == Info: [WRITE] [OUT] wrote 23 header bytes -> 23
14:07:44.898000 [0-0] == Info: [WRITE] [PAUSE] writing 23/23 bytes of type 2 -> 0
14:07:44.905000 [0-0] == Info: [WRITE] download_write header(type=2, blen=23) -> 0
14:07:44.912000 [0-0] == Info: [WRITE] client_write(type=2, len=23) -> 0
14:07:44.918000 [0-0] == Info: [TCP] send(len=47) -> 47, err=0
14:07:44.923000 [0-0] == Info: [SSL] ossl_bio_cf_out_write(len=47) -> 47, err=0
14:07:44.930000 [0-0] => Send header, 18 bytes (0x12)
0000: PASS ftppass
14:07:44.936000 [0-0] == Info: [FTP] [USER] -> [PASS]
14:07:44.942000 [0-0] == Info: [MULTI] [PROTOCONNECTING] multi_wait pollset[fd=360 IN], timeouts=0
14:07:44.963000 [0-0] == Info: [MULTI] [PROTOCONNECTING] multi_perform(running=1)
14:07:44.970000 [0-0] == Info: [TCP] recv(len=5) -> 5, err=0
14:07:44.974000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
14:07:44.980000 [0-0] == Info: [TCP] recv(len=45) -> 45, err=0
14:07:44.984000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=45) -> 45, err=0
14:07:44.991000 [0-0] == Info: [SSL] cf_recv(len=900) -> 21, 0
14:07:44.996000 [0-0] <= Recv header, 21 bytes (0x15)
0000: 230 User logged in.
14:07:45.004000 [0-0] == Info: [WRITE] [OUT] wrote 21 header bytes -> 21
14:07:45.010000 [0-0] == Info: [WRITE] [PAUSE] writing 21/21 bytes of type 2 -> 0
14:07:45.015000 [0-0] == Info: [WRITE] download_write header(type=2, blen=21) -> 0
14:07:45.023000 [0-0] == Info: [WRITE] client_write(type=2, len=21) -> 0
14:07:45.029000 [0-0] == Info: [TCP] send(len=37) -> 37, err=0
14:07:45.034000 [0-0] == Info: [SSL] ossl_bio_cf_out_write(len=37) -> 37, err=0
14:07:45.040000 [0-0] => Send header, 8 bytes (0x8)
0000: PBSZ 0
14:07:45.047000 [0-0] == Info: [FTP] [PASS] -> [PBSZ]
14:07:45.051000 [0-0] == Info: [MULTI] [PROTOCONNECTING] multi_wait pollset[fd=360 IN], timeouts=0
14:07:45.059000 [0-0] == Info: [MULTI] [PROTOCONNECTING] multi_perform(running=1)
14:07:45.066000 [0-0] == Info: [TCP] recv(len=5) -> 5, err=0
14:07:45.071000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
14:07:45.078000 [0-0] == Info: [TCP] recv(len=54) -> 54, err=0
14:07:45.084000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=54) -> 54, err=0
14:07:45.090000 [0-0] == Info: [SSL] cf_recv(len=900) -> 30, 0
14:07:45.095000 [0-0] <= Recv header, 30 bytes (0x1e)
0000: 200 PBSZ command successful.
14:07:45.101000 [0-0] == Info: [WRITE] [OUT] wrote 30 header bytes -> 30
14:07:45.107000 [0-0] == Info: [WRITE] [PAUSE] writing 30/30 bytes of type 2 -> 0
14:07:45.114000 [0-0] == Info: [WRITE] download_write header(type=2, blen=30) -> 0
14:07:45.121000 [0-0] == Info: [WRITE] client_write(type=2, len=30) -> 0
14:07:45.127000 [0-0] == Info: [TCP] send(len=37) -> 37, err=0
14:07:45.132000 [0-0] == Info: [SSL] ossl_bio_cf_out_write(len=37) -> 37, err=0
14:07:45.139000 [0-0] => Send header, 8 bytes (0x8)
0000: PROT P
14:07:45.144000 [0-0] == Info: [FTP] [PBSZ] -> [PROT]
14:07:45.149000 [0-0] == Info: [MULTI] [PROTOCONNECTING] multi_wait pollset[fd=360 IN], timeouts=0
14:07:45.156000 [0-0] == Info: [MULTI] [PROTOCONNECTING] multi_perform(running=1)
14:07:45.164000 [0-0] == Info: [TCP] recv(len=5) -> 5, err=0
14:07:45.169000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
14:07:45.175000 [0-0] == Info: [TCP] recv(len=54) -> 54, err=0
14:07:45.180000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=54) -> 54, err=0
14:07:45.186000 [0-0] == Info: [SSL] cf_recv(len=900) -> 30, 0
14:07:45.191000 [0-0] <= Recv header, 30 bytes (0x1e)
0000: 200 PROT command successful.
14:07:45.197000 [0-0] == Info: [WRITE] [OUT] wrote 30 header bytes -> 30
14:07:45.205000 [0-0] == Info: [WRITE] [PAUSE] writing 30/30 bytes of type 2 -> 0
14:07:45.211000 [0-0] == Info: [WRITE] download_write header(type=2, blen=30) -> 0
14:07:45.219000 [0-0] == Info: [WRITE] client_write(type=2, len=30) -> 0
14:07:45.225000 [0-0] == Info: [TCP] send(len=34) -> 34, err=0
14:07:45.231000 [0-0] == Info: [SSL] ossl_bio_cf_out_write(len=34) -> 34, err=0
14:07:45.238000 [0-0] => Send header, 5 bytes (0x5)
0000: PWD
14:07:45.243000 [0-0] == Info: [FTP] [PROT] -> [PWD]
14:07:45.248000 [0-0] == Info: [MULTI] [PROTOCONNECTING] multi_wait pollset[fd=360 IN], timeouts=0
14:07:45.256000 [0-0] == Info: [MULTI] [PROTOCONNECTING] multi_perform(running=1)
14:07:45.264000 [0-0] == Info: [TCP] recv(len=5) -> 5, err=0
14:07:45.270000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
14:07:45.277000 [0-0] == Info: [TCP] recv(len=55) -> 55, err=0
14:07:45.284000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=55) -> 55, err=0
14:07:45.291000 [0-0] == Info: [SSL] cf_recv(len=900) -> 31, 0
14:07:45.296000 [0-0] <= Recv header, 31 bytes (0x1f)
0000: 257 "/" is current directory.
14:07:45.304000 [0-0] == Info: [WRITE] [OUT] wrote 31 header bytes -> 31
14:07:45.310000 [0-0] == Info: [WRITE] [PAUSE] writing 31/31 bytes of type 2 -> 0
14:07:45.318000 [0-0] == Info: [WRITE] download_write header(type=2, blen=31) -> 0
14:07:45.326000 [0-0] == Info: [WRITE] client_write(type=2, len=31) -> 0
14:07:45.333000 [0-0] == Info: Entry path is '/'
14:07:45.337000 [0-0] == Info: [FTP] [PWD] -> [STOP]
14:07:45.341000 [0-0] == Info: [FTP] [STOP] protocol connect phase DONE
14:07:45.348000 [0-0] == Info: [MULTI] [PROTOCONNECTING] -> [DO]
  0     0    0     0    0     0      0      0 --:--:--  0:00:02 --:--:--     014:07:45.360000 [0-0] == Info: Request has same path as previous transfer
14:07:45.366000 [0-0] == Info: [FTP] [STOP] DO phase starts
14:07:45.371000 [0-0] == Info: [TCP] send(len=35) -> 35, err=0
14:07:45.377000 [0-0] == Info: [SSL] ossl_bio_cf_out_write(len=35) -> 35, err=0
14:07:45.382000 [0-0] => Send header, 6 bytes (0x6)
0000: EPSV
14:07:45.388000 [0-0] == Info: [FTP] [STOP] -> [PASV]
14:07:45.392000 [0-0] == Info: Connect data stream passively
14:07:45.397000 [0-0] == Info: [FTP] [PASV] perform, awaiting DATA connect
14:07:45.402000 [0-0] == Info: [MULTI] [DO] -> [DOING]
14:07:45.407000 [0-0] == Info: [TCP] recv(len=5) -> 5, err=0
14:07:45.411000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
14:07:45.418000 [0-0] == Info: [TCP] recv(len=72) -> 72, err=0
14:07:45.422000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=72) -> 72, err=0
14:07:45.427000 [0-0] == Info: [SSL] cf_recv(len=900) -> 48, 0
14:07:45.433000 [0-0] <= Recv header, 48 bytes (0x30)
0000: 229 Entering Extended Passive Mode (|||17579|)
14:07:45.441000 [0-0] == Info: [WRITE] [OUT] wrote 48 header bytes -> 48
14:07:45.445000 [0-0] == Info: [WRITE] [PAUSE] writing 48/48 bytes of type 2 -> 0
14:07:45.451000 [0-0] == Info: [WRITE] download_write header(type=2, blen=48) -> 0
14:07:45.458000 [0-0] == Info: [WRITE] client_write(type=2, len=48) -> 0
14:07:45.463000 [0-0] == Info: Hostname proxyip was found in DNS cache
14:07:45.469000 [0-0] == Info: [SETUP-1] added
14:07:45.473000 [0-0] == Info: Connecting to ftp.server.com (proxyip) port 1080
14:07:45.480000 [0-0] == Info: [FTP] [PASV] -> [STOP]
14:07:45.485000 [0-0] == Info: [FTP] [STOP] DO phase is complete2
14:07:45.490000 [0-0] == Info: [MULTI] [DOING] -> [DOING_MORE]
14:07:45.495000 [0-0] == Info: [HAPPY-EYEBALLS-1] created ipv4 (timeout 297752ms)
14:07:45.501000 [0-0] == Info: [HAPPY-EYEBALLS-1] ipv4 starting (timeout=297746ms)
14:07:45.508000 [0-0] == Info:   Trying proxyip:1080...
14:07:45.513000 [0-0] == Info: [TCP-1] cf_socket_open() -> 0, fd=380
14:07:45.519000 [0-0] == Info: [TCP-1] local address 0.0.0.0 port 57639...
14:07:45.524000 [0-0] == Info: [HAPPY-EYEBALLS-1] ipv4 connect -> 0, connected=0
14:07:45.531000 [0-0] == Info: [SETUP-1] Curl_conn_connect(block=0) -> 0, done=0
14:07:45.536000 [0-0] == Info: [FTP] [STOP] ftp_domore_getsock()
14:07:45.541000 [0-0] == Info: [TCP-1] adjust_pollset, !connected, POLLOUT fd=380
14:07:45.547000 [0-0] == Info: [HAPPY-EYEBALLS-1] adjust_pollset -> 2 socks
14:07:45.553000 [0-0] == Info: [MULTI] [DOING_MORE] multi_wait pollset[fd=360 IN, fd=380 OUT], timeouts=0
14:07:45.562000 [0-0] == Info: [MULTI] [DOING_MORE] multi_perform(running=1)
14:07:45.567000 [0-0] == Info: [TCP-1] connected
14:07:45.571000 [0-0] == Info: [HAPPY-EYEBALLS-1] ipv4 connect -> 0, connected=1
14:07:45.576000 [0-0] == Info: [HAPPY-EYEBALLS-1] Connected to ftp.server.com (proxyip) port 1080
14:07:45.586000 [0-0] == Info: [TCP-1] send(len=5) -> 5, err=0
14:07:45.591000 [0-0] == Info: [TCP-1] recv(len=2) -> -1, err=81
14:07:45.597000 [0-0] == Info: [SETUP-1] Curl_conn_connect(block=0) -> 0, done=0
14:07:45.604000 [0-0] == Info: [FTP] [STOP] ftp_domore_getsock()
14:07:45.610000 [0-0] == Info: [TCP-1] adjust_pollset, !active, POLLIN fd=380
14:07:45.618000 [0-0] == Info: [MULTI] [DOING_MORE] multi_wait pollset[fd=360 IN, fd=380 IN], timeouts=0
14:07:45.628000 [0-0] == Info: [MULTI] [DOING_MORE] multi_perform(running=1)
14:07:45.634000 [0-0] == Info: [TCP-1] recv(len=2) -> 2, err=0
14:07:45.640000 [0-0] == Info: [TCP-1] send(len=43) -> 43, err=0
14:07:45.645000 [0-0] == Info: [TCP-1] recv(len=2) -> -1, err=81
14:07:45.652000 [0-0] == Info: [SETUP-1] Curl_conn_connect(block=0) -> 0, done=0
14:07:45.659000 [0-0] == Info: [FTP] [STOP] ftp_domore_getsock()
14:07:45.665000 [0-0] == Info: [TCP-1] adjust_pollset, !active, POLLIN fd=380
14:07:45.672000 [0-0] == Info: [MULTI] [DOING_MORE] multi_wait pollset[fd=360 IN, fd=380 IN], timeouts=0
14:07:45.684000 [0-0] == Info: [MULTI] [DOING_MORE] multi_perform(running=1)
14:07:45.700000 [0-0] == Info: [TCP-1] recv(len=2) -> 2, err=0
14:07:45.711000 [0-0] == Info: [SETUP-1] Curl_conn_connect(block=0) -> 0, done=0
14:07:45.725000 [0-0] == Info: [FTP] [STOP] ftp_domore_getsock()
14:07:45.738000 [0-0] == Info: [TCP-1] adjust_pollset, !active, POLLIN fd=380
14:07:45.747000 [0-0] == Info: [MULTI] [DOING_MORE] multi_wait pollset[fd=360 IN, fd=380 IN], timeouts=1
14:07:45.760000 [0-0] == Info: [MULTI] [DOING_MORE] multi_perform(running=1)
14:07:45.768000 [0-0] == Info: [MULTI] [DOING_MORE] Curl_multi_will_close fd=464
14:07:45.775000 [0-0] == Info: Host ftp.server.com:17579 was resolved.
14:07:45.784000 [0-0] == Info: IPv6: (none)
14:07:45.789000 [0-0] == Info: IPv4: 52.174.49.89
14:07:45.794000 [0-0] == Info: SOCKS5 connect to 52.174.49.89:17579 (locally resolved)
14:07:45.801000 [0-0] == Info: [TCP-1] send(len=10) -> 10, err=0
14:07:45.807000 [0-0] == Info: [TCP-1] recv(len=10) -> -1, err=81
14:07:45.813000 [0-0] == Info: [SETUP-1] Curl_conn_connect(block=0) -> 0, done=0
14:07:45.819000 [0-0] == Info: [FTP] [STOP] ftp_domore_getsock()
14:07:45.824000 [0-0] == Info: [TCP-1] adjust_pollset, !active, POLLIN fd=380
14:07:45.830000 [0-0] == Info: [MULTI] [DOING_MORE] multi_wait pollset[fd=360 IN, fd=380 IN], timeouts=0
14:07:45.838000 [0-0] == Info: [MULTI] [DOING_MORE] multi_perform(running=1)
14:07:45.843000 [0-0] == Info: [TCP-1] recv(len=10) -> 10, err=0
14:07:45.849000 [0-0] == Info: SOCKS5 request granted.
14:07:45.853000 [0-0] == Info: Connected 2nd connection to  port 0
14:07:45.857000 [0-0] == Info: [SSL-1] cf_connect()
14:07:45.861000 [0-0] == Info: [SSL-1] ossl_connect, step1
14:07:45.866000 [0-0] == Info: [SSLS] find peer slot for ftp.server.com:21:CA-C:\Users\rmja\Downloads\curl-8.13.0_2-win64-mingw\curl-8.13.0_2-win64-mingw\bin\curl-ca-bundle.crt:IMPL-LibreSSL/4.0.0:G among 25 slots
14:07:45.884000 [0-0] == Info: [SSLS] took session for ftp.server.com:21:CA-C:\Users\rmja\Downloads\curl-8.13.0_2-win64-mingw\curl-8.13.0_2-win64-mingw\bin\curl-ca-bundle.crt:IMPL-LibreSSL/4.0.0:G [proto=0x303, alpn=(nil), earlydata=0, quic_tp=no], 0 sessions remain
14:07:45.905000 [0-0] == Info: SSL reusing session with ALPN '-'
14:07:45.911000 [0-0] == Info: [SSLS] find peer slot for ftp.server.com:21:CA-C:\Users\rmja\Downloads\curl-8.13.0_2-win64-mingw\curl-8.13.0_2-win64-mingw\bin\curl-ca-bundle.crt:IMPL-LibreSSL/4.0.0:G among 25 slots
14:07:45.929000 [0-0] == Info: [SSLS] added session for ftp.server.com:21:CA-C:\Users\rmja\Downloads\curl-8.13.0_2-win64-mingw\curl-8.13.0_2-win64-mingw\bin\curl-ca-bundle.crt:IMPL-LibreSSL/4.0.0:G [proto=0x303, valid_secs=7199, alpn=(nil), earlydata=0, quic_tp=no], peer has 1 sessions now
14:07:45.953000 [0-0] == Info: [SSL-1] ossl_connect, step2
14:07:45.958000 [0-0] == Info: [TCP-1] send(len=267) -> 267, err=0
14:07:45.963000 [0-0] == Info: [SSL-1] ossl_bio_cf_out_write(len=267) -> 267, err=0
14:07:45.969000 [0-0] == Info: TLSv1.2 (OUT), TLS handshake, Client hello (1):
14:07:45.974000 [0-0] => Send SSL data, 262 bytes (0x106)
0000: ......$...'.....b.X..;.?c..r....S..k(. ....p..O.Ig..>`t....lt.;.
0040: ...u.J:.V.0.,.(.$.......k.9.............=.5...../.+.'.#.......g.
0080: 3...E...<./...A...................c.............................
00c0: ......../.-..*ftp.server.com........
0100: ......
14:07:45.999000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:07:46.005000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:07:46.011000 [0-0] == Info: [SSL-1] SSL_connect() -> err=-1, detail=2
14:07:46.016000 [0-0] == Info: [SSL-1] SSL_connect() -> want recv
14:07:46.021000 [0-0] == Info: [SSL-1] cf_connect() -> 0, done=0
14:07:46.027000 [0-0] == Info: [SETUP-1] Curl_conn_connect(block=0) -> 0, done=0
14:07:46.032000 [0-0] == Info: [TCP] send(len=37) -> 37, err=0
14:07:46.037000 [0-0] == Info: [SSL] ossl_bio_cf_out_write(len=37) -> 37, err=0
14:07:46.043000 [0-0] => Send header, 8 bytes (0x8)
0000: TYPE I
14:07:46.049000 [0-0] == Info: [FTP] [STOP] -> [STOR_TYPE]
14:07:46.053000 [0-0] == Info: [FTP] [STOR_TYPE] ftp_domore_getsock()
14:07:46.059000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:07:46.063000 [0-0] == Info: [TCP-1] adjust_pollset, !active, POLLIN fd=380
14:07:46.070000 [0-0] == Info: [MULTI] [DOING_MORE] multi_wait pollset[fd=360 IN, fd=380 IN], timeouts=0
14:07:46.078000 [0-0] == Info: [MULTI] [DOING_MORE] multi_perform(running=1)
14:07:46.084000 [0-0] == Info: [SSL-1] cf_connect()
14:07:46.089000 [0-0] == Info: [SSL-1] ossl_connect, step2
14:07:46.092000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:07:46.098000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:07:46.104000 [0-0] == Info: [SSL-1] SSL_connect() -> err=-1, detail=2
14:07:46.110000 [0-0] == Info: [SSL-1] SSL_connect() -> want recv
14:07:46.115000 [0-0] == Info: [SSL-1] cf_connect() -> 0, done=0
14:07:46.119000 [0-0] == Info: [SETUP-1] Curl_conn_connect(block=0) -> 0, done=0
14:07:46.124000 [0-0] == Info: [TCP] recv(len=5) -> 5, err=0
14:07:46.130000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
14:07:46.136000 [0-0] == Info: [TCP] recv(len=44) -> 44, err=0
14:07:46.140000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=44) -> 44, err=0
14:07:46.147000 [0-0] == Info: [SSL] cf_recv(len=900) -> 20, 0
14:07:46.151000 [0-0] <= Recv header, 20 bytes (0x14)
0000: 200 Type set to I.
14:07:46.156000 [0-0] == Info: [WRITE] [OUT] wrote 20 header bytes -> 20
14:07:46.162000 [0-0] == Info: [WRITE] [PAUSE] writing 20/20 bytes of type 2 -> 0
14:07:46.170000 [0-0] == Info: [WRITE] download_write header(type=2, blen=20) -> 0
14:07:46.176000 [0-0] == Info: [WRITE] client_write(type=2, len=20) -> 0
14:07:46.181000 [0-0] == Info: [TCP] send(len=48) -> 48, err=0
14:07:46.187000 [0-0] == Info: [SSL] ossl_bio_cf_out_write(len=48) -> 48, err=0
14:07:46.192000 [0-0] => Send header, 19 bytes (0x13)
0000: STOR testfile.txt
14:07:46.199000 [0-0] == Info: [FTP] [STOR_TYPE] -> [STOR]
14:07:46.203000 [0-0] == Info: [FTP] [STOR] ftp_domore_getsock()
14:07:46.209000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:07:46.213000 [0-0] == Info: [TCP-1] adjust_pollset, !active, POLLIN fd=380
14:07:46.219000 [0-0] == Info: [MULTI] [DOING_MORE] multi_wait pollset[fd=360 IN, fd=380 IN], timeouts=0
14:07:46.236000 [0-0] == Info: [MULTI] [DOING_MORE] multi_perform(running=1)
14:07:46.241000 [0-0] == Info: [SSL-1] cf_connect()
14:07:46.245000 [0-0] == Info: [SSL-1] ossl_connect, step2
14:07:46.249000 [0-0] == Info: [TCP-1] recv(len=5) -> 5, err=0
14:07:46.254000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> 5, err=0
14:07:46.260000 [0-0] == Info: [TCP-1] recv(len=3361) -> 3361, err=0
14:07:46.265000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=3361) -> 3361, err=0
14:07:46.272000 [0-0] == Info: TLSv1.2 (IN), TLS handshake, Server hello (2):
14:07:46.277000 [0-0] <= Recv SSL data, 81 bytes (0x51)
0000: ...M..h....8.q+..L....&..D+.....q.:.t. .=..N....z.Om.=.bo.....1Y
0040: 1.....b.0........
14:07:46.289000 [0-0] == Info: TLSv1.2 (IN), TLS handshake, Certificate (11):
14:07:46.295000 [0-0] <= Recv SSL data, 2976 bytes (0xba0)
0000: ..........0...0..........].....{.0...*.H........0..1.0...U....US
0040: 1.0...U....Arizona1.0...U....Scottsdale1.0...U....GoDaddy.com, I
0080: nc.1-0+..U...$http://certs.godaddy.com/repository/1301..U...*Go
00c0: Daddy Secure Certificate Authority - G20...241003084213Z..251013
0100: 123219Z051301..U...*ftp.server.com0.
0140: ."0...*.H.............0..........h<_.S.mY....O...........2e..N..
0180: .9.+....~..GV%.:.R........D..5.7.N./..[.)....{=..`h...n7.>R2...{
01c0: .o....{.....UM.4..6..$.....&$z...H|s...r..W[...`n27M........r&.%
0200: .l.....l..e..../s.bd..q2C...M....g.,Lb....:..C..7.gk.D....=..M..
0240: M.yF.w...`..i .....V....!.....Q........M0..I0...U.......0.0...U.
0280: %..0...+.........+.......0...U...........09..U...2000..,.*.(http
02c0: ://crl.godaddy.com/gdig2s1-31057.crl0]..U. .V0T0H..`.H...m....09
0300: 07..+........+http://certificates.godaddy.com/repository/0...g..
0340: ...0v..+........j0h0$..+.....0...http://ocsp.godaddy.com/0@..+..
0380: ...0..4http://certificates.godaddy.com/repository/gdig2.crt0...U
03c0: .#..0...@..'..4.0.3..l...,..05..U....0,.*redacted.westeurope
0400: .cloudapp.azure.com0...U..............Tf?I&s..(?..0.....+.....y.
0440: .....o...k.i.v...N4.SrL.....?z....b...m0.....&:....Q.A......G0E.
0480:  MX;......tMA.L=#..4uS.P..h.......!...W\Q...Z.=....).&..i.......
04c0: .....w.}Y...x*{.ag|^....\..N..../....y.....Q.B-.....H0F.!..]....
0500: ..j..F.......#\.Ds.9..b..n.!...a.../.......W&...@z..G.R...;{..v.
0540: ...j.q.e...S...|"..\.....~T..L......Q.Cw.....G0E.!..^..n..:..1..
0580: ..........#Y.@..Si . .e(.?(....(.oh.ih.D,......".J[O.0...*.H....
05c0: .........]..9..n.x.-&..z.mT..m_.)..fp..H.l...;R.5.....2/..|.=..y
0600: .....t..x....wA.4...b.bQ......N9..G.l.....$../D.q...'[..G`f.-H.
0640: .;..07....s..x......-...d.Y.!...f............@05.&.*..t.c.....~'
0680: <..A..uw.gvHC..Xn-...U.....3....G....R.*.F.K.~..>z.H..B.l-Hj,...
06c0: ..G..}......0...0...........0...*.H........0..1.0...U....US1.0..
0700: .U....Arizona1.0...U....Scottsdale1.0...U....GoDaddy.com, Inc.11
0740: 0/..U...(Go Daddy Root Certificate Authority - G20...11050307000
0780: 0Z..310503070000Z0..1.0...U....US1.0...U....Arizona1.0...U....Sc
07c0: ottsdale1.0...U....GoDaddy.com, Inc.1-0+..U...$http://certs.goda
0800: ddy.com/repository/1301..U...*Go Daddy Secure Certificate Author
0840: ity - G20.."0...*.H.............0..............v...b.0d...l...b.
0880: ./.>e...b.<R...EKU.xkc.b...il.....L.E3......+..a.yW....?0<]G....
08c0: .7.AQ..KT.(.....08...&.fGcm.q&G.8GS.F......E...q..o....0:yO_LG..
0900: .[...`;..C...3N...'O.%........t.dW..DU.-*:>....2...d..:P.J..y3..
0940:  .....9.i.lcR.w...t.....PT5KiN.;.I.....R..........0...0...U.....
0980: ..0....0...U...........0...U......@..'..4.0.3..l...,..0...U.#..0
09c0: ...:....g(.....An .....04..+........(0&0$..+.....0...http://ocsp
0a00: .godaddy.com/05..U....0,0*.(.&.$http://crl.godaddy.com/gdroot-g2
0a40: .crl0F..U. .?0=0;..U. .0301..+........%https://certs.godaddy.com
0a80: /repository/0...*.H..............~l...8....K.._O..l>....P..s.W1.
0ac0: .../....[....yb....jcs1...H.;.-.]...|%O.V0..D.{,..^...a...*.....
0b00: .}.C.D..p........`..r.s$..."....bXD...%......b.Q...Q*...s.v.6...
0b40: .,....*...Mu..?.A.#}[K...X.F..``..}PA......../..T.D......3..-v6&
0b80: .....a....o.F.(..&}...........0.
14:07:46.563000 [0-0] == Info: TLSv1.2 (IN), TLS handshake, Server key exchange (12):
14:07:46.569000 [0-0] <= Recv SSL data, 300 bytes (0x12c)
0000: ...(... .Ns..D$.t?.._...b0 ......Y'.@./x...........$R.u..6B..:..
0040: U..Z.m.o.,..:.....t..,.......O.0?...6x......E.......].V..T......
0080: ..V..]..\...t.k.z..D..2=...t.8?.....V\...Mo.yo.<.#.D...gA...+H..
00c0: ..FEZ.p2DfS...l.Y.'$...y.a..$..O...Cbo!.......j|E......"..z.u...
0100: ....\vK.w..y.?.cT=X}.L.g.D....@!X.N.zHj..r..
14:07:46.602000 [0-0] == Info: TLSv1.2 (IN), TLS handshake, Server finished (14):
14:07:46.609000 [0-0] <= Recv SSL data, 4 bytes (0x4)
0000: ....
14:07:46.614000 [0-0] == Info: TLSv1.2 (OUT), TLS handshake, Client key exchange (16):
14:07:46.621000 [0-0] => Send SSL data, 37 bytes (0x25)
0000: ...! ...../&.'..;......+..g}.|...Y..r
14:07:46.629000 [0-0] == Info: TLSv1.2 (OUT), TLS change cipher, Change cipher spec (1):
14:07:46.636000 [0-0] => Send SSL data, 1 bytes (0x1)
0000: .
14:07:46.642000 [0-0] == Info: TLSv1.2 (OUT), TLS handshake, Finished (20):
14:07:46.649000 [0-0] => Send SSL data, 16 bytes (0x10)
0000: ....m........&2.
14:07:46.655000 [0-0] == Info: [TCP-1] send(len=93) -> 93, err=0
14:07:46.661000 [0-0] == Info: [SSL-1] ossl_bio_cf_out_write(len=93) -> 93, err=0
14:07:46.668000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:07:46.675000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:07:46.681000 [0-0] == Info: [SSL-1] SSL_connect() -> err=-1, detail=2
14:07:46.688000 [0-0] == Info: [SSL-1] SSL_connect() -> want recv
14:07:46.693000 [0-0] == Info: [SSL-1] cf_connect() -> 0, done=0
14:07:46.701000 [0-0] == Info: [SETUP-1] Curl_conn_connect(block=0) -> 0, done=0
14:07:46.707000 [0-0] == Info: [TCP] recv(len=5) -> 5, err=0
14:07:46.712000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
14:07:46.718000 [0-0] == Info: [TCP] recv(len=78) -> 78, err=0
14:07:46.723000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=78) -> 78, err=0
14:07:46.729000 [0-0] == Info: [SSL] cf_recv(len=900) -> 54, 0
14:07:46.733000 [0-0] <= Recv header, 54 bytes (0x36)
0000: 125 Data connection already open; Transfer starting.
14:07:46.742000 [0-0] == Info: [WRITE] [OUT] wrote 54 header bytes -> 54
14:07:46.748000 [0-0] == Info: [WRITE] [PAUSE] writing 54/54 bytes of type 2 -> 0
14:07:46.754000 [0-0] == Info: [WRITE] download_write header(type=2, blen=54) -> 0
14:07:46.759000 [0-0] == Info: [WRITE] client_write(type=2, len=54) -> 0
14:07:46.766000 [0-0] == Info: [FTP] InitiateTransfer()
14:07:46.771000 [0-0] == Info: [SSL-1] cf_connect()
14:07:46.775000 [0-0] == Info: [SSL-1] ossl_connect, step2
14:07:46.780000 [0-0] == Info: [TCP-1] recv(len=5) -> 5, err=0
14:07:46.785000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> 5, err=0
14:07:46.791000 [0-0] == Info: [TCP-1] recv(len=1) -> 1, err=0
14:07:46.796000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=1) -> 1, err=0
14:07:46.802000 [0-0] == Info: TLSv1.2 (IN), TLS change cipher, Change cipher spec (1):
14:07:46.809000 [0-0] <= Recv SSL data, 1 bytes (0x1)
0000: .
14:07:46.814000 [0-0] == Info: [TCP-1] recv(len=5) -> 5, err=0
14:07:46.817000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> 5, err=0
14:07:46.823000 [0-0] == Info: [TCP-1] recv(len=40) -> 40, err=0
14:07:46.829000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=40) -> 40, err=0
14:07:46.835000 [0-0] == Info: TLSv1.2 (IN), TLS handshake, Finished (20):
14:07:46.840000 [0-0] <= Recv SSL data, 16 bytes (0x10)
0000: ....xB.G.s..5jLR
14:07:46.846000 [0-0] == Info: [SSLS] find peer slot for ftp.server.com:21:CA-C:\Users\rmja\Downloads\curl-8.13.0_2-win64-mingw\curl-8.13.0_2-win64-mingw\bin\curl-ca-bundle.crt:IMPL-LibreSSL/4.0.0:G among 25 slots
14:07:46.864000 [0-0] == Info: [SSLS] added session for ftp.server.com:21:CA-C:\Users\rmja\Downloads\curl-8.13.0_2-win64-mingw\curl-8.13.0_2-win64-mingw\bin\curl-ca-bundle.crt:IMPL-LibreSSL/4.0.0:G [proto=0x303, valid_secs=7200, alpn=(nil), earlydata=0, quic_tp=no], peer has 1 sessions now
14:07:46.887000 [0-0] == Info: SSL connection using TLSv1.2 / ECDHE-RSA-AES256-GCM-SHA384 / [blank] / UNDEF
14:07:46.895000 [0-0] == Info: [SSL-1] ossl_connect, step3
14:07:46.900000 [0-0] == Info: Server certificate:
14:07:46.904000 [0-0] == Info:  subject: CN=ftp.server.com
14:07:46.909000 [0-0] == Info:  start date: Oct  3 08:42:13 2024 GMT
14:07:46.914000 [0-0] == Info:  expire date: Oct 13 12:32:19 2025 GMT
14:07:46.919000 [0-0] == Info:  subjectAltName: host "ftp.server.com" matched cert's "ftp.server.com"
14:07:46.931000 [0-0] == Info:  issuer: C=US; ST=Arizona; L=Scottsdale; O=GoDaddy.com, Inc.; OU=http://certs.godaddy.com/repository/; CN=Go Daddy Secure Certificate Authority - G2
14:07:46.945000 [0-0] == Info:  SSL certificate verify ok.
14:07:46.949000 [0-0] == Info:   Certificate level 0: Public key type ? (2048/112 Bits/secBits), signed using sha256WithRSAEncryption
14:07:46.959000 [0-0] == Info:   Certificate level 1: Public key type ? (2048/112 Bits/secBits), signed using sha256WithRSAEncryption
14:07:46.969000 [0-0] == Info:   Certificate level 2: Public key type ? (2048/112 Bits/secBits), signed using sha256WithRSAEncryption
14:07:46.979000 [0-0] == Info: [SSL-1] ossl_connect, done
14:07:46.983000 [0-0] == Info: [SSL-1] cf_connect() -> 0, done=1
14:07:46.987000 [0-0] == Info: [SETUP-1] Curl_conn_connect(block=1) -> 0, done=1
14:07:46.994000 [0-0] == Info: Connected 2nd connection to proxyip port 1080
14:07:47.001000 [0-0] == Info: [FTP] [STOR] -> [STOP]
14:07:47.004000 [0-0] == Info: [MULTI] [DOING_MORE] -> [DID]
  0    18    0     0    0     0      0      0 --:--:--  0:00:03 --:--:--     014:07:47.015000 [0-0] == Info: [MULTI] [DID] -> [PERFORMING]
14:07:47.019000 [0-0] == Info: [READ] add fread reader, len=18 -> 0
14:07:47.025000 [0-0] == Info: [READ] cr_in_read(len=18, total=18, read=18) -> 0, nread=18, eos=1
14:07:47.032000 [0-0] == Info: [READ] client_read(len=65536) -> 0, nread=18, eos=1
14:07:47.039000 [0-0] == Info: [TCP-1] send(len=47) -> 47, err=0
14:07:47.044000 [0-0] == Info: [SSL-1] ossl_bio_cf_out_write(len=47) -> 47, err=0
14:07:47.050000 [0-0] => Send data, 18 bytes (0x12)
0000: This is a testfile
14:07:47.055000 [0-0] == Info: [MULTI] [PERFORMING] shutdown start on secondary connection
14:07:47.063000 [0-0] == Info: [SETUP-1] shut down successfully
14:07:47.068000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:07:47.073000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:07:47.079000 [0-0] == Info: [SSL-1] SSL shutdown not sent, read -> -1
14:07:47.085000 [0-0] == Info: [SSL-1] send SSL close notify
14:07:47.089000 [0-0] == Info: [TCP-1] send(len=31) -> 31, err=0
14:07:47.095000 [0-0] == Info: [SSL-1] ossl_bio_cf_out_write(len=31) -> 31, err=0
14:07:47.100000 [0-0] == Info: TLSv1.2 (OUT), TLS alert, close notify (256):
14:07:47.106000 [0-0] => Send SSL data, 2 bytes (0x2)
0000: ..
14:07:47.110000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:07:47.115000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:07:47.121000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:07:47.125000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:07:47.131000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:07:47.135000 [0-0] == Info: [SSL-1] shut down not done yet
14:07:47.140000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:07:47.145000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:07:48.161000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:07:48.167000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:07:48.172000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:07:48.177000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:07:48.183000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:07:48.187000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:07:48.192000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0  100    18      0      3  0:00:06  0:00:04  0:00:02     314:07:48.202000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:07:48.207000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:07:49.214000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:07:49.220000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:07:49.225000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:07:49.230000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:07:49.234000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:07:49.239000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:07:49.243000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0  100    18      0      2  0:00:09  0:00:06  0:00:03     314:07:49.251000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:07:49.257000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:07:50.273000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:07:50.279000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:07:50.283000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:07:50.290000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:07:50.294000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:07:50.300000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:07:50.304000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0  100    18      0      2  0:00:09  0:00:07  0:00:02     314:07:50.314000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:07:50.321000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:07:51.343000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:07:51.352000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:07:51.356000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:07:51.362000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:07:51.367000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:07:51.372000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:07:51.376000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0  100    18      0      2  0:00:09  0:00:08  0:00:01     214:07:51.387000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:07:51.393000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:07:52.400000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:07:52.406000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:07:52.410000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:07:52.418000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:07:52.422000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:07:52.427000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:07:52.432000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0  100    18      0      1  0:00:18  0:00:09  0:00:09     314:07:52.442000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:07:52.447000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:07:53.467000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:07:53.474000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:07:53.478000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:07:53.484000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:07:53.489000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:07:53.494000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:07:53.497000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0  100    18      0      1  0:00:18  0:00:10  0:00:08     014:07:53.507000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:07:53.511000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:07:54.517000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:07:54.523000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:07:54.528000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:07:54.534000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:07:54.539000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:07:54.544000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:07:54.548000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0  100    18      0      1  0:00:18  0:00:11  0:00:07     014:07:54.557000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:07:54.562000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:07:55.571000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:07:55.577000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:07:55.584000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:07:55.590000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:07:55.594000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:07:55.600000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:07:55.605000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0  100    18      0      1  0:00:18  0:00:12  0:00:06     014:07:55.613000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:07:55.619000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:07:56.627000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:07:56.634000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:07:56.638000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:07:56.645000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:07:56.650000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:07:56.655000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:07:56.659000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0  100    18      0      1  0:00:18  0:00:13  0:00:05     014:07:56.670000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:07:56.675000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:07:57.683000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:07:57.692000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:07:57.697000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:07:57.705000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:07:57.709000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:07:57.714000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:07:57.719000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0  100    18      0      1  0:00:18  0:00:14  0:00:04     014:07:57.728000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:07:57.732000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:07:58.740000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:07:58.746000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:07:58.750000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:07:58.755000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:07:58.761000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:07:58.765000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:07:58.770000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0  100    18      0      1  0:00:18  0:00:15  0:00:03     014:07:58.780000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:07:58.784000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:07:59.797000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:07:59.803000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:07:59.807000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:07:59.813000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:07:59.817000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:07:59.823000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:07:59.828000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0  100    18      0      1  0:00:18  0:00:16  0:00:02     014:07:59.838000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:07:59.842000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:00.855000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:00.861000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:00.865000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:00.871000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:00.876000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:00.881000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:00.886000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0  100    18      0      1  0:00:18  0:00:17  0:00:01     014:08:00.895000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:00.901000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:01.920000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:01.927000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:01.932000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:01.938000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:01.942000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:01.947000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:01.952000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:18 --:--:--     014:08:01.961000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:01.966000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:02.981000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:02.990000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:03.000000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:03.010000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:03.019000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:03.030000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:03.037000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:19 --:--:--     014:08:03.052000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:03.060000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:04.084000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:04.092000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:04.098000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:04.107000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:04.113000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:04.120000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:04.128000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:20 --:--:--     014:08:04.144000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:04.152000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:05.168000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:05.174000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:05.180000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:05.186000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:05.191000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:05.201000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:05.207000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:21 --:--:--     014:08:05.222000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:05.230000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:06.251000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:06.258000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:06.263000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:06.267000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:06.271000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:06.276000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:06.281000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:23 --:--:--     014:08:06.292000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:06.298000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:07.310000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:07.315000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:07.321000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:07.328000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:07.332000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:07.337000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:07.342000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:24 --:--:--     014:08:07.357000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:07.362000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:08.382000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:08.392000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:08.399000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:08.405000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:08.413000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:08.420000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:08.426000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:25 --:--:--     014:08:08.437000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:08.446000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:09.469000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:09.478000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:09.483000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:09.491000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:09.497000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:09.504000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:09.509000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:26 --:--:--     014:08:09.521000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:09.527000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:10.541000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:10.550000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:10.558000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:10.565000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:10.574000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:10.582000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:10.589000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:27 --:--:--     014:08:10.608000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:10.615000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:11.639000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:11.646000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:11.653000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:11.661000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:11.667000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:11.677000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:11.684000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:28 --:--:--     014:08:11.697000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:11.702000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:12.711000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:12.717000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:12.723000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:12.731000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:12.735000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:12.741000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:12.747000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:29 --:--:--     014:08:12.760000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:12.766000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:13.782000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:13.789000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:13.794000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:13.799000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:13.804000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:13.808000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:13.813000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:30 --:--:--     014:08:13.824000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:13.829000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:14.837000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:14.846000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:14.852000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:14.860000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:14.866000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:14.875000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:14.881000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:31 --:--:--     014:08:14.896000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:14.902000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:15.919000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:15.929000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:15.937000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:15.944000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:15.950000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:15.957000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:15.964000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:32 --:--:--     014:08:15.975000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:15.983000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:16.997000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:17.006000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:17.012000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:17.018000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:17.022000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:17.026000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:17.031000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:33 --:--:--     014:08:17.045000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:17.050000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:18.068000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:18.077000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:18.085000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:18.091000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:18.096000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:18.102000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:18.109000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:34 --:--:--     014:08:18.123000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:18.129000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:19.141000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:19.153000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:19.160000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:19.168000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:19.179000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:19.186000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:19.192000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:35 --:--:--     014:08:19.207000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:19.215000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:20.234000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:20.246000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:20.252000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:20.258000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:20.265000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:20.272000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:20.279000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:37 --:--:--     014:08:20.295000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:20.301000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:21.324000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:21.333000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:21.340000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:21.346000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:21.351000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:21.359000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:21.363000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:38 --:--:--     014:08:21.378000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:21.384000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:22.406000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:22.415000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:22.421000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:22.426000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:22.432000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:22.440000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:22.446000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:39 --:--:--     014:08:22.460000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:22.469000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:23.493000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:23.502000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:23.509000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:23.517000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:23.523000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:23.531000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:23.537000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:40 --:--:--     014:08:23.550000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:23.557000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:24.575000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:24.585000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:24.591000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:24.597000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:24.601000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:24.607000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:24.611000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:41 --:--:--     014:08:24.625000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:24.631000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:25.647000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:25.654000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:25.662000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:25.670000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:25.674000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:25.680000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:25.686000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:42 --:--:--     014:08:25.699000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:25.705000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:26.719000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:26.729000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:26.735000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:26.741000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:26.747000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:26.753000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:26.759000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:43 --:--:--     014:08:26.772000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:26.777000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:27.802000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:27.811000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:27.819000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:27.825000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:27.830000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:27.837000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:27.842000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:44 --:--:--     014:08:27.855000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:27.862000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:28.885000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:28.896000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:28.901000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:28.907000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:28.913000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:28.920000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:28.926000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:45 --:--:--     014:08:28.939000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:28.945000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:29.967000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:29.975000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:29.984000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:29.991000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:29.999000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:30.007000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:30.013000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:46 --:--:--     014:08:30.026000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:30.033000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:31.057000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:31.066000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:31.074000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:31.081000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:31.086000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:31.092000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:31.099000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:47 --:--:--     014:08:31.111000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:31.117000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:32.129000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:32.139000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:32.145000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:32.153000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:32.159000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:32.166000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:32.172000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:48 --:--:--     014:08:32.187000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:32.192000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:33.216000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:33.230000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:33.236000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:33.244000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:33.251000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:33.259000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:33.264000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:50 --:--:--     014:08:33.281000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:33.289000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:34.307000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:34.315000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:34.321000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:34.329000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:34.334000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:34.340000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:34.346000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:51 --:--:--     014:08:34.359000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:34.369000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:35.391000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:35.399000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:35.404000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:35.413000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:35.419000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:35.426000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:35.432000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:52 --:--:--     014:08:35.442000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:35.448000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:36.464000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:36.472000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:36.479000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:36.488000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:36.496000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:36.507000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:36.515000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:53 --:--:--     014:08:36.530000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:36.536000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:37.552000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:37.560000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:37.565000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:37.572000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:37.577000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:37.582000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:37.587000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:54 --:--:--     014:08:37.598000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:37.604000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:38.623000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:38.630000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:38.636000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:38.642000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:38.646000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:38.651000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:38.655000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:55 --:--:--     014:08:38.670000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:38.677000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:39.701000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:39.711000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:39.715000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:39.722000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:39.729000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:39.734000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:39.741000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:56 --:--:--     014:08:39.755000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:39.762000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:40.776000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:40.783000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:40.789000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:40.797000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:40.801000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:40.807000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:40.812000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:57 --:--:--     014:08:40.822000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:40.826000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:41.847000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:41.854000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:41.858000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:41.864000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:41.868000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:41.872000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:41.876000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:58 --:--:--     014:08:41.885000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:41.889000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:42.902000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:42.909000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:42.914000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:42.919000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:42.923000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:42.927000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:42.931000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:00:59 --:--:--     014:08:42.941000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:42.945000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:43.966000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:43.972000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:43.977000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:43.983000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:43.987000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:43.992000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:43.996000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:01:00 --:--:--     014:08:44.006000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:44.010000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:45.019000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:45.025000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:45.029000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:45.035000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:45.040000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:45.044000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:45.047000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:01:01 --:--:--     014:08:45.058000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:45.062000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:46.084000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:46.093000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:46.100000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:46.109000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:46.119000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:46.128000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:46.135000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:01:02 --:--:--     014:08:46.157000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:46.168000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:47.179000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:47.186000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:47.191000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:47.198000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:47.203000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:47.208000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:47.213000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:01:03 --:--:--     014:08:47.224000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:47.230000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:48.249000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:48.256000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:48.261000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:48.267000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:48.272000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:48.277000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:48.282000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:01:05 --:--:--     014:08:48.292000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:48.297000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:49.308000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:49.314000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:49.319000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:49.325000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:49.329000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:49.335000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:49.340000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:01:06 --:--:--     014:08:49.355000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:49.361000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:50.369000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:50.376000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:50.381000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:50.387000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:50.392000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:50.397000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:50.401000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:01:07 --:--:--     014:08:50.409000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:50.413000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:51.423000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:51.430000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:51.434000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:51.441000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:51.445000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:51.450000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:51.454000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:01:08 --:--:--     014:08:51.463000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:51.467000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:52.482000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:52.490000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:52.494000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:52.501000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:52.504000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:52.509000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:52.513000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:01:09 --:--:--     014:08:52.523000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:52.527000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:53.534000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:53.540000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:53.544000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:53.551000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:53.555000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:53.559000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:53.563000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:01:10 --:--:--     014:08:53.574000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:53.578000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:54.585000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:54.592000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:54.597000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:54.602000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:54.606000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:54.609000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:54.615000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:01:11 --:--:--     014:08:54.625000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:54.631000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:55.644000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:55.651000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:55.656000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:55.663000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:55.667000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:55.672000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:55.677000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:01:12 --:--:--     014:08:55.685000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:55.690000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:56.705000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:56.712000 [0-0] == Info: [TCP-1] recv(len=5) -> -1, err=81
14:08:56.716000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> -1, err=81
14:08:56.723000 [0-0] == Info: [SSL-1] SSL shutdown read -> -1
14:08:56.727000 [0-0] == Info: [SSL-1] SSL shutdown sent, want receive
14:08:56.731000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=0
14:08:56.735000 [0-0] == Info: [SSL-1] shut down not done yet
100    18    0     0    0    18      0      0 --:--:--  0:01:13 --:--:--     014:08:56.745000 [0-0] == Info: [SSL-1] adjust_pollset, POLLIN fd=380
14:08:56.749000 [0-0] == Info: [MULTI] [PERFORMING] multi_wait pollset[fd=380 IN], timeouts=0
14:08:57.719000 [0-0] == Info: [MULTI] [PERFORMING] multi_perform(running=1)
14:08:57.726000 [0-0] == Info: [TCP-1] recv(len=5) -> 0, err=0
14:08:57.731000 [0-0] == Info: [SSL-1] ossl_bio_cf_in_read(len=5) -> 0, err=0
14:08:57.737000 [0-0] == Info: [SSL-1] SSL shutdown read -> 0
14:08:57.741000 [0-0] == Info: [SSL-1] SSL shutdown, ignore recv error: 'SSL_ERROR_SYSCALL', errno 0
14:08:57.747000 [0-0] == Info: [SSL-1] cf_shutdown -> 0, done=1
14:08:57.752000 [0-0] == Info: [SSL-1] shut down successfully
14:08:57.757000 [0-0] == Info: [HAPPY-EYEBALLS-1] shut down successfully
14:08:57.761000 [0-0] == Info: [TCP-1] cf_socket_shutdown, fd=380
14:08:57.766000 [0-0] == Info: [TCP-1] shut down successfully
14:08:57.769000 [0-0] == Info: upload completely sent off: 18 bytes
100    18    0     0    0    18      0      0 --:--:--  0:01:14 --:--:--     014:08:57.779000 [0-0] == Info: [MULTI] [PERFORMING] -> [DONE]
14:08:57.783000 [0-0] == Info: [MULTI] [DONE] multi_done: status: 0 prem: 0 done: 0
14:08:57.789000 [0-0] == Info: Remembering we are in dir ""
14:08:57.793000 [0-0] == Info: [FTP] [STOP] closing DATA connection
14:08:57.797000 [0-0] == Info: [SETUP-1] close
14:08:57.799000 [0-0] == Info: [HAPPY-EYEBALLS-1] close
14:08:57.803000 [0-0] == Info: [TCP-1] cf_socket_close, fd=380
14:08:57.807000 [0-0] == Info: [MULTI] [DONE] Curl_multi_will_close fd=380
14:08:57.812000 [0-0] == Info: [TCP-1] destroy
14:08:57.815000 [0-0] == Info: [HAPPY-EYEBALLS-1] destroy
14:08:57.819000 [0-0] == Info: [SETUP-1] destroy
14:08:57.822000 [0-0] == Info: [FTP] getFTPResponse start
14:08:57.825000 [0-0] == Info: [TCP] recv(len=5) -> 5, err=0
14:08:57.830000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=5) -> 5, err=0
14:08:57.835000 [0-0] == Info: [TCP] recv(len=106) -> 106, err=0
14:08:57.839000 [0-0] == Info: [SSL] ossl_bio_cf_in_read(len=106) -> 106, err=0
14:08:57.845000 [0-0] == Info: [SSL] cf_recv(len=900) -> 82, 0
14:08:57.850000 [0-0] <= Recv header, 82 bytes (0x52)
0000: 425 Data channel timed out due to not meeting the minimum bandwi
0040: dth requirement.
14:08:57.860000 [0-0] == Info: [WRITE] [OUT] wrote 82 header bytes -> 82
14:08:57.865000 [0-0] == Info: [WRITE] [PAUSE] writing 82/82 bytes of type 2 -> 0
14:08:57.870000 [0-0] == Info: [WRITE] download_write header(type=2, blen=82) -> 0
14:08:57.876000 [0-0] == Info: [WRITE] client_write(type=2, len=82) -> 0
14:08:57.882000 [0-0] == Info: [FTP] getFTPResponse -> result=0, nread=82, ftpcode=425
14:08:57.889000 [0-0] == Info: server did not report OK, got 425
14:08:57.894000 [0-0] == Info: [FTP] [STOP] done, result=18
100    18    0     0    0    18      0      0 --:--:--  0:01:14 --:--:--     0
14:08:57.904000 [0-0] == Info: [WRITE] [OUT] done
14:08:57.907000 [0-0] == Info: Connection #0 to host proxyip left intact
14:08:57.912000 [0-0] == Info: [MULTI] [DONE] -> [COMPLETED]
14:08:57.916000 [0-0] == Info: [MULTI] [COMPLETED] -> [MSGSENT]
14:08:57.921000 [0-0] == Info: [MULTI] [COMPLETED] removed, transfers=0
curl: (18) server did not report OK, got 425
PS C:\Users\rmja\Downloads\curl-8.13.0_2-win64-mingw\curl-8.13.0_2-win64-mingw\bin>

@icing
Copy link
Contributor

icing commented Apr 22, 2025

@rmja thanks. The problem here is a bug in Microsoft's FTP server handling of the TLS DATA connection:

  • curl opens the DATA connection and negotiates TLS with the server
  • curl uploads the file
  • curl sends the TLS "close notify" message, telling the server that the upload is complete and well.
  • curl then expects the "close notify" from the server, indicating it has received everything.
  • MS FTP does not send the TLS close notify, seemingly waiting on curl to close the TCP connection
  • MS FTP times out waiting on the TCP close and closes it from its side
  • MS FTP reports on the control connection that the upload was "too slow" and has failed.

I am not certain what we can do in curl about this...

@bagder bagder added the FTP label Apr 22, 2025
@rmja
Copy link
Author

rmja commented Apr 22, 2025

This seem related to the issue I had in #14843 which was fixed in #14848 where the close notify from the server had a 2 second timeout window. Is it possible to restore this behavior, instead of waiting for the server to time out?

Something like this:

  • curl opens the DATA connection and negotiates TLS with the server
  • curl uploads the file
  • curl sends the TLS "close notify" message, telling the server that the upload is complete and well.
  • curl then expects the "close notify" from the server, indicating it has received everything WITHIN 2 seconds.
  • MS FTP does not send the TLS close notify, seemingly waiting on curl to close the TCP connection so we simply continue
  • Run any POSTQUOTE as if the previous upload succeeded.

@icing
Copy link
Contributor

icing commented Apr 22, 2025

@rmja I think I found a problem in our handling of shutdown timeouts. Could you give #17135 a try on your system?

Background: there should be a default timeout of 2 seconds for connection shutdown, but your log clearly showed that curl was willing to wait forever on the reply for the server.

@rmja
Copy link
Author

rmja commented Apr 22, 2025

Sounds promising!

I can try it when it gets merged and is availble at https://github.com/curl/curl-for-win/actions/workflows/daily.yml.
Unfortunately I don't have the setup to produce a build myself.

@icing icing mentioned this issue Apr 22, 2025
Closed
@bagder bagder closed this as completed in 2a25abe Apr 22, 2025
@vszakats
Copy link
Member

Ref #15300, which sounds related.

@rmja
Copy link
Author

rmja commented Apr 23, 2025

@icing I can confirm that the curl binary now works and that replacing the dll library in the daily build resolves this issue. perform() now returns OK and the POSTQUOTE is run.

@bagder
Copy link
Member

bagder commented Apr 23, 2025

Lovely, thanks a lot for confirming!

nbaws pushed a commit to nbaws/curl that referenced this issue Apr 26, 2025
@icing @nbaws
connect: shutdown timer fix
284c269 
Fix a bug in timeout handling for connection shutdowns that led to
default timeout of 2 seconds not being in effect.

Only set the shutdown timeout expiry when operating on a non-admin
transfers. Admin handles are only temproarily tied to a connection.

Fixes curl#17130
Reported-by: Rasmus Melchior Jacobsen
Closes curl#17135
nbaws pushed a commit to nbaws/curl that referenced this issue Apr 26, 2025
@icing @nbaws
connect: shutdown timer fix
d52b005 
Fix a bug in timeout handling for connection shutdowns that led to
default timeout of 2 seconds not being in effect.

Only set the shutdown timeout expiry when operating on a non-admin
transfers. Admin handles are only temproarily tied to a connection.

Fixes curl#17130
Reported-by: Rasmus Melchior Jacobsen
Closes curl#17135
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
FTP
Milestone
No milestone
Development

No branches or pull requests
4 participants
@icing @bagder @vszakats @rmja