thestr4ng3r / chiaki

Moved to https://git.sr.ht/~thestr4ng3r/chiaki - Free and Open Source PS4 Remote Play Client
https://git.sr.ht/~thestr4ng3r/chiaki
2.17k stars 373 forks source link

Received Ctrl Message with unknown type 0x16 : After Minecraft update 1.15 to 1.16 #379

Open bbros-dev opened 3 years ago

bbros-dev commented 3 years ago

Environment

Describe the bug A clear and concise description of what the bug is.

Using and PS4 Dual Shock controller. After updating MineCraft from 1.15 to 1.16, and updating the PS account data, the remote play user is able to login with the PS4 button. However, upon selecting the x button to join an exiting world the connection abends.

To Reproduce Steps to reproduce the behavior:

  1. Have a Console USB-connected player start MineCraft and resume an existing world.
  2. Run Chiaki Remote Play server
  3. Remote player presses PS4 button (should be logged in), press x
  4. See the world with the Console Players view (no split screen)
  5. Remote player presses x (to join the world)
  6. Remote play connection abends

Expected behavior A clear and concise description of what you expected to happen.

Log Files

[2020-11-20 18:17:39:507507] [I] Chiaki Version 1.3.0
[2020-11-20 18:17:39:547547] [I] Starting session request
[2020-11-20 18:17:39:556556] [I] Trying to request session from 192.168.1.118:9295
[2020-11-20 18:17:39:556556] [I] Connected to 192.168.1.118:9295
[2020-11-20 18:17:39:556556] [I] Sending session request
[2020-11-20 18:17:39:559559] [I] Session request successful
[2020-11-20 18:17:39:566566] [I] OpenGL initialized with version "3.3 (Core Profile) Mesa 20.0.8"
[2020-11-20 18:17:39:569569] [I] Starting ctrl
[2020-11-20 18:17:39:570570] [I] Ctrl connected to 192.168.1.118:9295
[2020-11-20 18:17:39:574574] [I] Sending ctrl request
[2020-11-20 18:17:39:577577] [I] Ctrl received http header as response
[2020-11-20 18:17:39:577577] [I] Ctrl received ctrl request http response
[2020-11-20 18:17:39:577577] [I] Ctrl connected
[2020-11-20 18:17:39:599599] [I] Ctrl received Login message: success
[2020-11-20 18:17:39:599599] [W] Received Ctrl Message with unknown type 0x6
[2020-11-20 18:17:39:599599] [W] offset 0  1  2  3  4  5  6  7  8  9  a  b  c  d  e  f  0123456789abcdef
[2020-11-20 18:17:39:599599] [W]      0 01 01                                           ..              
[2020-11-20 18:17:41:598598] [W] Received presumably invalid Session Id:
[2020-11-20 18:17:41:598598] [W] offset 0  1  2  3  4  5  6  7  8  9  a  b  c  d  e  f  0123456789abcdef
[2020-11-20 18:17:41:598598] [W]      0 10 49 6e 76 61 6c 69 64 53 65 73 73 69 6f 6e 49 .InvalidSessionI
[2020-11-20 18:17:41:598598] [W]     10 64                                              d               
[2020-11-20 18:17:41:598598] [I] Ctrl received valid Session Id: InvalidSessionId
[2020-11-20 18:17:41:598598] [I] Starting Senkusha
[2020-11-20 18:17:41:598598] [I] Takion connecting (version 7)
[2020-11-20 18:17:41:598598] [I] Takion enabled Don't Fragment Bit
[2020-11-20 18:17:41:599599] [I] Takion sent init
[2020-11-20 18:17:41:599599] [I] Takion received init ack with remote tag 0x3c9fd38e, outbound streams: 0x64, inbound streams: 0x64
[2020-11-20 18:17:41:599599] [I] Takion sent cookie
[2020-11-20 18:17:41:600600] [I] Takion received cookie ack
[2020-11-20 18:17:41:600600] [I] Takion connected
[2020-11-20 18:17:41:600600] [I] Senkusha sending big
[2020-11-20 18:17:41:601601] [I] Senkusha successfully received bang
[2020-11-20 18:17:41:601601] [I] Senkusha Ping Test with count 10 starting
[2020-11-20 18:17:41:602602] [I] Senkusha enabled echo
[2020-11-20 18:17:41:602602] [I] Senkusha sending Ping 0 of test index 0
[2020-11-20 18:17:41:603603] [I] Senkusha received Pong, RTT = 0.796 ms
[2020-11-20 18:17:41:603603] [I] Senkusha sending Ping 1 of test index 0
[2020-11-20 18:17:41:603603] [I] Senkusha received Pong, RTT = 0.729 ms
[2020-11-20 18:17:41:604604] [I] Senkusha sending Ping 2 of test index 0
[2020-11-20 18:17:41:604604] [I] Senkusha received Pong, RTT = 0.815 ms
[2020-11-20 18:17:41:604604] [I] Senkusha sending Ping 3 of test index 0
[2020-11-20 18:17:41:605605] [I] Senkusha received Pong, RTT = 0.634 ms
[2020-11-20 18:17:41:605605] [I] Senkusha sending Ping 4 of test index 0
[2020-11-20 18:17:41:606606] [I] Senkusha received Pong, RTT = 0.617 ms
[2020-11-20 18:17:41:606606] [I] Senkusha sending Ping 5 of test index 0
[2020-11-20 18:17:41:607607] [I] Senkusha received Pong, RTT = 0.821 ms
[2020-11-20 18:17:41:607607] [I] Senkusha sending Ping 6 of test index 0
[2020-11-20 18:17:41:608608] [I] Senkusha received Pong, RTT = 0.550 ms
[2020-11-20 18:17:41:608608] [I] Senkusha sending Ping 7 of test index 0
[2020-11-20 18:17:41:608608] [I] Senkusha received Pong, RTT = 0.569 ms
[2020-11-20 18:17:41:608608] [I] Senkusha sending Ping 8 of test index 0
[2020-11-20 18:17:41:609609] [I] Senkusha received Pong, RTT = 0.481 ms
[2020-11-20 18:17:41:609609] [I] Senkusha sending Ping 9 of test index 0
[2020-11-20 18:17:41:610610] [I] Senkusha received Pong, RTT = 0.566 ms
[2020-11-20 18:17:41:610610] [I] Senkusha disabled echo
[2020-11-20 18:17:41:610610] [I] Senkusha determined average RTT = 0.657 ms
[2020-11-20 18:17:41:610610] [I] Senkusha starting MTU in test with min 576, max 1454, retries 3, timeout 5 ms
[2020-11-20 18:17:41:610610] [I] Senkusha MTU request 1454 (min 576, max 1454), id 1, attempt 0
[2020-11-20 18:17:41:611611] [I] Senkusha MTU 1454 success
[2020-11-20 18:17:41:611611] [I] Senkusha determined inbound MTU 1454
[2020-11-20 18:17:41:611611] [I] Senkusha starting MTU out test with min 576, max 1454, retries 3, timeout 5 ms
[2020-11-20 18:17:41:612612] [I] Senkusha sent initial client MTU command
[2020-11-20 18:17:41:612612] [I] Senkusha received expected Client MTU Command
[2020-11-20 18:17:41:612612] [I] Senkusha MTU 1454 out ping attempt 0
[2020-11-20 18:17:41:613613] [I] Senkusha MTU ping 1454 success
[2020-11-20 18:17:41:613613] [I] Senkusha determined outbound MTU 1454
[2020-11-20 18:17:41:613613] [I] Senkusha sending final Client MTU Command
[2020-11-20 18:17:41:614614] [I] Senkusha is disconnecting
[2020-11-20 18:17:41:614614] [I] Senkusha closed takion
[2020-11-20 18:17:41:614614] [I] Senkusha completed successfully
[2020-11-20 18:17:41:623623] [I] Takion connecting (version 9)
[2020-11-20 18:17:41:623623] [I] Takion sent init
[2020-11-20 18:17:41:624624] [I] Takion received init ack with remote tag 0x1f31b697, outbound streams: 0x64, inbound streams: 0x64
[2020-11-20 18:17:41:624624] [I] Takion sent cookie
[2020-11-20 18:17:41:625625] [I] Takion received cookie ack
[2020-11-20 18:17:41:625625] [I] Takion connected
[2020-11-20 18:17:41:625625] [I] StreamConnection sending big
[2020-11-20 18:17:41:634634] [I] BANG received
[2020-11-20 18:17:41:636636] [I] StreamConnection successfully received bang
[2020-11-20 18:17:41:638638] [I] Crypt has become available. Re-checking MACs of 0 packets
[2020-11-20 18:17:41:726726] [D] StreamConnection received audio header:
[2020-11-20 18:17:41:726726] [D] offset 0  1  2  3  4  5  6  7  8  9  a  b  c  d  e  f  0123456789abcdef
[2020-11-20 18:17:41:726726] [D]      0 02 10 00 00 bb 80 00 00 01 e0 00 00 00 01       ..............  
[2020-11-20 18:17:41:726726] [I] Audio Header:
[2020-11-20 18:17:41:726726] [I]   channels = 2
[2020-11-20 18:17:41:726726] [I]   bits = 16
[2020-11-20 18:17:41:726726] [I]   rate = 48000
[2020-11-20 18:17:41:726726] [I]   frame size = 480
[2020-11-20 18:17:41:726726] [I]   unknown = 1
[2020-11-20 18:17:41:728728] [I] ChiakiOpusDecoder initialized
[2020-11-20 18:17:41:941941] [I] Audio Device alsa_output.pci-0000_00_1b.0.analog-stereo opened with 2 channels @ 48000 Hz, buffer size 9600
[2020-11-20 18:17:41:942942] [I] Video Profiles:
[2020-11-20 18:17:41:942942] [I]   0: 1280x720
[2020-11-20 18:17:41:942942] [E] Takion dropping data with seq num 0x1f31b698
[2020-11-20 18:17:41:942942] [I] StreamConnection successfully received streaminfo
[2020-11-20 18:17:41:958958] [I] Switched to profile 0, resolution: 1280x720
[2020-11-20 18:17:41:958958] [E] Failed to push frame: Invalid data found when processing input
[2020-11-20 18:17:44:577577] [I] Ctrl received Heartbeat, sending reply
[2020-11-20 18:17:49:577577] [I] Ctrl received Heartbeat, sending reply
[2020-11-20 18:17:54:577577] [I] Ctrl received Heartbeat, sending reply
[2020-11-20 18:17:59:577577] [I] Ctrl received Heartbeat, sending reply
[2020-11-20 18:18:04:318318] [W] Received Ctrl Message with unknown type 0x16
[2020-11-20 18:18:04:318318] [W] offset 0  1  2  3  4  5  6  7  8  9  a  b  c  d  e  f  0123456789abcdef
[2020-11-20 18:18:04:318318] [W]      0 01 ff                                           ..              
[2020-11-20 18:18:04:577577] [I] Ctrl received Heartbeat, sending reply
[2020-11-20 18:18:08:298298] [W] Received Ctrl Message with unknown type 0x16
[2020-11-20 18:18:08:299299] [W] offset 0  1  2  3  4  5  6  7  8  9  a  b  c  d  e  f  0123456789abcdef
[2020-11-20 18:18:08:299299] [W]      0 00 00                                           ..              
[2020-11-20 18:18:08:458458] [I] Remote disconnected from StreamConnection with reason "Server shutting down"
[2020-11-20 18:18:08:458458] [I] StreamConnection is disconnecting
[2020-11-20 18:18:08:459459] [I] StreamConnection sending Disconnect
[2020-11-20 18:18:08:459459] [I] StreamConnection closing after Remote disconnected
[2020-11-20 18:18:08:459459] [I] StreamConnection closed takion
[2020-11-20 18:18:08:459459] [E] Remote disconnected from StreamConnection
[2020-11-20 18:18:08:459459] [I] Ctrl requested to stop
[2020-11-20 18:18:08:459459] [I] Ctrl stopped
[2020-11-20 18:18:08:459459] [I] Session has quit

Screenshots If applicable, add screenshots to help explain your problem. Screenshot from 2020-11-22 06-13-22

Additional context The only change was updating MineCraft to version 1.16 from 1.15