Статус: Новичок
Группы: Участники
Зарегистрирован: 27.02.2025(UTC) Сообщений: 7
|
После добавления Цитата:tls_client_disable_extension_SigAlgsCert=1 соединение устанавливается, но дальше данные не проходят через тунель. Цитата:2025.02.28 12:12:04 LOG5[24884:1744]: Handshake was successful 2025.02.28 12:12:04 LOG5[24884:1744]: PerformClientHandshake finish 2025.02.28 12:12:04 LOG5[24884:1744]: Verify_level = 0, skipping Server certificate verification 2025.02.28 12:12:04 LOG7[24884:1744]: add ssl read socket to pool 2025.02.28 12:12:04 LOG7[24884:1744]: ssl_rd = 1, c->ssl_ptr = 0,c->sock_ptr=0,want_rd = 0 2025.02.28 12:12:04 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:12:04 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = -1 ((sock_rd && ssl_rd)=1) c->ssl_ptr = 0 c->sock_ptr=0 2025.02.28 12:12:04 LOG7[24884:1744]: data reciev from socket = 193 2025.02.28 12:12:04 LOG7[24884:1744]: add ssl read socket to pool 2025.02.28 12:12:04 LOG7[24884:1744]: ssl_rd = 1, c->ssl_ptr = 0,c->sock_ptr=193,want_rd = 0 2025.02.28 12:12:04 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:12:04 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = -1 ((sock_rd && ssl_rd)=1) c->ssl_ptr = 0 c->sock_ptr=c1 2025.02.28 12:12:04 LOG5[24884:1744]: SSPI_write start 2025.02.28 12:12:04 LOG7[24884:1744]: SSPI_write data is GET 2025.02.28 12:12:04 LOG7[24884:1744]: send all data after encrypt 2025.02.28 12:12:04 LOG7[24884:1744]: data send to ssl_socket =193 2025.02.28 12:12:04 LOG7[24884:1744]: add ssl read socket to pool 2025.02.28 12:12:04 LOG7[24884:1744]: ssl_rd = 1, c->ssl_ptr = 0,c->sock_ptr=0,want_rd = 0 2025.02.28 12:12:04 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:12:04 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = -1 ((sock_rd && ssl_rd)=1) c->ssl_ptr = 0 c->sock_ptr=0 2025.02.28 12:12:04 LOG7[24884:1744]: SSPI_read start 2025.02.28 12:12:04 LOG7[24884:1744]: recv ok on SSPI_read err= 1525 2025.02.28 12:12:04 LOG5[24884:1744]: Received 1525 bytes from ssl socket 2025.02.28 12:12:04 LOG7[24884:1744]: SSPI_read data in ssl_buff is HTTP 2025.02.28 12:12:04 LOG7[24884:1744]: data read from ssl_sock =1504 2025.02.28 12:12:04 LOG7[24884:1744]: add ssl read socket to pool 2025.02.28 12:12:04 LOG7[24884:1744]: ssl_rd = 1, c->ssl_ptr = 1504,c->sock_ptr=0,want_rd = 0 2025.02.28 12:12:04 LOG7[24884:1744]: add write socket to poll 2025.02.28 12:12:04 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:12:04 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = -1 ((sock_rd && ssl_rd)=1) c->ssl_ptr = 5e0 c->sock_ptr=0 2025.02.28 12:12:04 LOG7[24884:1744]: data send to socket = 1504 2025.02.28 12:12:04 LOG7[24884:1744]: add ssl read socket to pool 2025.02.28 12:12:04 LOG7[24884:1744]: ssl_rd = 1, c->ssl_ptr = 0,c->sock_ptr=0,want_rd = 0 2025.02.28 12:12:04 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:12:04 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = -1 ((sock_rd && ssl_rd)=1) c->ssl_ptr = 0 c->sock_ptr=0 2025.02.28 12:12:04 LOG7[24884:1744]: SSPI_read start 2025.02.28 12:12:04 LOG7[24884:1744]: recv ok on SSPI_read err= 6850 2025.02.28 12:12:04 LOG5[24884:1744]: Received 6850 bytes from ssl socket 2025.02.28 12:12:04 LOG7[24884:1744]: Zerro bytes read 2025.02.28 12:12:04 LOG7[24884:1744]: add ssl read socket to pool 2025.02.28 12:12:04 LOG7[24884:1744]: ssl_rd = 1, c->ssl_ptr = 0,c->sock_ptr=0,want_rd = 1 2025.02.28 12:12:04 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:12:04 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = -1 ((sock_rd && ssl_rd)=1) c->ssl_ptr = 0 c->sock_ptr=0 2025.02.28 12:12:04 LOG7[24884:1744]: SSPI_read start 2025.02.28 12:12:04 LOG7[24884:1744]: add data from last call = 6850 2025.02.28 12:12:04 LOG7[24884:1744]: recv ok on SSPI_read err= 3050 2025.02.28 12:12:04 LOG5[24884:1744]: Received 3050 bytes from ssl socket 2025.02.28 12:12:04 LOG7[24884:1744]: Zerro bytes read 2025.02.28 12:12:04 LOG7[24884:1744]: add ssl read socket to pool 2025.02.28 12:12:04 LOG7[24884:1744]: ssl_rd = 1, c->ssl_ptr = 0,c->sock_ptr=0,want_rd = 1 2025.02.28 12:12:04 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:12:04 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = -1 ((sock_rd && ssl_rd)=1) c->ssl_ptr = 0 c->sock_ptr=0 2025.02.28 12:12:04 LOG7[24884:1744]: SSPI_read start 2025.02.28 12:12:04 LOG7[24884:1744]: add data from last call = 9900 2025.02.28 12:12:04 LOG7[24884:1744]: recv ok on SSPI_read err= 7965 2025.02.28 12:12:04 LOG5[24884:1744]: Received 7965 bytes from ssl socket 2025.02.28 12:12:04 LOG7[24884:1744]: Recieve 1460 bytes EXTRA_DATA 2025.02.28 12:12:04 LOG7[24884:1744]: data read return 16384, c->ssl_ptr = 0 2025.02.28 12:12:04 LOG7[24884:1744]: data read from ssl_sock =16384 2025.02.28 12:12:04 LOG7[24884:1744]: add write socket to poll 2025.02.28 12:12:04 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:12:04 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = -1 ((sock_rd && ssl_rd)=1) c->ssl_ptr = 4000 c->sock_ptr=0 2025.02.28 12:12:04 LOG7[24884:1744]: data send to socket = 16384 2025.02.28 12:12:04 LOG7[24884:1744]: SSPI_read start 2025.02.28 12:12:04 LOG7[24884:1744]: add data from last call = 1460 2025.02.28 12:12:04 LOG7[24884:1744]: recv ok on SSPI_read err= -1 2025.02.28 12:12:04 LOG3[24884:1744]: Reading failed. recv returned: -1 errno =0 2025.02.28 12:12:04 LOG7[24884:1744]: Win issue with nonblocking socket, lets try wait and read again 2025.02.28 12:12:04 LOG7[24884:1744]: Zerro bytes read 2025.02.28 12:12:04 LOG7[24884:1744]: add ssl read socket to pool 2025.02.28 12:12:04 LOG7[24884:1744]: ssl_rd = 1, c->ssl_ptr = 0,c->sock_ptr=0,want_rd = 1 2025.02.28 12:12:04 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:12:04 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = -1 ((sock_rd && ssl_rd)=1) c->ssl_ptr = 0 c->sock_ptr=0 2025.02.28 12:12:04 LOG7[24884:1744]: SSPI_read start 2025.02.28 12:12:04 LOG7[24884:1744]: add data from last call = 1460 2025.02.28 12:12:04 LOG7[24884:1744]: recv ok on SSPI_read err= 7240 2025.02.28 12:12:04 LOG5[24884:1744]: Received 7240 bytes from ssl socket 2025.02.28 12:12:04 LOG7[24884:1744]: Zerro bytes read 2025.02.28 12:12:04 LOG7[24884:1744]: add ssl read socket to pool 2025.02.28 12:12:04 LOG7[24884:1744]: ssl_rd = 1, c->ssl_ptr = 0,c->sock_ptr=0,want_rd = 1 2025.02.28 12:12:04 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:12:04 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = -1 ((sock_rd && ssl_rd)=1) c->ssl_ptr = 0 c->sock_ptr=0 2025.02.28 12:12:04 LOG7[24884:1744]: SSPI_read start 2025.02.28 12:12:04 LOG7[24884:1744]: add data from last call = 8700 2025.02.28 12:12:04 LOG7[24884:1744]: recv ok on SSPI_read err= 9733 2025.02.28 12:12:04 LOG5[24884:1744]: Received 9733 bytes from ssl socket 2025.02.28 12:12:04 LOG7[24884:1744]: Recieve 4380 bytes EXTRA_DATA 2025.02.28 12:12:04 LOG7[24884:1744]: data read return 14032, c->ssl_ptr = 0 2025.02.28 12:12:04 LOG7[24884:1744]: data read from ssl_sock =14032 2025.02.28 12:12:04 LOG7[24884:1744]: add ssl read socket to pool 2025.02.28 12:12:04 LOG7[24884:1744]: ssl_rd = 1, c->ssl_ptr = 14032,c->sock_ptr=0,want_rd = 1 2025.02.28 12:12:04 LOG7[24884:1744]: add write socket to poll 2025.02.28 12:12:04 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:12:04 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = -1 ((sock_rd && ssl_rd)=1) c->ssl_ptr = 36d0 c->sock_ptr=0 2025.02.28 12:12:04 LOG7[24884:1744]: data send to socket = 14032 2025.02.28 12:12:04 LOG7[24884:1744]: SSPI_read start 2025.02.28 12:12:04 LOG7[24884:1744]: add data from last call = 4380 2025.02.28 12:12:04 LOG7[24884:1744]: recv ok on SSPI_read err= -1 2025.02.28 12:12:04 LOG3[24884:1744]: Reading failed. recv returned: -1 errno =0 2025.02.28 12:12:04 LOG7[24884:1744]: Win issue with nonblocking socket, lets try wait and read again 2025.02.28 12:12:04 LOG7[24884:1744]: Zerro bytes read 2025.02.28 12:12:04 LOG7[24884:1744]: add ssl read socket to pool 2025.02.28 12:12:04 LOG7[24884:1744]: ssl_rd = 1, c->ssl_ptr = 0,c->sock_ptr=0,want_rd = 1 2025.02.28 12:12:04 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:12:04 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = -1 ((sock_rd && ssl_rd)=1) c->ssl_ptr = 0 c->sock_ptr=0 2025.02.28 12:12:04 LOG7[24884:1744]: SSPI_read start 2025.02.28 12:12:04 LOG7[24884:1744]: add data from last call = 4380 2025.02.28 12:12:04 LOG7[24884:1744]: recv ok on SSPI_read err= 12025 2025.02.28 12:12:04 LOG5[24884:1744]: Received 12025 bytes from ssl socket 2025.02.28 12:12:04 LOG7[24884:1744]: SSPI_read data in ssl_buff is 7ed0 2025.02.28 12:12:04 LOG7[24884:1744]: data read from ssl_sock =16384 2025.02.28 12:12:04 LOG7[24884:1744]: add write socket to poll 2025.02.28 12:12:04 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:12:04 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = -1 ((sock_rd && ssl_rd)=1) c->ssl_ptr = 4000 c->sock_ptr=0 2025.02.28 12:12:04 LOG7[24884:1744]: data send to socket = 16384 2025.02.28 12:12:04 LOG7[24884:1744]: add ssl read socket to pool 2025.02.28 12:12:04 LOG7[24884:1744]: ssl_rd = 1, c->ssl_ptr = 0,c->sock_ptr=0,want_rd = 0 2025.02.28 12:12:04 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:12:04 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = -1 ((sock_rd && ssl_rd)=1) c->ssl_ptr = 0 c->sock_ptr=0 2025.02.28 12:12:04 LOG7[24884:1744]: SSPI_read start 2025.02.28 12:12:04 LOG7[24884:1744]: recv ok on SSPI_read err= 11680 2025.02.28 12:12:04 LOG5[24884:1744]: Received 11680 bytes from ssl socket 2025.02.28 12:12:04 LOG7[24884:1744]: Zerro bytes read 2025.02.28 12:12:04 LOG7[24884:1744]: add ssl read socket to pool 2025.02.28 12:12:04 LOG7[24884:1744]: ssl_rd = 1, c->ssl_ptr = 0,c->sock_ptr=0,want_rd = 1 2025.02.28 12:12:04 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:12:04 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = -1 ((sock_rd && ssl_rd)=1) c->ssl_ptr = 0 c->sock_ptr=0 2025.02.28 12:12:04 LOG7[24884:1744]: SSPI_read start 2025.02.28 12:12:04 LOG7[24884:1744]: add data from last call = 11680 2025.02.28 12:12:04 LOG7[24884:1744]: recv ok on SSPI_read err= 4455 2025.02.28 12:12:04 LOG5[24884:1744]: Received 4455 bytes from ssl socket 2025.02.28 12:12:04 LOG7[24884:1744]: Recieve 26 bytes EXTRA_DATA 2025.02.28 12:12:04 LOG7[24884:1744]: data read return 16088, c->ssl_ptr = 0 2025.02.28 12:12:04 LOG7[24884:1744]: data read from ssl_sock =16088 2025.02.28 12:12:04 LOG7[24884:1744]: add ssl read socket to pool 2025.02.28 12:12:04 LOG7[24884:1744]: ssl_rd = 1, c->ssl_ptr = 16088,c->sock_ptr=0,want_rd = 1 2025.02.28 12:12:04 LOG7[24884:1744]: add write socket to poll 2025.02.28 12:12:04 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:12:04 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = -1 ((sock_rd && ssl_rd)=1) c->ssl_ptr = 3ed8 c->sock_ptr=0 2025.02.28 12:12:04 LOG7[24884:1744]: data send to socket = 16088 2025.02.28 12:12:04 LOG7[24884:1744]: SSPI_read start 2025.02.28 12:12:04 LOG7[24884:1744]: add data from last call = 26 2025.02.28 12:12:04 LOG7[24884:1744]: SSPI_read data in ssl_buff is 0
2025.02.28 12:12:04 LOG7[24884:1744]: data read from ssl_sock =5 2025.02.28 12:12:04 LOG7[24884:1744]: add ssl read socket to pool 2025.02.28 12:12:04 LOG7[24884:1744]: ssl_rd = 1, c->ssl_ptr = 5,c->sock_ptr=0,want_rd = 0 2025.02.28 12:12:04 LOG7[24884:1744]: add write socket to poll 2025.02.28 12:12:04 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:12:04 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = -1 ((sock_rd && ssl_rd)=1) c->ssl_ptr = 5 c->sock_ptr=0 2025.02.28 12:12:04 LOG7[24884:1744]: data send to socket = 5 2025.02.28 12:12:04 LOG7[24884:1744]: add ssl read socket to pool 2025.02.28 12:12:04 LOG7[24884:1744]: ssl_rd = 1, c->ssl_ptr = 0,c->sock_ptr=0,want_rd = 0 2025.02.28 12:12:04 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:12:04 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = -1 ((sock_rd && ssl_rd)=1) c->ssl_ptr = 0 c->sock_ptr=0 2025.02.28 12:13:09 LOG7[24884:1744]: SSPI_read start 2025.02.28 12:13:09 LOG7[24884:1744]: recv ok on SSPI_read err= 23 2025.02.28 12:13:09 LOG5[24884:1744]: Received 23 bytes from ssl socket 2025.02.28 12:13:09 LOG5[24884:1744]: SEC_I_CONTEXT_EXPIRED, 2025.02.28 12:13:09 LOG5[24884:1744]: SSPI_read return SEC_I_CONTEXT_EXPIRED 2025.02.28 12:13:09 LOG7[24884:1744]: Socket write shutdown 2025.02.28 12:13:09 LOG7[24884:1744]: c->ssl_ptr = 0 2025.02.28 12:13:09 LOG7[24884:1744]: Enter pool section on transfer 2025.02.28 12:13:09 LOG7[24884:1744]: !!!!!Call s_poll_wait with timeout = 60 ((sock_rd && ssl_rd)=0) c->ssl_ptr = 0 c->sock_ptr=0 2025.02.28 12:13:09 LOG5[24884:1744]: 23 bytes of close_notify data sent 2025.02.28 12:13:09 LOG6[24884:1744]: SSL_shutdown successfully sent close_notify 2025.02.28 12:13:09 LOG5[24884:1744]: Connection closed: 193 bytes sent to SSL, 64397 bytes sent to socket 2025.02.28 12:13:09 LOG7[24884:1744]: free Buffers 2025.02.28 12:13:09 LOG7[24884:1744]: delete c->hContext 2025.02.28 12:13:09 LOG7[24884:1744]: delete c->hClientCreds 2025.02.28 12:13:09 LOG5[24884:1744]: incomp_mess = 16, extra_data = 4 2025.02.28 12:13:09 LOG7[24884:1744]: https finished (0 left) Отредактировано пользователем 28 февраля 2025 г. 12:29:20(UTC)
| Причина: Не указана
|