Skip to content
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

[proxy] Connection Windows 2008 R2 occasionally disconnects directly #8374

Closed
wojiushixiaobai opened this issue Nov 5, 2022 · 7 comments · Fixed by #8377
Closed

[proxy] Connection Windows 2008 R2 occasionally disconnects directly #8374

wojiushixiaobai opened this issue Nov 5, 2022 · 7 comments · Fixed by #8377
Assignees
@akallabeth
Labels
backport-to-stable proxy waiting-feedback
Milestone
2.9.0

Comments

@wojiushixiaobai
Copy link

wojiushixiaobai commented Nov 5, 2022 •
edited

Connecting to Windows 2008 R2 with freerdp-proxy version 2.8.1 is disconnected randomly.
The same problem exists on Windows 7 ( Windown 7 has been upgraded to RDP8 ).

the RemoteFX and RDP 8.0 enhancements are not available for Windows Server 2008 R2 Session Host.

[10:32:02:322] [198:198] [INFO][proxy.server] - freerdp-proxy version info:
[10:32:02:322] [198:198] [INFO][proxy.server] - 	FreeRDP version: 2.8.1
[10:32:02:322] [198:198] [INFO][proxy.server] - 	Git commit: 2.8.1
[10:32:02:322] [198:198] [DEBUG][proxy.server] - 	Build config: Build configuration: BUILD_TESTING=OFF BUILTIN_CHANNELS=ON HAVE_AIO_H=1 HAVE_EXECINFO_BACKTRACE=1 HAVE_EXECINFO_BACKTRACE_SYMBOLS=1 HAVE_EXECINFO_BACKTRACE_SYMBOLS_FD=1 HAVE_EXECINFO_H=ON HAVE_EXECINFO_HEADER=1 HAVE_FCNTL_H=1 HAVE_GETLOGIN_R=1 HAVE_GETPWUID_R=1 HAVE_INTTYPES_H=1 HAVE_JOURNALD_H=TRUE HAVE_MATH_C99_LONG_DOUBLE=1 HAVE_POLL_H=1 HAVE_PTHREAD_MUTEX_TIMEDLOCK=ON HAVE_PTHREAD_MUTEX_TIMEDLOCK_LIB=1 HAVE_PTHREAD_MUTEX_TIMEDLOCK_SYMBOL= HAVE_SYSLOG_H=1 HAVE_SYS_EVENTFD_H=1 HAVE_SYS_FILIO_H= HAVE_SYS_MODEM_H= HAVE_SYS_SELECT_H=1 HAVE_SYS_SOCKIO_H= HAVE_SYS_STRTIO_H= HAVE_SYS_TIMERFD_H=1 HAVE_TM_GMTOFF=1 HAVE_UNISTD_H=1 HAVE_XI_TOUCH_CLASS=1 WITH_ALSA=ON WITH_CAIRO=ON WITH_CCACHE=ON WITH_CHANNELS=ON WITH_CLANG_FORMAT=ON WITH_CLIENT=ON WITH_CLIENT_AVAILABLE=1 WITH_CLIENT_CHANNELS=ON WITH_CLIENT_CHANNELS_AVAILABLE=1 WITH_CLIENT_COMMON=ON WITH_CLIENT_INTERFACE=OFF WITH_CUPS=ON WITH_DEBUG_ALL=OFF WITH_DEBUG_CAPABILITIES=OFF WITH_DEBUG_CERTIFICATE=OFF WITH_DEBUG_CHANNELS=OFF WITH_DEBUG_CLIPRDR=OFF WITH_DEBUG_DVC=OFF WITH_DEBUG_KBD=OFF WITH_DEBUG_LICENSE=OFF WITH_DEBUG_MUTEX=OFF WITH_DEBUG_NEGO=OFF WITH_DEBUG_NLA=OFF WITH_DEBUG_NTLM=OFF WITH_DEBUG_RAIL=OFF WITH_DEBUG_RDP=OFF WITH_DEBUG_RDPDR=OFF WITH_DEBUG_RDPEI=OFF WITH_DEBUG_RDPGFX=OFF WITH_DEBUG_REDIR=OFF WITH_DEBUG_RFX=OFF WITH_DEBUG_RINGBUFFER=OFF WITH_DEBUG_SCARD=OFF WITH_DEBUG_SND=OFF WITH_DEBUG_SVC=OFF WITH_DEBUG_SYMBOLS=OFF WITH_DEBUG_THREADS=OFF WITH_DEBUG_TIMEZONE=OFF WITH_DEBUG_TRANSPORT=OFF WITH_DEBUG_TSG=OFF WITH_DEBUG_TSMF=OFF WITH_DEBUG_TSMF=OFF WITH_DEBUG_TSMF_AVAILABLE=0 WITH_DEBUG_WND=OFF WITH_DEBUG_X11=OFF WITH_DEBUG_X11_CLIPRDR=OFF WITH_DEBUG_X11_LOCAL_MOVESIZE=OFF WITH_DEBUG_XV=OFF WITH_DSP_EXPERIMENTAL=OFF WITH_DSP_FFMPEG=OFF WITH_EVENTFD_READ_WRITE=1 WITH_FAAC=OFF WITH_FAAD2=OFF WITH_FFMPEG=TRUE WITH_FFMPEG=TRUE WITH_GFX_H264=ON WITH_GPROF=OFF WITH_GSM=OFF WITH_GSSAPI=OFF WITH_ICU=OFF WITH_IPP=OFF WITH_JPEG=OFF WITH_LAME=OFF WITH_LIBRARY_VERSIONING=ON WITH_LIBSYSTEMD=ON WITH_MACAUDIO=OFF WITH_MACAUDIO=OFF WITH_MACAUDIO_AVAILABLE=0 WITH_MANPAGES=ON WITH_MBEDTLS=OFF WITH_OPENCL=OFF WITH_OPENH264=OFF WITH_OPENSLES=OFF WITH_OPENSSL=ON WITH_OSS=ON WITH_PCSC=ON WITH_PROFILER=OFF WITH_PROXY=ON WITH_PROXY_MODULES=OFF WITH_PULSE=ON WITH_SAMPLE=OFF WITH_SANITIZE_ADDRESS=OFF WITH_SANITIZE_ADDRESS_AVAILABLE=1 WITH_SANITIZE_MEMORY=OFF WITH_SANITIZE_MEMORY_AVAILABLE=1 WITH_SANITIZE_THREAD=OFF WITH_SANITIZE_THREAD_AVAILABLE=1 WITH_SERVER=ON WITH_SERVER_CHANNELS=ON WITH_SERVER_INTERFACE=ON WITH_SHADOW=OFF WITH_SMARTCARD_INSPECT=OFF WITH_SOXR=OFF WITH_SSE2=OFF WITH_SWSCALE=ON WITH_THIRD_PARTY=OFF WITH_VAAPI=OFF WITH_VALGRIND_MEMCHECK=OFF WITH_VALGRIND_MEMCHECK_AVAILABLE=1 WITH_VERBOSE_WINPR_ASSERT=ON WITH_WAYLAND=OFF WITH_WINPR_TOOLS=ON WITH_X11=ON WITH_XCURSOR=ON WITH_XEXT=ON WITH_XFIXES=ON WITH_XI=ON WITH_XINERAMA=ON WITH_XKBFILE=ON WITH_XRANDR=ON WITH_XRENDER=ON WITH_XSHM=ON WITH_XV=ON WITH_ZLIB=ON
Build type:          Release
CFLAGS:               -Wall -Wno-unused-result -Wno-unused-but-set-variable -Wno-deprecated-declarations -fvisibility=hidden -Wimplicit-function-declaration -Wredundant-decls -fno-omit-frame-pointer
Compiler:            GNU, 9.4.0
Target architecture: 

[10:32:02:322] [198:198] [INFO][proxy.config] - Proxy configuration:
[10:32:02:322] [198:198] [INFO][proxy.config] - 	Server:
[10:32:02:322] [198:198] [INFO][proxy.config] - 		Host: 0.0.0.0
[10:32:02:322] [198:198] [INFO][proxy.config] - 		Port: 3389
[10:32:02:322] [198:198] [INFO][proxy.config] - 		SessionCapture: TRUE
[10:32:02:322] [198:198] [INFO][proxy.config] - 	Target:
[10:32:02:322] [198:198] [INFO][proxy.config] - 		TargetHost: 192.168.208.222
[10:32:02:322] [198:198] [INFO][proxy.config] - 		TargetPort: 3389
[10:32:02:322] [198:198] [INFO][proxy.config] - 	Input:
[10:32:02:322] [198:198] [INFO][proxy.config] - 		Keyboard: TRUE
[10:32:02:322] [198:198] [INFO][proxy.config] - 		Mouse: TRUE
[10:32:02:322] [198:198] [INFO][proxy.config] - 	Server Security:
[10:32:02:322] [198:198] [INFO][proxy.config] - 		ServerTlsSecurity: TRUE
[10:32:02:322] [198:198] [INFO][proxy.config] - 		ServerRdpSecurity: FALSE
[10:32:02:322] [198:198] [INFO][proxy.config] - 	Client Security:
[10:32:02:322] [198:198] [INFO][proxy.config] - 		ClientNlaSecurity: TRUE
[10:32:02:322] [198:198] [INFO][proxy.config] - 		ClientTlsSecurity: TRUE
[10:32:02:322] [198:198] [INFO][proxy.config] - 		ClientRdpSecurity: FALSE
[10:32:02:322] [198:198] [INFO][proxy.config] - 		ClientAllowFallbackToTls: TRUE
[10:32:02:322] [198:198] [INFO][proxy.config] - 	Channels:
[10:32:02:322] [198:198] [INFO][proxy.config] - 		GFX: TRUE
[10:32:02:322] [198:198] [INFO][proxy.config] - 		DisplayControl: TRUE
[10:32:02:322] [198:198] [INFO][proxy.config] - 		Clipboard: TRUE
[10:32:02:322] [198:198] [INFO][proxy.config] - 		AudioOutput: TRUE
[10:32:02:322] [198:198] [INFO][proxy.config] - 		RemoteApp: TRUE
[10:32:02:322] [198:198] [INFO][proxy.config] - 	Static Channels Proxy:
[10:32:02:323] [198:198] [INFO][proxy.config] - 		- rdpdr
[10:32:02:323] [198:198] [INFO][proxy.config] - 	Clipboard:
[10:32:02:323] [198:198] [INFO][proxy.config] - 		TextOnly: FALSE
[10:32:02:323] [198:198] [INFO][proxy.config] - 	SessionCapture:
[10:32:02:323] [198:198] [INFO][proxy.config] - 		SessionCapture: TRUE
[10:32:02:323] [198:198] [INFO][proxy.config] - 		CapturesDirectory: /opt/freerdp/data/captures
[10:32:02:323] [198:198] [DEBUG][proxy.modules] - modules root directory: /opt/freerdp

Error Connection Log.
[com.freerdp.core.fastpath] - [fastpath_send_multiple_input_pdu] called before activation [UNKNOWN]

[10:33:17:256] [198:277] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:17:256] [198:277] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[10:33:17:256] [198:277] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:17:256] [198:277] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[10:33:17:256] [198:277] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:17:256] [198:277] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[10:33:17:256] [198:277] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[10:33:17:256] [198:277] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:17:256] [198:277] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[10:33:17:256] [198:277] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:17:256] [198:277] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:17:256] [198:277] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:17:256] [198:277] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:17:256] [198:277] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:17:256] [198:277] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[10:33:17:256] [198:277] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[10:33:17:256] [198:277] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:17:257] [198:277] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:17:257] [198:277] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:17:257] [198:277] [DEBUG][proxy.update] - pf_client_send_pointer_new
[10:33:17:257] [198:277] [DEBUG][proxy.update] - pf_client_end_paint
[10:33:17:355] [198:276] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_CONFIRM_ACTIVE
[10:33:17:355] [198:276] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x1f size=37 channelId=1009)
[10:33:17:355] [198:276] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x14 size=41 channelId=1009)
[10:33:17:355] [198:276] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_DATA
[10:33:17:355] [198:276] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_DATA
[10:33:17:355] [198:276] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_DATA
[10:33:17:355] [198:276] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x14 size=41 channelId=1009)
[10:33:17:355] [198:276] [WARN][com.freerdp.core.fastpath] - [fastpath_send_multiple_input_pdu] called before activation [UNKNOWN]
[10:33:17:355] [198:276] [ERROR][com.freerdp.core.peer] - peer_recv_callback: CONNECTION_STATE_FINALIZATION - peer_recv_pdu() fail
[10:33:17:355] [198:276] [ERROR][com.freerdp.core.transport] - transport_check_fds: transport->ReceiveCallback() - -1
[10:33:17:355] [198:276] [DEBUG][com.freerdp.core.rdp] - transport_check_fds() - -1
[10:33:17:355] [198:276] [INFO][proxy.server] - [SessionID=4E80962875D58A5C864BA69C93D4D9CF][pf_server_handle_peer]: starting shutdown of connection
[10:33:17:355] [198:276] [INFO][proxy.server] - [SessionID=4E80962875D58A5C864BA69C93D4D9CF][pf_server_handle_peer]: stopping proxy's client
[10:33:17:355] [198:276] [DEBUG][proxy.client] - [SessionID=4E80962875D58A5C864BA69C93D4D9CF][pf_client_client_stop]: aborting client connection
[10:33:17:355] [198:276] [ERROR][com.freerdp.core] - freerdp_abort_connect:freerdp_set_last_error_ex ERRCONNECT_CONNECT_CANCELLED [0x0002000B]
[10:33:17:355] [198:276] [DEBUG][proxy.client] - [SessionID=4E80962875D58A5C864BA69C93D4D9CF][pf_client_client_stop]: waiting for client thread to finish
[10:33:17:403] [198:277] [DEBUG][com.freerdp.core.rdp] - transport_check_fds() - -1
[10:33:17:403] [198:277] [ERROR][com.freerdp.core.rdp] - rdp_recv_tpkt_pdu: rdp_recv_deactivate_all() fail
[10:33:17:403] [198:277] [DEBUG][com.freerdp.core.rdp] - rdp_recv_callback: CONNECTION_STATE_CAPABILITIES_EXCHANGE - rdp_recv_pdu() - -1
[10:33:17:403] [198:277] [ERROR][com.freerdp.core.transport] - transport_check_fds: transport->ReceiveCallback() - -1
[10:33:17:403] [198:277] [DEBUG][com.freerdp.core.rdp] - transport_check_fds() - -1
[10:33:17:403] [198:277] [DEBUG][com.freerdp.core] - rdp_check_fds() - -1
[10:33:17:404] [198:277] [INFO][proxy.channels] - [SessionID=4E80962875D58A5C864BA69C93D4D9CF][pf_channels_on_client_channel_disconnect]: Channel disconnected: rdpdr
[10:33:17:404] [198:277] [INFO][proxy.channels] - [SessionID=4E80962875D58A5C864BA69C93D4D9CF][pf_channels_on_client_channel_disconnect]: Channel disconnected: rdpsnd
[10:33:17:404] [198:277] [INFO][proxy.channels] - [SessionID=4E80962875D58A5C864BA69C93D4D9CF][pf_channels_on_client_channel_disconnect]: Channel disconnected: cliprdr
[10:33:17:404] [198:277] [INFO][proxy.channels] - [SessionID=4E80962875D58A5C864BA69C93D4D9CF][pf_channels_on_client_channel_disconnect]: Channel disconnected: drdynvc
[10:33:17:409] [198:276] [DEBUG][proxy.client] - [SessionID=4E80962875D58A5C864BA69C93D4D9CF][pf_client_client_stop]: thread finished
[10:33:17:409] [198:276] [INFO][proxy.server] - [SessionID=4E80962875D58A5C864BA69C93D4D9CF][pf_server_handle_peer]: freeing server's channels
[10:33:17:409] [198:276] [INFO][proxy.server] - [SessionID=4E80962875D58A5C864BA69C93D4D9CF][pf_server_handle_peer]: freeing proxy data

Successful Connection Log.

[10:33:00:813] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:00:813] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[10:33:00:813] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:00:813] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[10:33:00:813] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:00:813] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[10:33:00:813] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[10:33:00:813] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:00:813] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[10:33:00:813] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:00:813] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:00:813] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:00:813] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:00:813] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:00:813] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[10:33:00:813] [198:252] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:00:813] [198:252] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:00:813] [198:252] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:00:814] [198:252] [DEBUG][proxy.update] - pf_client_send_pointer_new
[10:33:00:814] [198:252] [DEBUG][proxy.update] - pf_client_end_paint
[10:33:00:920] [198:251] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_CONFIRM_ACTIVE
[10:33:00:920] [198:251] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x1f size=37 channelId=1009)
[10:33:00:920] [198:251] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x14 size=41 channelId=1009)
[10:33:00:920] [198:251] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_DATA
[10:33:00:921] [198:251] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_DATA
[10:33:00:921] [198:251] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_DATA
[10:33:00:921] [198:251] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x14 size=41 channelId=1009)
[10:33:00:922] [198:251] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_DATA
[10:33:00:922] [198:251] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x28 size=41 channelId=1009)
[10:33:00:923] [198:252] [DEBUG][proxy.update] - pf_client_begin_paint
[10:33:00:923] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[10:33:00:923] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[10:33:00:923] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[10:33:00:923] [198:252] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[10:33:00:923] [198:252] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:00:923] [198:252] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:00:923] [198:252] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:00:923] [198:252] [DEBUG][proxy.update] - pf_client_end_paint
[10:33:00:924] [198:252] [DEBUG][proxy.update] - pf_client_begin_paint
[10:33:00:925] [198:252] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:00:925] [198:252] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:00:925] [198:252] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:00:925] [198:252] [DEBUG][proxy.update] - pf_client_end_paint
[10:33:00:971] [198:252] [DEBUG][proxy.update] - pf_client_begin_paint
[10:33:00:971] [198:252] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:00:971] [198:252] [DEBUG][proxy.update] - pf_client_end_paint
[10:33:00:972] [198:271] [DEBUG][com.freerdp.channels.rdpsnd.server] - Client requested sound quality: 0x0000
[10:33:01:004] [198:252] [DEBUG][proxy.update] - pf_client_begin_paint
[10:33:01:004] [198:252] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:01:005] [198:252] [DEBUG][proxy.update] - pf_client_end_paint
[10:33:01:009] [198:252] [DEBUG][proxy.update] - pf_client_begin_paint
[10:33:01:009] [198:252] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:01:009] [198:252] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:01:009] [198:252] [DEBUG][proxy.update] - pf_client_end_paint
[10:33:01:034] [198:252] [DEBUG][proxy.update] - pf_client_begin_paint
[10:33:01:034] [198:252] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:01:034] [198:252] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:01:034] [198:252] [DEBUG][proxy.update] - pf_client_bitmap_update
[10:33:01:034] [198:252] [DEBUG][proxy.update] - pf_client_send_pointer_cached
[10:33:01:034] [198:252] [DEBUG][proxy.update] - pf_client_end_paint
[10:33:01:238] [198:252] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x1f size=37 channelId=1009)
[10:33:01:238] [198:252] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x14 size=41 channelId=1009)
[10:33:01:238] [198:252] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x14 size=41 channelId=1009)
[10:33:01:238] [198:252] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x27 size=41 channelId=1009)
[10:33:01:438] [198:252] [DEBUG][com.freerdp.core.rdp] - recv Synchronize Data PDU (0x1F), length: 22
[10:33:01:439] [198:252] [DEBUG][com.freerdp.core.rdp] - recv Control Data PDU (0x14), length: 26
[10:33:01:539] [198:252] [DEBUG][com.freerdp.core.rdp] - recv Control Data PDU (0x14), length: 26
[10:33:01:539] [198:252] [DEBUG][com.freerdp.core.rdp] - recv Font Map Data PDU (0x28), length: 26
[10:33:01:539] [198:252] [INFO][proxy.client] - [SessionID=14699CA6F7CE881CEB1292E44E3A0472][pf_client_on_activated]: client activated, registering server input callbacks
@akallabeth
Copy link
Member

which client do you use to connect to the proxy?
the error is one concerning the connection between client and proxy, not between proxy and rdp server

@akallabeth akallabeth added this to the next-3.0 milestone Nov 5, 2022
@akallabeth
Copy link
Member

anyway, should change the behavior to just drop such packets with a warning and not terminate the session.

@akallabeth akallabeth self-assigned this Nov 5, 2022
@wojiushixiaobai
Copy link
Author

wojiushixiaobai commented Nov 5, 2022 •
edited by akallabeth

The client operating system is windows11.

Info Log.

``` [21:23:35:181] [19:96] [INFO][proxy.server] - [SessionID=F298B03DF226F9D62A5D82C0396C6602][pf_server_handle_peer]: peer connected: 192.168.208.215 [21:23:35:266] [19:96] [INFO][com.freerdp.core.connection] - Client Security: NLA:1 TLS:1 RDP:0 [21:23:35:266] [19:96] [INFO][com.freerdp.core.connection] - Server Security: NLA:0 TLS:1 RDP:0 [21:23:35:266] [19:96] [INFO][com.freerdp.core.connection] - Negotiated Security: NLA:0 TLS:1 RDP:0 [21:23:36:703] [19:96] [ERROR][com.freerdp.core.transport] - BIO_read returned a system error 104: Connection reset by peer [21:23:36:703] [19:96] [ERROR][com.freerdp.core] - transport_read_layer:freerdp_set_last_error_ex ERRCONNECT_CONNECT_TRANSPORT_FAILED [0x0002000D] [21:23:36:703] [19:96] [INFO][proxy.server] - [SessionID=F298B03DF226F9D62A5D82C0396C6602][pf_server_handle_peer]: starting shutdown of connection [21:23:36:703] [19:96] [INFO][proxy.server] - [SessionID=F298B03DF226F9D62A5D82C0396C6602][pf_server_handle_peer]: stopping proxy's client [21:23:36:703] [19:96] [INFO][proxy.server] - [SessionID=F298B03DF226F9D62A5D82C0396C6602][pf_server_handle_peer]: freeing server's channels [21:23:36:703] [19:96] [INFO][proxy.server] - [SessionID=F298B03DF226F9D62A5D82C0396C6602][pf_server_handle_peer]: freeing proxy data [21:23:36:704] [19:96] [ERROR][com.freerdp.core.transport] - BIO_should_retry returned a system error 32: Broken pipe [21:23:37:754] [19:97] [INFO][proxy.server] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_server_handle_peer]: peer connected: 192.168.208.215 [21:23:37:833] [19:97] [INFO][com.freerdp.core.connection] - Client Security: NLA:1 TLS:1 RDP:0 [21:23:37:833] [19:97] [INFO][com.freerdp.core.connection] - Server Security: NLA:0 TLS:1 RDP:0 [21:23:37:833] [19:97] [INFO][com.freerdp.core.connection] - Negotiated Security: NLA:0 TLS:1 RDP:0 [21:23:37:264] [19:97] [INFO][com.freerdp.core.connection] - Accepted client: XIAOBAI-PC [21:23:37:264] [19:97] [INFO][com.freerdp.core.connection] - Accepted channels: [21:23:37:264] [19:97] [INFO][com.freerdp.core.connection] - rdpdr [21:23:37:264] [19:97] [INFO][com.freerdp.core.connection] - rdpsnd [21:23:37:264] [19:97] [INFO][com.freerdp.core.connection] - cliprdr [21:23:37:264] [19:97] [INFO][com.freerdp.core.connection] - drdynvc [21:23:37:264] [19:97] [INFO][com.freerdp.core.gcc] - Active rdp encryption level: NONE [21:23:37:264] [19:97] [INFO][com.freerdp.core.gcc] - Selected rdp encryption method: NONE [21:23:39:473] [19:97] [INFO][proxy.server] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_server_get_target_info]: fetching target from config [21:23:39:473] [19:97] [INFO][proxy.server] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_server_post_connect]: remote target is 192.168.208.222:3389 [21:23:39:473] [19:98] [INFO][proxy.client] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_client_connect]: connecting using client info: Username: Administrator, Domain: WIN-G0MGP0D03U7 [21:23:39:473] [19:98] [INFO][proxy.client] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_client_pre_connect]: Loading addins [21:23:39:264] [19:98] [WARN][com.freerdp.crypto] - Certificate verification failure 'unable to get local issuer certificate (20)' at stack position 0 [21:23:39:264] [19:98] [WARN][com.freerdp.crypto] - CN = WIN-51SBJ0197QU [21:23:43:469] [19:98] [INFO][proxy.client] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_client_on_activated]: client activated, registering server input callbacks [21:23:43:469] [19:98] [ERROR][proxy.client] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_client_post_connect]: frames dir created: /opt/freerdp/data/captures/Administrator/192.168.208.222_05-11-2022_21-23-43-469 [21:23:43:469] [19:98] [INFO][com.freerdp.gdi] - Local framebuffer format PIXEL_FORMAT_BGRA32 [21:23:43:469] [19:98] [INFO][com.freerdp.gdi] - Remote framebuffer format PIXEL_FORMAT_RGB16 [21:23:43:470] [19:98] [INFO][proxy.channels] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_channels_on_client_channel_connect]: Channel connected: rdpdr [21:23:43:470] [19:98] [INFO][com.freerdp.channels.rdpsnd.client] - [static] Loaded proxy backend for rdpsnd [21:23:43:470] [19:98] [INFO][proxy.channels] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_channels_on_client_channel_connect]: Channel connected: rdpsnd [21:23:43:470] [19:98] [INFO][proxy.channels] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_channels_on_client_channel_connect]: Channel connected: cliprdr [21:23:43:470] [19:98] [INFO][com.freerdp.channels.drdynvc.client] - Loading Dynamic Virtual Channel rdpgfx [21:23:43:472] [19:98] [INFO][com.freerdp.channels.drdynvc.client] - Loading Dynamic Virtual Channel disp [21:23:43:472] [19:98] [INFO][proxy.channels] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_channels_on_client_channel_connect]: Channel connected: drdynvc [21:23:43:957] [19:97] [WARN][com.freerdp.core.fastpath] - [fastpath_send_multiple_input_pdu] called before activation [UNKNOWN] [21:23:43:958] [19:97] [ERROR][com.freerdp.core.peer] - peer_recv_callback: CONNECTION_STATE_ACTIVE - peer_recv_pdu() fail [21:23:43:958] [19:97] [ERROR][com.freerdp.core.transport] - transport_check_fds: transport->ReceiveCallback() - -1 [21:23:43:958] [19:97] [INFO][proxy.server] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_server_handle_peer]: starting shutdown of connection [21:23:43:958] [19:97] [INFO][proxy.server] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_server_handle_peer]: stopping proxy's client [21:23:43:958] [19:97] [ERROR][com.freerdp.core] - freerdp_abort_connect:freerdp_set_last_error_ex ERRCONNECT_CONNECT_CANCELLED [0x0002000B] [21:23:43:057] [19:98] [ERROR][com.freerdp.core.rdp] - rdp_recv_tpkt_pdu: rdp_recv_deactivate_all() fail [21:23:43:057] [19:98] [ERROR][com.freerdp.core.transport] - transport_check_fds: transport->ReceiveCallback() - -1 [21:23:43:057] [19:98] [INFO][proxy.channels] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_channels_on_client_channel_disconnect]: Channel disconnected: rdpdr [21:23:43:057] [19:98] [INFO][proxy.channels] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_channels_on_client_channel_disconnect]: Channel disconnected: rdpsnd [21:23:43:058] [19:98] [INFO][proxy.channels] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_channels_on_client_channel_disconnect]: Channel disconnected: cliprdr [21:23:43:058] [19:98] [INFO][proxy.channels] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_channels_on_client_channel_disconnect]: Channel disconnected: drdynvc [21:23:43:062] [19:97] [INFO][proxy.server] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_server_handle_peer]: freeing server's channels [21:23:43:062] [19:97] [INFO][proxy.server] - [SessionID=40C4ABD4A1FC7DAC99CA559D4BBEFACC][pf_server_handle_peer]: freeing proxy data ```

Debug Log.

[21:34:37:219] [132:133] [DEBUG][com.winpr.thread] - Thread running, setting to detached state!
[21:34:37:227] [132:209] [INFO][proxy.server] - [SessionID=F693F4DBC94487E58D214FA5ED17EB01][pf_server_handle_peer]: peer connected: 192.168.208.215
[21:34:37:306] [132:209] [DEBUG][com.freerdp.core.nego] - received cookie [Cookie: mstshash=WIN-G0MGP]
[21:34:37:306] [132:209] [DEBUG][com.freerdp.core.nego] - RDP_NEG_REQ: RequestedProtocol: 0x0000000B
[21:34:37:306] [132:209] [INFO][com.freerdp.core.connection] - Client Security: NLA:1 TLS:1 RDP:0
[21:34:37:306] [132:209] [INFO][com.freerdp.core.connection] - Server Security: NLA:0 TLS:1 RDP:0
[21:34:37:306] [132:209] [INFO][com.freerdp.core.connection] - Negotiated Security: NLA:0 TLS:1 RDP:0
[21:34:38:744] [132:209] [ERROR][com.freerdp.core.transport] - BIO_read returned a system error 104: Connection reset by peer
[21:34:38:744] [132:209] [ERROR][com.freerdp.core] - transport_read_layer:freerdp_set_last_error_ex ERRCONNECT_CONNECT_TRANSPORT_FAILED [0x0002000D]
[21:34:38:744] [132:209] [DEBUG][com.freerdp.core.transport] - transport_check_fds: transport_read_pdu() - -1
[21:34:38:744] [132:209] [DEBUG][com.freerdp.core.rdp] - transport_check_fds() - -1
[21:34:38:744] [132:209] [INFO][proxy.server] - [SessionID=F693F4DBC94487E58D214FA5ED17EB01][pf_server_handle_peer]: starting shutdown of connection
[21:34:38:744] [132:209] [INFO][proxy.server] - [SessionID=F693F4DBC94487E58D214FA5ED17EB01][pf_server_handle_peer]: stopping proxy's client
[21:34:38:744] [132:209] [INFO][proxy.server] - [SessionID=F693F4DBC94487E58D214FA5ED17EB01][pf_server_handle_peer]: freeing server's channels
[21:34:38:744] [132:209] [INFO][proxy.server] - [SessionID=F693F4DBC94487E58D214FA5ED17EB01][pf_server_handle_peer]: freeing proxy data
[21:34:38:744] [132:209] [ERROR][com.freerdp.core.transport] - BIO_should_retry returned a system error 32: Broken pipe
[21:34:39:487] [132:133] [DEBUG][com.winpr.thread] - Thread running, setting to detached state!
[21:34:39:495] [132:210] [INFO][proxy.server] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_server_handle_peer]: peer connected: 192.168.208.215
[21:34:39:574] [132:210] [DEBUG][com.freerdp.core.nego] - received cookie [Cookie: mstshash=WIN-G0MGP]
[21:34:39:574] [132:210] [DEBUG][com.freerdp.core.nego] - RDP_NEG_REQ: RequestedProtocol: 0x0000000B
[21:34:39:574] [132:210] [INFO][com.freerdp.core.connection] - Client Security: NLA:1 TLS:1 RDP:0
[21:34:39:574] [132:210] [INFO][com.freerdp.core.connection] - Server Security: NLA:0 TLS:1 RDP:0
[21:34:39:574] [132:210] [INFO][com.freerdp.core.connection] - Negotiated Security: NLA:0 TLS:1 RDP:0
[21:34:39:007] [132:210] [INFO][com.freerdp.core.connection] - Accepted client: XIAOBAI-PC
[21:34:39:007] [132:210] [INFO][com.freerdp.core.connection] - Accepted channels:
[21:34:39:007] [132:210] [INFO][com.freerdp.core.connection] -  rdpdr
[21:34:39:007] [132:210] [INFO][com.freerdp.core.connection] -  rdpsnd
[21:34:39:007] [132:210] [INFO][com.freerdp.core.connection] -  cliprdr
[21:34:39:007] [132:210] [INFO][com.freerdp.core.connection] -  drdynvc
[21:34:39:007] [132:210] [INFO][com.freerdp.core.gcc] - Active rdp encryption level: NONE
[21:34:39:007] [132:210] [INFO][com.freerdp.core.gcc] - Selected rdp encryption method: NONE
[21:34:40:094] [132:210] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_CONFIRM_ACTIVE
[21:34:40:094] [132:210] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x1f size=37 channelId=1009)
[21:34:40:095] [132:210] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x14 size=41 channelId=1009)
[21:34:40:120] [132:210] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_DATA
[21:34:40:121] [132:210] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_DATA
[21:34:40:121] [132:210] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_DATA
[21:34:40:121] [132:210] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x14 size=41 channelId=1009)
[21:34:40:121] [132:210] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_DATA
[21:34:40:121] [132:210] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x28 size=41 channelId=1009)
[21:34:40:122] [132:210] [INFO][proxy.server] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_server_get_target_info]: fetching target from config
[21:34:40:122] [132:210] [INFO][proxy.server] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_server_post_connect]: remote target is 192.168.208.222:3389
[21:34:40:122] [132:211] [INFO][proxy.client] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_client_connect]: connecting using client info: Username: Administrator, Domain: WIN-G0MGP0D03U7
[21:34:40:122] [132:211] [DEBUG][com.freerdp.core] - freerdp_connect:freerdp_set_last_error_ex resetting error state
[21:34:40:122] [132:211] [INFO][proxy.client] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_client_pre_connect]: Loading addins
[21:34:40:122] [132:211] [DEBUG][com.freerdp.client.common.cmdline] - loading channelEx rdpdr
[21:34:40:122] [132:211] [DEBUG][com.freerdp.client.common.cmdline] - loading channelEx rdpsnd
[21:34:40:122] [132:211] [DEBUG][com.freerdp.channels.cliprdr.client] - VirtualChannelEntryEx
[21:34:40:122] [132:211] [DEBUG][com.freerdp.client.common.cmdline] - loading channelEx cliprdr
[21:34:40:122] [132:211] [DEBUG][com.freerdp.channels.drdynvc.client] - VirtualChannelEntryEx
[21:34:40:122] [132:211] [DEBUG][com.freerdp.client.common.cmdline] - loading channelEx drdynvc
[21:34:40:141] [132:211] [DEBUG][com.freerdp.core.nego] - Enabling security layer negotiation: TRUE
[21:34:40:141] [132:211] [DEBUG][com.freerdp.core.nego] - Enabling restricted admin mode: FALSE
[21:34:40:141] [132:211] [DEBUG][com.freerdp.core.nego] - Enabling RDP security: FALSE
[21:34:40:141] [132:211] [DEBUG][com.freerdp.core.nego] - Enabling TLS security: TRUE
[21:34:40:141] [132:211] [DEBUG][com.freerdp.core.nego] - Enabling NLA security: TRUE
[21:34:40:141] [132:211] [DEBUG][com.freerdp.core.nego] - Enabling NLA extended security: FALSE
[21:34:40:141] [132:211] [DEBUG][com.freerdp.core.nego] - state: NEGO_STATE_NLA
[21:34:40:141] [132:211] [DEBUG][com.freerdp.core.nego] - Attempting NLA security
[21:34:40:141] [132:211] [DEBUG][com.freerdp.core] - freerdp_tcp_is_hostname_resolvable:freerdp_set_last_error_ex resetting error state
[21:34:40:141] [132:211] [DEBUG][com.freerdp.core] - freerdp_tcp_connect:freerdp_set_last_error_ex resetting error state
[21:34:40:141] [132:211] [DEBUG][com.freerdp.core] - connecting to peer 192.168.208.222
[21:34:40:336] [132:211] [DEBUG][com.freerdp.core.nego] - RequestedProtocols: 3
[21:34:41:524] [132:211] [DEBUG][com.freerdp.core.nego] - RDP_NEG_RSP
[21:34:41:524] [132:211] [DEBUG][com.freerdp.core.nego] - RDP_NEG_RSP::flags = { [0x09] |EXTENDED_CLIENT_DATA_SUPPORTED|RESTRICTED_ADMIN_MODE_SUPPORTED }
[21:34:41:524] [132:211] [DEBUG][com.freerdp.core.nego] - selected_protocol: 2
[21:34:41:524] [132:211] [DEBUG][com.freerdp.core.nego] - state: NEGO_STATE_FINAL
[21:34:41:524] [132:211] [DEBUG][com.freerdp.core.nego] - Negotiated NLA security
[21:34:41:524] [132:211] [DEBUG][com.freerdp.core.nego] - nego_security_connect with PROTOCOL_HYBRID
[21:34:41:824] [132:211] [WARN][com.freerdp.crypto] - Certificate verification failure 'unable to get local issuer certificate (20)' at stack position 0
[21:34:41:824] [132:211] [WARN][com.freerdp.crypto] - CN = WIN-51SBJ0197QU
[21:34:41:824] [132:211] [DEBUG][com.winpr.sspi] - InitSecurityInterfaceExA
[21:34:41:824] [132:211] [DEBUG][com.freerdp.core.nla] - nla_client_init 411 : packageName=Negotiate ; cbMaxToken=12256
[21:34:41:824] [132:211] [DEBUG][com.winpr.sspi.NTLM] - change state from NTLM_STATE_INITIAL to NTLM_STATE_INITIAL
[21:34:41:824] [132:211] [DEBUG][com.winpr.sspi.NTLM] - change state from NTLM_STATE_INITIAL to NTLM_STATE_NEGOTIATE
[21:34:41:824] [132:211] [DEBUG][com.winpr.sspi.NTLM] - Write flags [0xe20882b7] NTLMSSP_NEGOTIATE_UNICODE|NTLMSSP_NEGOTIATE_OEM|NTLMSSP_REQUEST_TARGET|NTLMSSP_NEGOTIATE_SIGN|NTLMSSP_NEGOTIATE_SEAL|NTLMSSP_NEGOTIATE_LM_KEY|NTLMSSP_NEGOTIATE_NTLM|NTLMSSP_NEGOTIATE_ALWAYS_SIGN|NTLMSSP_NEGOTIATE_EXTENDED_SESSION_SECURITY|NTLMSSP_NEGOTIATE_VERSION|NTLMSSP_NEGOTIATE_128|NTLMSSP_NEGOTIATE_KEY_EXCH
[21:34:41:824] [132:211] [DEBUG][com.winpr.sspi.NTLM] - change state from NTLM_STATE_NEGOTIATE to NTLM_STATE_CHALLENGE
[21:34:41:824] [132:211] [DEBUG][com.freerdp.core.nla] - Client: Sending Authentication Token
[21:34:41:824] [132:211] [DEBUG][com.freerdp.core.nla] - NLA.negoToken (length = 40):
[21:34:41:024] [132:211] [DEBUG][com.freerdp.core.nla] - CredSSP protocol support 6, peer supports 5
[21:34:41:024] [132:211] [DEBUG][com.winpr.sspi.NTLM] - Read flags [0xe28a8235] NTLMSSP_NEGOTIATE_UNICODE|NTLMSSP_REQUEST_TARGET|NTLMSSP_NEGOTIATE_SIGN|NTLMSSP_NEGOTIATE_SEAL|NTLMSSP_NEGOTIATE_NTLM|NTLMSSP_NEGOTIATE_ALWAYS_SIGN|NTLMSSP_TARGET_TYPE_SERVER|NTLMSSP_NEGOTIATE_EXTENDED_SESSION_SECURITY|NTLMSSP_NEGOTIATE_TARGET_INFO|NTLMSSP_NEGOTIATE_VERSION|NTLMSSP_NEGOTIATE_128|NTLMSSP_NEGOTIATE_KEY_EXCH
[21:34:41:024] [132:211] [DEBUG][com.winpr.sspi.NTLM] - change state from NTLM_STATE_CHALLENGE to NTLM_STATE_AUTHENTICATE
[21:34:41:024] [132:211] [DEBUG][com.winpr.sspi.NTLM] - Write flags [0xe288b235] NTLMSSP_NEGOTIATE_UNICODE|NTLMSSP_REQUEST_TARGET|NTLMSSP_NEGOTIATE_SIGN|NTLMSSP_NEGOTIATE_SEAL|NTLMSSP_NEGOTIATE_NTLM|NTLMSSP_NEGOTIATE_DOMAIN_SUPPLIED|NTLMSSP_NEGOTIATE_WORKSTATION_SUPPLIED|NTLMSSP_NEGOTIATE_ALWAYS_SIGN|NTLMSSP_NEGOTIATE_EXTENDED_SESSION_SECURITY|NTLMSSP_NEGOTIATE_TARGET_INFO|NTLMSSP_NEGOTIATE_VERSION|NTLMSSP_NEGOTIATE_128|NTLMSSP_NEGOTIATE_KEY_EXCH
[21:34:41:024] [132:211] [DEBUG][com.winpr.sspi.NTLM] - change state from NTLM_STATE_AUTHENTICATE to NTLM_STATE_FINAL
[21:34:41:024] [132:211] [DEBUG][com.freerdp.core.nla] - Client: Sending Authentication Token
[21:34:41:024] [132:211] [DEBUG][com.freerdp.core.nla] - NLA.negoToken (length = 494):
[21:34:41:024] [132:211] [DEBUG][com.freerdp.core.nla] - NLA.pubKeyAuth (length = 48):
[21:34:41:225] [132:211] [DEBUG][com.freerdp.core.nla] - Client: Sending PubKeyAuth Token
[21:34:41:225] [132:211] [DEBUG][com.freerdp.core.nla] - NLA.authInfo (length = 123):
[21:34:42:425] [132:211] [DEBUG][com.freerdp.core.gcc] - Server rdp encryption method: NONE
[21:34:43:028] [132:211] [DEBUG][com.freerdp.core.info] - Client Info Packet Flags = INFO_MOUSE|INFO_DISABLECTRLALTDEL|INFO_AUTOLOGON|INFO_UNICODE|INFO_MAXIMIZESHELL|INFO_COMPRESSION|INFO_ENABLEWINDOWSKEY|INFO_FORCE_ENCRYPTED_CS_PDU|INFO_LOGONERRORS|INFO_MOUSE_HAS_WHEEL
[21:34:43:028] [132:211] [DEBUG][com.winpr.timezone] - tz: Bias=-480 sn='China Standard Time' dln='China Daylight Time'
[21:34:44:729] [132:211] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x1f size=37 channelId=1009)
[21:34:44:729] [132:211] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x14 size=41 channelId=1009)
[21:34:44:729] [132:211] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x14 size=41 channelId=1009)
[21:34:44:729] [132:211] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x2b size=57 channelId=1009)
[21:34:44:729] [132:211] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x27 size=41 channelId=1009)
[21:34:44:930] [132:211] [DEBUG][com.freerdp.core.rdp] - recv Synchronize Data PDU (0x1F), length: 22
[21:34:44:930] [132:211] [DEBUG][com.freerdp.core.rdp] - recv Control Data PDU (0x14), length: 26
[21:34:44:930] [132:211] [DEBUG][com.freerdp.core.rdp] - recv Control Data PDU (0x14), length: 26
[21:34:44:130] [132:211] [DEBUG][com.freerdp.core.rdp] - recv Font Map Data PDU (0x28), length: 26
[21:34:44:130] [132:211] [INFO][proxy.client] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_client_on_activated]: client activated, registering server input callbacks
[21:34:44:130] [132:211] [ERROR][proxy.client] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_client_post_connect]: frames dir created: /opt/freerdp/data/captures/Administrator/192.168.208.222_05-11-2022_21-34-44-130
[21:34:44:130] [132:211] [INFO][com.freerdp.gdi] - Local framebuffer format  PIXEL_FORMAT_BGRA32
[21:34:44:130] [132:211] [INFO][com.freerdp.gdi] - Remote framebuffer format PIXEL_FORMAT_RGB16
[21:34:44:131] [132:211] [INFO][proxy.channels] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_channels_on_client_channel_connect]: Channel connected: rdpdr
[21:34:44:131] [132:211] [INFO][com.freerdp.channels.rdpsnd.client] - [static] Loaded proxy backend for rdpsnd
[21:34:44:131] [132:211] [INFO][proxy.channels] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_channels_on_client_channel_connect]: Channel connected: rdpsnd
[21:34:44:131] [132:211] [INFO][proxy.channels] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_channels_on_client_channel_connect]: Channel connected: cliprdr
[21:34:44:131] [132:211] [INFO][com.freerdp.channels.drdynvc.client] - Loading Dynamic Virtual Channel rdpgfx
[21:34:44:132] [132:211] [DEBUG][com.freerdp.channels.drdynvc.client] - register_plugin: num_plugins 1
[21:34:44:132] [132:211] [INFO][com.freerdp.channels.drdynvc.client] - Loading Dynamic Virtual Channel disp
[21:34:44:132] [132:211] [DEBUG][com.freerdp.channels.drdynvc.client] - register_plugin: num_plugins 2
[21:34:44:132] [132:211] [DEBUG][com.freerdp.channels.drdynvc.client] - create_listener: 1.Microsoft::Windows::RDS::Graphics.
[21:34:44:132] [132:211] [DEBUG][com.freerdp.channels.drdynvc.client] - create_listener: 2.Microsoft::Windows::RDS::DisplayControl.
[21:34:44:132] [132:211] [INFO][proxy.channels] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_channels_on_client_channel_connect]: Channel connected: drdynvc
[21:34:44:148] [132:211] [DEBUG][proxy.update] - pf_client_begin_paint
[21:34:44:148] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[21:34:44:148] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[21:34:44:148] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[21:34:44:148] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[21:34:44:148] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[21:34:44:148] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[21:34:44:148] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[21:34:44:148] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[21:34:44:148] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[21:34:44:148] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[21:34:44:148] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[21:34:44:148] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[21:34:44:148] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[21:34:44:148] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[21:34:44:148] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[21:34:44:148] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:44:149] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:44:149] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:44:149] [132:211] [DEBUG][proxy.update] - pf_client_send_pointer_new
[21:34:44:149] [132:211] [DEBUG][proxy.update] - pf_client_end_paint
[21:34:44:285] [132:210] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_CONFIRM_ACTIVE
[21:34:44:285] [132:210] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x1f size=37 channelId=1009)
[21:34:44:285] [132:210] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x14 size=41 channelId=1009)
[21:34:44:285] [132:210] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_DATA
[21:34:44:285] [132:210] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_DATA
[21:34:44:285] [132:210] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_DATA
[21:34:44:286] [132:210] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x14 size=41 channelId=1009)
[21:34:44:286] [132:210] [DEBUG][com.freerdp.core.peer] - Received PDU_TYPE_DATA
[21:34:44:286] [132:210] [DEBUG][com.freerdp.core.rdp] - rdp_send_data_pdu: sending data (type=0x28 size=41 channelId=1009)
[21:34:44:290] [132:211] [DEBUG][proxy.update] - pf_client_begin_paint
[21:34:44:290] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[21:34:44:290] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x01] Create Offscreen Bitmap
[21:34:44:290] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[21:34:44:290] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:44:290] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:44:290] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:44:290] [132:211] [DEBUG][proxy.update] - pf_client_send_pointer_new
[21:34:44:290] [132:211] [DEBUG][proxy.update] - pf_client_end_paint
[21:34:44:334] [132:230] [DEBUG][com.freerdp.channels.rdpsnd.server] - Client requested sound quality: 0x0000
[21:34:44:337] [132:211] [DEBUG][proxy.update] - pf_client_begin_paint
[21:34:44:337] [132:211] [DEBUG][com.freerdp.core.update] - Alternate Secondary Drawing Order [0x00] Switch Surface
[21:34:44:337] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:44:338] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:44:338] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:44:338] [132:211] [DEBUG][proxy.update] - pf_client_end_paint
[21:34:44:338] [132:211] [DEBUG][proxy.update] - pf_client_begin_paint
[21:34:44:338] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:44:338] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:44:339] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:44:339] [132:211] [DEBUG][proxy.update] - pf_client_end_paint
[21:34:44:369] [132:211] [DEBUG][proxy.update] - pf_client_begin_paint
[21:34:44:369] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:44:369] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:44:369] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:44:369] [132:211] [DEBUG][proxy.update] - pf_client_end_paint
[21:34:44:383] [132:211] [DEBUG][proxy.update] - pf_client_begin_paint
[21:34:44:383] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:44:383] [132:211] [DEBUG][proxy.update] - pf_client_end_paint
[21:34:45:423] [132:211] [DEBUG][proxy.update] - pf_client_begin_paint
[21:34:45:423] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:45:423] [132:211] [DEBUG][proxy.update] - pf_client_end_paint
[21:34:45:443] [132:211] [DEBUG][proxy.update] - pf_client_begin_paint
[21:34:45:443] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:45:443] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:45:443] [132:211] [DEBUG][proxy.update] - pf_client_end_paint
[21:34:45:469] [132:211] [DEBUG][proxy.update] - pf_client_begin_paint
[21:34:45:470] [132:211] [DEBUG][proxy.update] - pf_client_bitmap_update
[21:34:45:470] [132:211] [DEBUG][proxy.update] - pf_client_end_paint
[21:34:45:540] [132:210] [WARN][com.freerdp.core.fastpath] - [fastpath_send_multiple_input_pdu] called before activation [UNKNOWN]
[21:34:45:540] [132:210] [ERROR][com.freerdp.core.peer] - peer_recv_callback: CONNECTION_STATE_ACTIVE - peer_recv_pdu() fail
[21:34:45:540] [132:210] [ERROR][com.freerdp.core.transport] - transport_check_fds: transport->ReceiveCallback() - -1
[21:34:45:540] [132:210] [DEBUG][com.freerdp.core.rdp] - transport_check_fds() - -1
[21:34:45:540] [132:210] [INFO][proxy.server] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_server_handle_peer]: starting shutdown of connection
[21:34:45:540] [132:210] [INFO][proxy.server] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_server_handle_peer]: stopping proxy's client
[21:34:45:540] [132:210] [DEBUG][proxy.client] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_client_client_stop]: aborting client connection
[21:34:45:541] [132:210] [ERROR][com.freerdp.core] - freerdp_abort_connect:freerdp_set_last_error_ex ERRCONNECT_CONNECT_CANCELLED [0x0002000B]
[21:34:45:541] [132:210] [DEBUG][proxy.client] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_client_client_stop]: waiting for client thread to finish
[21:34:45:570] [132:211] [DEBUG][com.freerdp.core.rdp] - transport_check_fds() - -1
[21:34:45:570] [132:211] [ERROR][com.freerdp.core.rdp] - rdp_recv_tpkt_pdu: rdp_recv_deactivate_all() fail
[21:34:45:570] [132:211] [DEBUG][com.freerdp.core.rdp] - rdp_recv_callback: CONNECTION_STATE_CAPABILITIES_EXCHANGE - rdp_recv_pdu() - -1
[21:34:45:570] [132:211] [ERROR][com.freerdp.core.transport] - transport_check_fds: transport->ReceiveCallback() - -1
[21:34:45:570] [132:211] [DEBUG][com.freerdp.core.rdp] - transport_check_fds() - -1
[21:34:45:570] [132:211] [DEBUG][com.freerdp.core] - rdp_check_fds() - -1
[21:34:45:571] [132:211] [INFO][proxy.channels] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_channels_on_client_channel_disconnect]: Channel disconnected: rdpdr
[21:34:45:571] [132:211] [INFO][proxy.channels] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_channels_on_client_channel_disconnect]: Channel disconnected: rdpsnd
[21:34:45:571] [132:211] [INFO][proxy.channels] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_channels_on_client_channel_disconnect]: Channel disconnected: cliprdr
[21:34:45:571] [132:211] [INFO][proxy.channels] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_channels_on_client_channel_disconnect]: Channel disconnected: drdynvc
[21:34:45:575] [132:210] [DEBUG][proxy.client] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_client_client_stop]: thread finished
[21:34:45:575] [132:210] [INFO][proxy.server] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_server_handle_peer]: freeing server's channels
[21:34:45:575] [132:210] [INFO][proxy.server] - [SessionID=E7D768D0355318CAADF80831D5EBC75E][pf_server_handle_peer]: freeing proxy data

Thanks to @akallabeth.

@akallabeth
Copy link
Member

akallabeth commented Nov 6, 2022 •
edited

@wojiushixiaobai ok, but which client application do you use to connect?

@wojiushixiaobai
Copy link
Author

@akallabeth
mstsc.exe (10.0.22621.1)
Windows 11 22H2 (22621.755)

@akallabeth
Copy link
Member

ok, this looks like a backport is due, as this is already handled on master

@akallabeth akallabeth modified the milestones: next-3.0, stable-next Nov 7, 2022
@akallabeth akallabeth linked a pull request Nov 7, 2022 that will close this issue
Proxy input fix #8377
Merged
@akallabeth
Copy link
Member

@wojiushixiaobai ok, pushed a backport to the stable branch. A new release will follow this week.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@akallabeth akallabeth

Labels
backport-to-stable proxy waiting-feedback
Projects
None yet
Milestone
2.9.0
Development

Successfully merging a pull request may close this issue.

Proxy input fix akallabeth/FreeRDP
2 participants
@akallabeth @wojiushixiaobai