Re: [FreeRDP-devel] rdp_recv_tpkt_pdu: freerdp_channel_process() fail

Thu, 05 Mar 2020 05:28:21 -0800 

Now everything works fine. Many thanks!!

05.03.20 21:29, Armin Novak via FreeRDP-devel пишет:

Thank you Andrey,
found the culprit and created https://github.com/FreeRDP/FreeRDP/pull/5946. 

regards
Armin

On 05.03.20 11:05, Andrey Vasilkin via FreeRDP-devel wrote:
[D:\projects\FreeRDP\port]freerdp.exe /v:"skh50srv002.sahen.ru" /u:"..." /d:"..." /p:"..." /size:80% /bpp:16 /gdi:hw +old-license /log-level:debug [21:03:28:841] [1356:206b33a0] [INFO][com.freerdp.core] - freerdp_connect:freerdp_set_last_error_ex resetting error state [21:03:28:842] [1356:206b33a0] [INFO][com.freerdp.client.common.cmdline] - loading channelEx rdpdr [21:03:28:843] [1356:206b33a0] [INFO][com.freerdp.client.common.cmdline] - loading channelEx rdpsnd [21:03:28:843] [1356:206b33a0] [DEBUG][com.freerdp.channels.cliprdr.client] - VirtualChannelEntryEx [21:03:28:843] [1356:206b33a0] [INFO][com.freerdp.client.common.cmdline] - loading channelEx cliprdr [21:03:28:866] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Enabling security layer negotiation: TRUE [21:03:28:866] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Enabling restricted admin mode: FALSE [21:03:28:866] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Enabling RDP security: TRUE [21:03:28:866] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Enabling TLS security: TRUE [21:03:28:866] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Enabling NLA security: TRUE [21:03:28:866] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Enabling NLA extended security: FALSE [21:03:28:866] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - state: NEGO_STATE_NLA [21:03:28:866] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Attempting NLA security [21:03:28:870] [1356:206b33a0] [INFO][com.freerdp.core] - freerdp_tcp_is_hostname_resolvable:freerdp_set_last_error_ex resetting error state [21:03:28:871] [1356:206b33a0] [INFO][com.freerdp.core] - freerdp_tcp_connect:freerdp_set_last_error_ex resetting error state [21:03:28:871] [1356:206b33a0] [DEBUG][com.freerdp.core] - connecting to peer 172.26.97.14 [21:03:28:874] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - RequestedProtocols: 3 [21:03:28:876] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - RDP_NEG_FAILURE [21:03:28:876] [1356:206b33a0] [WARN][com.freerdp.core.nego] - Error: SSL_NOT_ALLOWED_BY_SERVER [21:03:28:876] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - state: NEGO_STATE_FAIL [21:03:28:876] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - state: NEGO_STATE_TLS [21:03:28:876] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Attempting TLS security [21:03:28:876] [1356:206b33a0] [INFO][com.freerdp.core] - freerdp_tcp_is_hostname_resolvable:freerdp_set_last_error_ex resetting error state [21:03:28:876] [1356:206b33a0] [INFO][com.freerdp.core] - freerdp_tcp_connect:freerdp_set_last_error_ex resetting error state [21:03:28:876] [1356:206b33a0] [DEBUG][com.freerdp.core] - connecting to peer 172.26.97.14 [21:03:28:881] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - RequestedProtocols: 1 [21:03:28:885] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - RDP_NEG_FAILURE [21:03:28:885] [1356:206b33a0] [WARN][com.freerdp.core.nego] - Error: SSL_NOT_ALLOWED_BY_SERVER [21:03:28:885] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - state: NEGO_STATE_RDP [21:03:28:886] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Attempting RDP security [21:03:28:886] [1356:206b33a0] [INFO][com.freerdp.core] - freerdp_tcp_is_hostname_resolvable:freerdp_set_last_error_ex resetting error state [21:03:28:886] [1356:206b33a0] [INFO][com.freerdp.core] - freerdp_tcp_connect:freerdp_set_last_error_ex resetting error state [21:03:28:886] [1356:206b33a0] [DEBUG][com.freerdp.core] - connecting to peer 172.26.97.14 [21:03:28:888] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - RequestedProtocols: 0 [21:03:28:891] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - RDP_NEG_RSP [21:03:28:891] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - selected_protocol: 0 [21:03:28:891] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Negotiated RDP security [21:03:28:891] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - nego_security_connect with PROTOCOL_RDP [21:03:28:013] [1356:206b33a0] [DEBUG][com.freerdp.core.gcc] - Server rdp encryption method: 128BIT [21:03:28:754] [1356:206b33a0] [DEBUG][com.freerdp.core.info] - Client Info Packet Flags = INFO_MOUSE|INFO_DISABLECTRLALTDEL|INFO_AUTOLOGON|INFO_UNICODE|INFO_MAXIMIZESHELL|INFO_LOGONNOTIFY|INFO_COMPRESSION|INFO_ENABLEWINDOWSKEY|INFO_FORCE_ENCRYPTED_CS_PDU|INFO_LOGONERRORS|INFO_MOUSE_HAS_WHEEL|INFO_NOAUDIOPLAYBACK [21:03:28:754] [1356:206b33a0] [DEBUG][com.winpr.timezone] - tz not found, using computed bias 0. [21:03:29:142] [1356:206b33a0] [ERROR][com.freerdp.core.tpkt] - Received invalid TPKT header length 4941, 16 bytes too long! [21:03:29:142] [1356:206b33a0] [DEBUG][com.freerdp.core.rdp] - rdp_recv_callback: CONNECTION_STATE_LICENSING - rdp_client_connect_license() - -1 [21:03:29:142] [1356:206b33a0] [ERROR][com.freerdp.core.transport] - transport_check_fds: transport->ReceiveCallback() - -1 [21:03:29:142] [1356:206b33a0] [DEBUG][com.freerdp.core.rdp] - transport_check_fds() - -1 [21:03:29:142] [1356:206b33a0] [ERROR][com.freerdp.core] - rdp_client_connect:freerdp_set_last_error_ex ERRCONNECT_CONNECT_TRANSPORT_FAILED [0x0002000D] [21:03:29:151] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Enabling security layer negotiation: TRUE [21:03:29:151] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Enabling restricted admin mode: FALSE [21:03:29:151] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Enabling RDP security: TRUE [21:03:29:151] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Enabling TLS security: TRUE [21:03:29:151] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Enabling NLA security: TRUE [21:03:29:152] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Enabling NLA extended security: FALSE [21:03:29:152] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - state: NEGO_STATE_NLA [21:03:29:152] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Attempting NLA security [21:03:29:152] [1356:206b33a0] [INFO][com.freerdp.core] - freerdp_tcp_is_hostname_resolvable:freerdp_set_last_error_ex resetting error state [21:03:29:152] [1356:206b33a0] [INFO][com.freerdp.core] - freerdp_tcp_connect:freerdp_set_last_error_ex resetting error state [21:03:29:152] [1356:206b33a0] [DEBUG][com.freerdp.core] - connecting to peer 172.26.97.14 [21:03:29:155] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - RequestedProtocols: 3 [21:03:29:160] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - RDP_NEG_FAILURE [21:03:29:160] [1356:206b33a0] [WARN][com.freerdp.core.nego] - Error: SSL_NOT_ALLOWED_BY_SERVER [21:03:29:160] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - state: NEGO_STATE_FAIL [21:03:29:160] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - state: NEGO_STATE_TLS [21:03:29:160] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Attempting TLS security [21:03:29:160] [1356:206b33a0] [INFO][com.freerdp.core] - freerdp_tcp_is_hostname_resolvable:freerdp_set_last_error_ex resetting error state [21:03:29:160] [1356:206b33a0] [INFO][com.freerdp.core] - freerdp_tcp_connect:freerdp_set_last_error_ex resetting error state [21:03:29:160] [1356:206b33a0] [DEBUG][com.freerdp.core] - connecting to peer 172.26.97.14 [21:03:29:164] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - RequestedProtocols: 1 [21:03:29:166] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - RDP_NEG_FAILURE [21:03:29:166] [1356:206b33a0] [WARN][com.freerdp.core.nego] - Error: SSL_NOT_ALLOWED_BY_SERVER [21:03:29:166] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - state: NEGO_STATE_RDP [21:03:29:166] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Attempting RDP security [21:03:29:166] [1356:206b33a0] [INFO][com.freerdp.core] - freerdp_tcp_is_hostname_resolvable:freerdp_set_last_error_ex resetting error state [21:03:29:166] [1356:206b33a0] [INFO][com.freerdp.core] - freerdp_tcp_connect:freerdp_set_last_error_ex resetting error state [21:03:29:166] [1356:206b33a0] [DEBUG][com.freerdp.core] - connecting to peer 172.26.97.14 [21:03:29:168] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - RequestedProtocols: 0 [21:03:29:172] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - RDP_NEG_RSP [21:03:29:172] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - selected_protocol: 0 [21:03:29:172] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - Negotiated RDP security [21:03:29:172] [1356:206b33a0] [DEBUG][com.freerdp.core.nego] - nego_security_connect with PROTOCOL_RDP [21:03:29:298] [1356:206b33a0] [DEBUG][com.freerdp.core.gcc] - Server rdp encryption method: 128BIT [21:03:30:048] [1356:206b33a0] [DEBUG][com.freerdp.core.info] - Client Info Packet Flags = INFO_MOUSE|INFO_DISABLECTRLALTDEL|INFO_AUTOLOGON|INFO_UNICODE|INFO_MAXIMIZESHELL|INFO_LOGONNOTIFY|INFO_COMPRESSION|INFO_ENABLEWINDOWSKEY|INFO_FORCE_ENCRYPTED_CS_PDU|INFO_LOGONERRORS|INFO_MOUSE_HAS_WHEEL|INFO_NOAUDIOPLAYBACK [21:03:30:048] [1356:206b33a0] [DEBUG][com.winpr.timezone] - tz not found, using computed bias 0. [21:03:30:433] [1356:206b33a0] [ERROR][com.freerdp.core.tpkt] - Received invalid TPKT header length 4941, 16 bytes too long! [21:03:30:433] [1356:206b33a0] [DEBUG][com.freerdp.core.rdp] - rdp_recv_callback: CONNECTION_STATE_LICENSING - rdp_client_connect_license() - -1 [21:03:30:433] [1356:206b33a0] [ERROR][com.freerdp.core.transport] - transport_check_fds: transport->ReceiveCallback() - -1 [21:03:30:433] [1356:206b33a0] [DEBUG][com.freerdp.core.rdp] - transport_check_fds() - -1 [21:03:30:433] [1356:206b33a0] [ERROR][com.freerdp.core] - rdp_client_connect:freerdp_set_last_error_ex ERRCONNECT_CONNECT_TRANSPORT_FAILED [0x0002000D] [21:03:30:433] [1356:206b33a0] [ERROR][com.freerdp.core] - freerdp_post_connect failed [21:03:30:434] [1356:206b33a0] [ERROR][com.freerdp.client.OS2] - Freerdp connect error [21:03:30:441] [1356:206b33a0] [DEBUG][com.freerdp.client.OS2] - Main thread exited. 


05.03.20 20:36, Armin Novak via FreeRDP-devel пишет:

Sorry, can´t reproduce it locally.

Could you run with /log-level:debug?
That way I´d know where to look at.

regards
Armin

On 05.03.20 10:21, Andrey Vasilkin via FreeRDP-devel wrote:
/v:"skh50srv002.sahen.ru" /u:"..." /d:"..." /p:"..." /size:80% /bpp:16 /gdi:hw /cert-ignore +old-license 


_______________________________________________
FreeRDP-devel mailing list
FreeRDP-devel@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/freerdp-devel




_______________________________________________
FreeRDP-devel mailing list
FreeRDP-devel@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/freerdp-devel



_______________________________________________
FreeRDP-devel mailing list
FreeRDP-devel@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/freerdp-devel




_______________________________________________
FreeRDP-devel mailing list
FreeRDP-devel@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/freerdp-devel

Reply via email to