protonscr

In Silence

protonopen appid 1361000Game compatibility - Unofficial
ValveSoftware/Proton#4316 · opened 2020-10-26 by mrc0mmand · updated 2022-11-06 · 4 comments · github · game page · search this game
1 matching comments, n / p to jump
Mmrc0mmand 2020-10-26 github

Compatibility Report

  • Name of the game with compatibility issues: In Silence
  • Steam AppID of the game: 1361000

System Information

I confirm:

  • [x] that I haven't found an existing compatibility report for this game.
  • [x] that I have checked whether there are updates for my system available.

Symptoms 

_v2-entry-point[666227]: STEAM_COMPAT_APP_ID=1361000
_v2-entry-point[666227]: STEAM_COMPAT_SESSION_ID=a61956717c6f3b16
_v2-entry-point[666227]: XDG_RUNTIME_DIR=/run/user/1000
_v2-entry-point[666242]: STEAM_COMPAT_APP_ID=1361000
_v2-entry-point[666242]: STEAM_COMPAT_SESSION_ID=a61956717c6f3b16
_v2-entry-point[666242]: XDG_RUNTIME_DIR=/run/user/1000
GameAction [AppID 1361000, ActionID 1] : LaunchApp changed task to SynchronizingCloud with ""
GameAction [AppID 1361000, ActionID 1] : LaunchApp changed task to SiteLicenseSeatCheckout with ""
GameAction [AppID 1361000, ActionID 1] : LaunchApp changed task to CreatingProcess with ""
GameAction [AppID 1361000, ActionID 1] : LaunchApp waiting for user response to CreatingProcess ""
GameAction [AppID 1361000, ActionID 1] : LaunchApp continues with user response "CreatingProcess"
Game update: AppID 1361000 "", ProcID 666258, IP 0.0.0.0:0
ERROR: ld.so: object '/home/mrc0mmand/.local/share/Steam/ubuntu12_32/gameoverlayrenderer.so' from LD_PRELOAD cannot be preloaded (wrong ELF class: ELFCLASS32): ignored.
/bin/sh: -c: line 0: syntax error near unexpected token `newline'
/bin/sh: -c: line 0: `PROTON_LOG=1 '/home/mrc0mmand/.local/share/Steam/steamapps/common/SteamLinuxRuntime_soldier'/_v2-entry-point --deploy=soldier --suite=soldier --verb=waitforexitandrun -- '/home/mrc0mmand/.local/share/Steam/steamapps/common/Proton 5.13'/proton waitforexitandrun  '/home/mrc0mmand/.local/share/Steam/steamapps/common/In Silence/In Silence.exe' +connect_lobby <64-bit lobby Steam ID>'
Starting app 1361000
>>> Adding process 666258 for game ID 1361000
GameAction [AppID 1361000, ActionID 1] : LaunchApp changed task to WaitingGameWindow with ""
>>> Adding process 666259 for game ID 1361000
GameAction [AppID 1361000, ActionID 1] : LaunchApp changed task to Completed with ""
Game removed: AppID 1361000 "", ProcID 666258 
Uploaded AppInterfaceStats to Steam
Exiting app 1361000

Reproducible with Proton 5.09 as well:

GameAction [AppID 1361000, ActionID 1] : LaunchApp changed task to SynchronizingCloud with ""
GameAction [AppID 1361000, ActionID 1] : LaunchApp changed task to SiteLicenseSeatCheckout with ""
GameAction [AppID 1361000, ActionID 1] : LaunchApp changed task to CreatingProcess with ""
GameAction [AppID 1361000, ActionID 1] : LaunchApp waiting for user response to CreatingProcess ""
GameAction [AppID 1361000, ActionID 1] : LaunchApp continues with user response "CreatingProcess"
Game update: AppID 1361000 "", ProcID 668141, IP 0.0.0.0:0
ERROR: ld.so: object '/home/mrc0mmand/.local/share/Steam/ubuntu12_32/gameoverlayrenderer.so' from LD_PRELOAD cannot be preloaded (wrong ELF class: ELFCLASS32): ignored.
/bin/sh: -c: line 0: syntax error near unexpected token `newline'
/bin/sh: -c: line 0: `'/home/mrc0mmand/.local/share/Steam/steamapps/common/Proton 5.0'/proton waitforexitandrun '/home/mrc0mmand/.local/share/Steam/steamapps/common/In Silence/In Silence.exe' +connect_lobby <64-bit lobby Steam ID>'
Starting app 1361000
>>> Adding process 668141 for game ID 1361000
GameAction [AppID 1361000, ActionID 1] : LaunchApp changed task to WaitingGameWindow with ""
>>> Adding process 668142 for game ID 1361000
GameAction [AppID 1361000, ActionID 1] : LaunchApp changed task to Completed with ""
Game removed: AppID 1361000 "", ProcID 668141 
Uploaded AppInterfaceStats to Steam
Exiting app 1361000

Reproduction

Start steam from terminal (to see the logs) and try to start the game.

Mmrc0mmand 2020-10-26 github

The issue is clearly in the +connect_lobby <64-bit lobby Steam ID> where < and > are interpreted as shell redirections. By using a particularly ugly piece of shell in the launch options (eval "$(echo "%command%" | sed 's/+connect.*$//')"), one can strip the invalid lobby ID away, but then the game gets stuck while connecting to the voice servers.

image

The question is why isn't the placeholder correctly replaced by a valid lobby ID in the first place.

However, the issue with the voice server is probably related to the proprietary middleware Vivox the game uses for the in-game voice chat. Looking at the Steam discussion forums for In Silence it looks like the Vivox issue is common even on Windows, so maybe this will be fixed in some of the future updates.

Mmrc0mmand 2020-12-07 github

I gave it a spin after the latest update (v0.60) with Proton 5.13-2 - unfortunately the situation remains the same.

In vain attempts to gather something useful I used the generated debug scripts (PROTON_DUMP_DEBUG_COMMANDS=1) in combination with +relay wine debug option, and the results are mixed. It looks like the login process works as expected - cookie & the pre-login phase finishes successfully, then the Vivox plugin connects to the voice server. After that, there's some data exchange (also confirmed via wireshark), which after a short amount of time ends without any obvious error, except for the red error in game. There are no errors/unhandled exceptions during the loading screen. The only thing which pops up is a couple of fixmes, which I suspect are not relevant, since the networking stuff seems to be working:

0244:fixme:wininet:InternetSetOptionW Option INTERNET_OPTION_IGNORE_OFFLINE: STUB
0244:fixme:wininet:InternetSetOptionW INTERNET_OPTION_SEND/RECEIVE_TIMEOUT/DATA_SEND_TIMEOUT 15000

Following is the log of the last few recv()s from the voice socket (0x224 is the voice thread ID). Further down from this point the thread just loops in KERNEL32.GetTickCount64() and KERNEL32.WaitForSingleObject().

+relay log 
0224:Ret  ntdll.wine_server_handle_to_fd() retval=00000000 ret=7f6af76d56cd
018c:Ret  KERNEL32.WaitForSingleObject() retval=00000000 ret=6a437ab6
018c:Call KERNEL32.SetEvent(0000032c) ret=6a437fc2
0224:Call KERNEL32.IsBadWritePtr(7161f8c0,00001000) ret=7f6af76d57c0
018c:Call ntdll.NtSetEvent(0000032c,00000000) ret=7b067045
00e4:Call KERNEL32.SetEvent(00000514) ret=180f0c026
0224:Ret  KERNEL32.IsBadWritePtr() retval=00000000 ret=7f6af76d57c0
018c:Ret  ntdll.NtSetEvent() retval=00000000 ret=7b067045
0194:Call KERNEL32.WaitForMultipleObjects(00000002,64e4fbe0,00000000,ffffffff) ret=6a437b6e
00e4:Call ntdll.NtSetEvent(00000514,00000000) ret=7b067045
018c:Ret  KERNEL32.SetEvent() retval=00000001 ret=6a437fc2
0224:Call ntdll.__wine_locked_recvmsg(00000232,69c9dcf0,00000000) ret=7f6af76d3638
00e4:Ret  ntdll.NtSetEvent() retval=00000000 ret=7b067045
00e4:Ret  KERNEL32.SetEvent() retval=00000001 ret=180f0c026
0224:Ret  ntdll.__wine_locked_recvmsg() retval=0000002d ret=7f6af76d3638
0224:Call ntdll.wine_server_release_fd(000005c0,00000232) ret=7f6af76d5c9d
0224:Ret  ntdll.wine_server_release_fd() retval=00000000 ret=7f6af76d5c9d
01f8:Ret  KERNEL32.WaitForSingleObject() retval=00000000 ret=180f1f662
01f8:Call KERNEL32.ResetEvent(00000514) ret=180f068bf
01f8:Call ntdll.NtResetEvent(00000514,00000000) ret=7b0670c5
01f8:Ret  ntdll.NtResetEvent() retval=00000000 ret=7b0670c5
01f8:Ret  KERNEL32.ResetEvent() retval=00000001 ret=180f068bf
0224:Ret  ws2_32.recv() retval=0000002d ret=696abe1a
018c:Call KERNEL32.WaitForMultipleObjects(00000002,64b6f9c0,00000000,ffffffff) ret=6a437b6e
0224:Call ws2_32.WSAGetLastError() ret=696abe23
0224:Ret  ws2_32.WSAGetLastError() retval=00000000 ret=696abe23
0224:Call ws2_32.recv(000005c0,7161f8c0,00001000,00000000) ret=696abe1a
0224:Call KERNEL32.GetTickCount() ret=7f6af76d560e
0224:Ret  KERNEL32.GetTickCount() retval=35cca503 ret=7f6af76d560e
0224:Call ntdll.wine_server_handle_to_fd(000005c0,00000001,69c9e060,69c9e040) ret=7f6af76d56cd
0224:Ret  ntdll.wine_server_handle_to_fd() retval=00000000 ret=7f6af76d56cd
0224:Call KERNEL32.IsBadWritePtr(7161f8c0,00001000) ret=7f6af76d57c0
0224:Ret  KERNEL32.IsBadWritePtr() retval=00000000 ret=7f6af76d57c0
0224:Call ntdll.__wine_locked_recvmsg(00000232,69c9dcf0,00000000) ret=7f6af76d3638
0224:Ret  ntdll.__wine_locked_recvmsg() retval=00000000 ret=7f6af76d3638
0224:Call ntdll.wine_server_release_fd(000005c0,00000232) ret=7f6af76d5c9d
0224:Ret  ntdll.wine_server_release_fd() retval=00000000 ret=7f6af76d5c9d
0224:Ret  ws2_32.recv() retval=00000000 ret=696abe1a
0224:Call ws2_32.WSAGetLastError() ret=696abe23
0224:Ret  ws2_32.WSAGetLastError() retval=00000000 ret=696abe23
01f8:Call KERNEL32.WaitForSingleObject(00000514,ffffffff) ret=180f1f662
0224:Call KERNEL32.FreeLibrary(7f69c5c50000) ret=696aac98
0224:Call ntdll.LdrUnloadDll(7f69c5c50000) ret=7b026a28
0224:Ret  ntdll.LdrUnloadDll() retval=00000000 ret=7b026a28
0224:Ret  KERNEL32.FreeLibrary() retval=00000001 ret=696aac98
0224:Call ws2_32.closesocket(000005c0) ret=6969fd3b
0224:Call ntdll.wine_server_handle_to_fd(000005c0,00000001,69c9e23c,00000000) ret=7f6af76d6aa4
0224:Ret  ntdll.wine_server_handle_to_fd() retval=00000000 ret=7f6af76d6aa4
0224:Call ntdll.wine_server_release_fd(000005c0,00000232) ret=7f6af76d6ac2
0224:Ret  ntdll.wine_server_release_fd() retval=00000000 ret=7f6af76d6ac2
0224:Call KERNEL32.CloseHandle(000005c0) ret=7f6af76d6aca
0224:Call ntdll.NtClose(000005c0) ret=7b04bd14
0224:Ret  ntdll.NtClose() retval=00000000 ret=7b04bd14
0224:Ret  KERNEL32.CloseHandle() retval=00000001 ret=7f6af76d6aca

I'm not sure how to debug this further or if this is even debuggable further (and possibly fixable), preferably without getting into any trouble.

Mmrc0mmand 2021-01-07 github

Thanks to @gofman's helpful $WINEDEBUG flags (from https://github.com/ValveSoftware/Proton/issues/1328#issuecomment-752199735 where they battle with a Vivox issue as well) I also managed to capture the gnutls part of the communication:

first connection (mt2p.www.vivox.com) 
192563.297:00cc:022c:trace:crypt:gnutls_log <2> added 3 protocols, 29 ciphersuites, 16 sig algos and 10 groups into priority list
192563.297:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: Adv. version: 3.3
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.2c (GNUTLS_ECDHE_ECDSA_AES_256_GCM_SHA384)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite cc.a9 (GNUTLS_ECDHE_ECDSA_CHACHA20_POLY1305)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.ad (GNUTLS_ECDHE_ECDSA_AES_256_CCM)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.0a (GNUTLS_ECDHE_ECDSA_AES_256_CBC_SHA1)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.2b (GNUTLS_ECDHE_ECDSA_AES_128_GCM_SHA256)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.ac (GNUTLS_ECDHE_ECDSA_AES_128_CCM)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.09 (GNUTLS_ECDHE_ECDSA_AES_128_CBC_SHA1)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.30 (GNUTLS_ECDHE_RSA_AES_256_GCM_SHA384)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite cc.a8 (GNUTLS_ECDHE_RSA_CHACHA20_POLY1305)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.14 (GNUTLS_ECDHE_RSA_AES_256_CBC_SHA1)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.2f (GNUTLS_ECDHE_RSA_AES_128_GCM_SHA256)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.13 (GNUTLS_ECDHE_RSA_AES_128_CBC_SHA1)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite 00.9d (GNUTLS_RSA_AES_256_GCM_SHA384)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.9d (GNUTLS_RSA_AES_256_CCM)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite 00.35 (GNUTLS_RSA_AES_256_CBC_SHA1)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite 00.9c (GNUTLS_RSA_AES_128_GCM_SHA256)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.9c (GNUTLS_RSA_AES_128_CCM)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite 00.2f (GNUTLS_RSA_AES_128_CBC_SHA1)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite 00.9f (GNUTLS_DHE_RSA_AES_256_GCM_SHA384)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite cc.aa (GNUTLS_DHE_RSA_CHACHA20_POLY1305)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.9f (GNUTLS_DHE_RSA_AES_256_CCM)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite 00.39 (GNUTLS_DHE_RSA_AES_256_CBC_SHA1)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite 00.9e (GNUTLS_DHE_RSA_AES_128_GCM_SHA256)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.9e (GNUTLS_DHE_RSA_AES_128_CCM)
192563.297:00cc:022c:trace:crypt:gnutls_log <2> Keeping ciphersuite 00.33 (GNUTLS_DHE_RSA_AES_128_CBC_SHA1)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (OCSP Status Request/5) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sending extension OCSP Status Request/5 (5 bytes)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Client Certificate Type/19) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Server Certificate Type/20) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Supported Groups/10) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sent group SECP256R1 (0x17)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sent group SECP384R1 (0x18)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sent group SECP521R1 (0x19)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sent group X25519 (0x1d)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sent group X448 (0x1e)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sent group FFDHE2048 (0x100)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sent group FFDHE3072 (0x101)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sent group FFDHE4096 (0x102)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sent group FFDHE6144 (0x103)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sent group FFDHE8192 (0x104)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sending extension Supported Groups/10 (22 bytes)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Supported EC Point Formats/11) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sending extension Supported EC Point Formats/11 (2 bytes)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (SRP/12) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Signature Algorithms/13) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: sent signature algo (4.1) RSA-SHA256
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: sent signature algo (8.9) RSA-PSS-SHA256
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: sent signature algo (8.4) RSA-PSS-RSAE-SHA256
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: sent signature algo (4.3) ECDSA-SHA256
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: sent signature algo (8.7) EdDSA-Ed25519
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: sent signature algo (5.1) RSA-SHA384
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: sent signature algo (8.10) RSA-PSS-SHA384
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: sent signature algo (8.5) RSA-PSS-RSAE-SHA384
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: sent signature algo (5.3) ECDSA-SHA384
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: sent signature algo (8.8) EdDSA-Ed448
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: sent signature algo (6.1) RSA-SHA512
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: sent signature algo (8.11) RSA-PSS-SHA512
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: sent signature algo (8.6) RSA-PSS-RSAE-SHA512
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: sent signature algo (6.3) ECDSA-SHA512
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: sent signature algo (2.1) RSA-SHA1
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: sent signature algo (2.3) ECDSA-SHA1
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sending extension Signature Algorithms/13 (34 bytes)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (SRTP/14) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Heartbeat/15) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (ALPN/16) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Encrypt-then-MAC/22) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sending extension Encrypt-then-MAC/22 (0 bytes)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Extended Master Secret/23) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sending extension Extended Master Secret/23 (0 bytes)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Session Ticket/35) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sending extension Session Ticket/35 (0 bytes)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Key Share/51) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Supported Versions/43) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Post Handshake Auth/49) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Safe Renegotiation/65281) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sending extension Safe Renegotiation/65281 (1 bytes)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Server Name Indication/0) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <2> HSK[0x7f4f38009a50]: sent server name: 'mt2p.www.vivox.com'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sending extension Server Name Indication/0 (23 bytes)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Cookie/44) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Early Data/42) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (PSK Key Exchange Modes/45) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Record Size Limit/28) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Sending extension Record Size Limit/28 (2 bytes)
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Maximum Record Size/1) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (ClientHello Padding/21) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Preparing extension (Pre Shared Key/41) for 'client hello'
192563.297:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: CLIENT HELLO was queued [224 bytes]
192563.297:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1168
192563.297:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: buffers.c[_gnutls_io_read_buffered]:589
192563.410:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1168
192563.410:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: SERVER HELLO (2) was received. Length 81[81], frag offset 0, frag length: 81, sequence: 0
192563.410:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1159
192563.410:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: buffers.c[_gnutls_handshake_io_recv_int]:1411
192563.410:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: Server's version: 3.3
192563.410:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: SessionID length: 32
192563.410:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: SessionID: 1ccbae35112ec33944d3d726bab7aa0336ae6424786a92ee2488115208c77a91
192563.410:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: Selected cipher suite: GNUTLS_DHE_RSA_AES_256_GCM_SHA384
192563.410:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Parsing extension 'Safe Renegotiation/65281' (1 bytes)
192563.410:00cc:022c:trace:crypt:gnutls_log <4> EXT[0x7f4f38009a50]: Parsing extension 'Server Name Indication/0' (0 bytes)
192563.410:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: Safe renegotiation succeeded
192563.410:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1168
192563.410:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: buffers.c[_gnutls_io_read_buffered]:589
192563.410:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: kx.c[_gnutls_recv_server_certificate]:749
192563.517:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1168
192563.517:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: CERTIFICATE (11) was received. Length 5034[5034], frag offset 0, frag length: 5034, sequence: 0
192563.517:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1168
192563.517:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: SERVER KEY EXCHANGE (12) was received. Length 523[523], frag offset 0, frag length: 523, sequence: 0
192563.517:00cc:022c:trace:crypt:gnutls_log <1> FFDHE groups advertised, but server didn't support it; falling back to server's choice
192563.517:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: mpi.c[wrap_nettle_mpi_print]:60
192563.517:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: mpi.c[wrap_nettle_mpi_print]:60
192563.517:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: mpi.c[wrap_nettle_mpi_print]:60
192563.517:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: verify TLS 1.2 handshake data: using RSA-SHA256
192563.517:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1168
192563.517:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: SERVER HELLO DONE (14) was received. Length 0[0], frag offset 0, frag length: 0, sequence: 0
192563.517:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1159
192563.517:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: buffers.c[_gnutls_handshake_io_recv_int]:1411
192563.517:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: mpi.c[wrap_nettle_mpi_print]:60
192563.517:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: mpi.c[wrap_nettle_mpi_print]:60
192563.517:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: CLIENT KEY EXCHANGE was queued [134 bytes]
192563.517:00cc:022c:trace:crypt:gnutls_log <4> REC[0x7f4f38009a50]: Sent ChangeCipherSpec
192563.517:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: Cipher Suite: GNUTLS_DHE_RSA_AES_256_GCM_SHA384
192563.517:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: Initializing internal [write] cipher sessions
192563.517:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: recording tls-unique CB (send)
192563.517:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: FINISHED was queued [16 bytes]
192563.517:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: buffers.c[_gnutls_io_read_buffered]:589
192563.517:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: record.c[_gnutls_recv_int]:1775
192563.517:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: handshake.c[recv_handshake_final]:3322
192563.627:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: Cipher Suite: GNUTLS_DHE_RSA_AES_256_GCM_SHA384
192563.627:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1168
192563.627:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: buffers.c[_gnutls_io_read_buffered]:589
192563.627:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: handshake.c[_gnutls_recv_finished]:979
192563.627:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: handshake.c[recv_handshake_final]:3357
192563.627:00cc:022c:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1168
192563.627:00cc:022c:trace:crypt:gnutls_log <4> HSK[0x7f4f38009a50]: FINISHED (20) was received. Length 12[12], frag offset 0, frag length: 12, sequence: 0
second connection (mt2p.vivox.com) 
192564.136:00cc:0214:trace:crypt:gnutls_log <2> added 3 protocols, 29 ciphersuites, 16 sig algos and 10 groups into priority list
192564.136:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: Adv. version: 3.3
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.2c (GNUTLS_ECDHE_ECDSA_AES_256_GCM_SHA384)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite cc.a9 (GNUTLS_ECDHE_ECDSA_CHACHA20_POLY1305)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.ad (GNUTLS_ECDHE_ECDSA_AES_256_CCM)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.0a (GNUTLS_ECDHE_ECDSA_AES_256_CBC_SHA1)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.2b (GNUTLS_ECDHE_ECDSA_AES_128_GCM_SHA256)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.ac (GNUTLS_ECDHE_ECDSA_AES_128_CCM)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.09 (GNUTLS_ECDHE_ECDSA_AES_128_CBC_SHA1)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.30 (GNUTLS_ECDHE_RSA_AES_256_GCM_SHA384)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite cc.a8 (GNUTLS_ECDHE_RSA_CHACHA20_POLY1305)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.14 (GNUTLS_ECDHE_RSA_AES_256_CBC_SHA1)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.2f (GNUTLS_ECDHE_RSA_AES_128_GCM_SHA256)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.13 (GNUTLS_ECDHE_RSA_AES_128_CBC_SHA1)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite 00.9d (GNUTLS_RSA_AES_256_GCM_SHA384)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.9d (GNUTLS_RSA_AES_256_CCM)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite 00.35 (GNUTLS_RSA_AES_256_CBC_SHA1)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite 00.9c (GNUTLS_RSA_AES_128_GCM_SHA256)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.9c (GNUTLS_RSA_AES_128_CCM)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite 00.2f (GNUTLS_RSA_AES_128_CBC_SHA1)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite 00.9f (GNUTLS_DHE_RSA_AES_256_GCM_SHA384)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite cc.aa (GNUTLS_DHE_RSA_CHACHA20_POLY1305)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.9f (GNUTLS_DHE_RSA_AES_256_CCM)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite 00.39 (GNUTLS_DHE_RSA_AES_256_CBC_SHA1)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite 00.9e (GNUTLS_DHE_RSA_AES_128_GCM_SHA256)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite c0.9e (GNUTLS_DHE_RSA_AES_128_CCM)
192564.136:00cc:0214:trace:crypt:gnutls_log <2> Keeping ciphersuite 00.33 (GNUTLS_DHE_RSA_AES_128_CBC_SHA1)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (OCSP Status Request/5) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sending extension OCSP Status Request/5 (5 bytes)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Client Certificate Type/19) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Server Certificate Type/20) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Supported Groups/10) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sent group SECP256R1 (0x17)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sent group SECP384R1 (0x18)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sent group SECP521R1 (0x19)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sent group X25519 (0x1d)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sent group X448 (0x1e)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sent group FFDHE2048 (0x100)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sent group FFDHE3072 (0x101)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sent group FFDHE4096 (0x102)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sent group FFDHE6144 (0x103)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sent group FFDHE8192 (0x104)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sending extension Supported Groups/10 (22 bytes)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Supported EC Point Formats/11) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sending extension Supported EC Point Formats/11 (2 bytes)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (SRP/12) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Signature Algorithms/13) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: sent signature algo (4.1) RSA-SHA256
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: sent signature algo (8.9) RSA-PSS-SHA256
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: sent signature algo (8.4) RSA-PSS-RSAE-SHA256
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: sent signature algo (4.3) ECDSA-SHA256
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: sent signature algo (8.7) EdDSA-Ed25519
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: sent signature algo (5.1) RSA-SHA384
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: sent signature algo (8.10) RSA-PSS-SHA384
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: sent signature algo (8.5) RSA-PSS-RSAE-SHA384
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: sent signature algo (5.3) ECDSA-SHA384
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: sent signature algo (8.8) EdDSA-Ed448
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: sent signature algo (6.1) RSA-SHA512
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: sent signature algo (8.11) RSA-PSS-SHA512
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: sent signature algo (8.6) RSA-PSS-RSAE-SHA512
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: sent signature algo (6.3) ECDSA-SHA512
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: sent signature algo (2.1) RSA-SHA1
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: sent signature algo (2.3) ECDSA-SHA1
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sending extension Signature Algorithms/13 (34 bytes)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (SRTP/14) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Heartbeat/15) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (ALPN/16) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Encrypt-then-MAC/22) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sending extension Encrypt-then-MAC/22 (0 bytes)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Extended Master Secret/23) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sending extension Extended Master Secret/23 (0 bytes)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Session Ticket/35) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sending extension Session Ticket/35 (0 bytes)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Key Share/51) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Supported Versions/43) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Post Handshake Auth/49) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Safe Renegotiation/65281) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sending extension Safe Renegotiation/65281 (1 bytes)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Server Name Indication/0) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <2> HSK[0x7f4f3c006150]: sent server name: 'mt2p.vivox.com'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sending extension Server Name Indication/0 (19 bytes)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Cookie/44) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Early Data/42) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (PSK Key Exchange Modes/45) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Record Size Limit/28) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Sending extension Record Size Limit/28 (2 bytes)
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Maximum Record Size/1) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (ClientHello Padding/21) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Preparing extension (Pre Shared Key/41) for 'client hello'
192564.136:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: CLIENT HELLO was queued [220 bytes]
192564.136:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1168
192564.136:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: buffers.c[_gnutls_io_read_buffered]:589
192564.167:00cc:0214:warn:winsock:WS2_recv_base  -> ERROR 10035
192564.197:00cc:0214:warn:winsock:WS2_recv_base  -> ERROR 10035
192564.222:00cc:0214:warn:winsock:WS2_recv_base  -> ERROR 10035
192564.242:00cc:0214:warn:winsock:WS2_recv_base  -> ERROR 10035
192564.263:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1168
192564.263:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: SERVER HELLO (2) was received. Length 81[81], frag offset 0, frag length: 81, sequence: 0
192564.263:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1159
192564.263:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: buffers.c[_gnutls_handshake_io_recv_int]:1411
192564.263:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: Server's version: 3.3
192564.263:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: SessionID length: 32
192564.263:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: SessionID: 2eb491af43b8c45397fc82772b81ec5bd8fab06e3bc1bd46d7e67f18fa113c6b
192564.263:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: Selected cipher suite: GNUTLS_DHE_RSA_AES_256_GCM_SHA384
192564.263:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Parsing extension 'Safe Renegotiation/65281' (1 bytes)
192564.263:00cc:0214:trace:crypt:gnutls_log <4> EXT[0x7f4f3c006150]: Parsing extension 'Server Name Indication/0' (0 bytes)
192564.263:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: Safe renegotiation succeeded
192564.263:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1168
192564.263:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: buffers.c[_gnutls_io_read_buffered]:589
192564.263:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: kx.c[_gnutls_recv_server_certificate]:749
192564.263:00cc:0214:warn:winsock:WS2_recv_base  -> ERROR 10035
192564.283:00cc:0214:warn:winsock:WS2_recv_base  -> ERROR 10035
192564.313:00cc:0214:warn:winsock:WS2_recv_base  -> ERROR 10035
192564.343:00cc:0214:warn:winsock:WS2_recv_base  -> ERROR 10035
192564.373:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1168
192564.373:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: CERTIFICATE (11) was received. Length 5142[5142], frag offset 0, frag length: 5142, sequence: 0
192564.373:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1168
192564.373:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: SERVER KEY EXCHANGE (12) was received. Length 523[523], frag offset 0, frag length: 523, sequence: 0
192564.373:00cc:0214:trace:crypt:gnutls_log <1> FFDHE groups advertised, but server didn't support it; falling back to server's choice
192564.373:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: mpi.c[wrap_nettle_mpi_print]:60
192564.373:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: mpi.c[wrap_nettle_mpi_print]:60
192564.373:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: mpi.c[wrap_nettle_mpi_print]:60
192564.373:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: verify TLS 1.2 handshake data: using RSA-SHA256
192564.373:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1168
192564.373:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: SERVER HELLO DONE (14) was received. Length 0[0], frag offset 0, frag length: 0, sequence: 0
192564.373:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1159
192564.373:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: buffers.c[_gnutls_handshake_io_recv_int]:1411
192564.373:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: mpi.c[wrap_nettle_mpi_print]:60
192564.373:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: mpi.c[wrap_nettle_mpi_print]:60
192564.373:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: CLIENT KEY EXCHANGE was queued [134 bytes]
192564.373:00cc:0214:trace:crypt:gnutls_log <4> REC[0x7f4f3c006150]: Sent ChangeCipherSpec
192564.373:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: Cipher Suite: GNUTLS_DHE_RSA_AES_256_GCM_SHA384
192564.373:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: Initializing internal [write] cipher sessions
192564.373:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: recording tls-unique CB (send)
192564.373:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: FINISHED was queued [16 bytes]
192564.373:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: buffers.c[_gnutls_io_read_buffered]:589
192564.373:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: record.c[_gnutls_recv_int]:1775
192564.373:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: handshake.c[recv_handshake_final]:3322
192564.374:00cc:0214:warn:winsock:WS2_recv_base  -> ERROR 10035
192564.403:00cc:0214:warn:winsock:WS2_recv_base  -> ERROR 10035
192564.433:00cc:0214:warn:winsock:WS2_recv_base  -> ERROR 10035
192564.463:00cc:0214:warn:winsock:WS2_recv_base  -> ERROR 10035
192564.493:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: Cipher Suite: GNUTLS_DHE_RSA_AES_256_GCM_SHA384
192564.493:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: buffers.c[get_last_packet]:1168
192564.493:00cc:0214:trace:crypt:gnutls_log <4> HSK[0x7f4f3c006150]: FINISHED (20) was received. Length 12[12], frag offset 0, frag length: 12, sequence: 0
192564.623:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: buffers.c[_gnutls_io_read_buffered]:589
192564.623:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: record.c[_gnutls_recv_int]:1775
192564.623:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: buffers.c[_gnutls_io_read_buffered]:589
192564.623:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: record.c[_gnutls_recv_int]:1775
192564.624:00cc:0214:warn:winsock:WS2_recv_base  -> ERROR 10035
192564.772:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: buffers.c[_gnutls_io_read_buffered]:589
192564.772:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: record.c[_gnutls_recv_int]:1775
192564.772:00cc:0214:warn:winsock:WS2_recv_base  -> ERROR 10035
192564.902:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: buffers.c[_gnutls_io_read_buffered]:589
192564.902:00cc:0214:trace:crypt:gnutls_log <3> ASSERT: record.c[_gnutls_recv_int]:1775
192564.902:00cc:00d0:trace:seh:RtlGrowFunctionTable 00007FFFE6A877F0, 55

After this the loading screen remains stuck indefinitely with the voice error message, and logs unfortunately don't procur anything useful apart from RtlGrowFunctionTable calls. To my layman's eye it looks like the handshake was successful in both cases and there's no indication of what's going awry.

Tested with Proton Experimental (wine-5.13-1208-ge112e54b65d at the time of writing).

NNTMan 2022-11-06 github

Didn't work with Proton 7.0-4, Proton Experimental, Proton Hotfix.
Even proton log file didn't appear in home directory.

GameAction [AppID 1361000, ActionID 18] : LaunchApp changed task to ProcessingInstallScript with ""
GameAction [AppID 1361000, ActionID 18] : LaunchApp changed task to SynchronizingCloud with ""
GameAction [AppID 1361000, ActionID 18] : LaunchApp changed task to SynchronizingControllerConfig with ""
Loaded Config for Local Override Path for App ID 1361000, Controller 0: /home/mikhail/.local/share/Steam//controller_base/empty.vdf
GameAction [AppID 1361000, ActionID 18] : LaunchApp changed task to SiteLicenseSeatCheckout with ""
GameAction [AppID 1361000, ActionID 18] : LaunchApp changed task to CreatingProcess with ""
GameAction [AppID 1361000, ActionID 18] : LaunchApp waiting for user response to CreatingProcess ""
GameAction [AppID 1361000, ActionID 18] : LaunchApp continues with user response "CreatingProcess"
/bin/sh\0-c\0PROTON_LOG=1 /home/mikhail/.local/share/Steam/ubuntu12_32/reaper SteamLaunch AppId=1361000 -- /home/mikhail/.local/share/Steam/ubuntu12_32/steam-launch-wrapper -- '/home/mikhail/.local/share/Steam/steamapps/common/SteamLinuxRuntime_soldier'/_v2-entry-point --verb=waitforexitandrun -- '/home/mikhail/.local/share/Steam/steamapps/common/Proton Hotfix'/proton waitforexitandrun  '/home/mikhail/.local/share/Steam/steamapps/common/In Silence/In Silence.exe' +connect_lobby <64-bit lobby Steam ID>\0
Game process added : AppID 1361000 "PROTON_LOG=1 /home/mikhail/.local/share/Steam/ubuntu12_32/reaper SteamLaunch AppId=1361000 -- /home/mikhail/.local/share/Steam/ubuntu12_32/steam-launch-wrapper -- '/home/mikhail/.local/share/Steam/steamapps/common/SteamLinuxRuntime_soldier'/_v2-entry-point --verb=waitforexitandrun -- '/home/mikhail/.local/share/Steam/steamapps/common/Proton Hotfix'/proton waitforexitandrun  '/home/mikhail/.local/share/Steam/steamapps/common/In Silence/In Silence.exe' +connect_lobby <64-bit lobby Steam ID>", ProcID 102965, IP 0.0.0.0:0
chdir /home/mikhail/.local/share/Steam/steamapps/common/In Silence
ERROR: ld.so: object '/home/mikhail/.local/share/Steam/ubuntu12_32/gameoverlayrenderer.so' from LD_PRELOAD cannot be preloaded (wrong ELF class: ELFCLASS32): ignored.
Loaded Config for Local Override Path for App ID 1361000, Controller 0: /home/mikhail/.local/share/Steam//controller_base/empty.vdf
GameAction [AppID 1361000, ActionID 18] : LaunchApp changed task to WaitingGameWindow with ""
/bin/sh: -c: line 1: syntax error near unexpected token `newline'
/bin/sh: -c: line 1: `PROTON_LOG=1 /home/mikhail/.local/share/Steam/ubuntu12_32/reaper SteamLaunch AppId=1361000 -- /home/mikhail/.local/share/Steam/ubuntu12_32/steam-launch-wrapper -- '/home/mikhail/.local/share/Steam/steamapps/common/SteamLinuxRuntime_soldier'/_v2-entry-point --verb=waitforexitandrun -- '/home/mikhail/.local/share/Steam/steamapps/common/Proton Hotfix'/proton waitforexitandrun  '/home/mikhail/.local/share/Steam/steamapps/common/In Silence/In Silence.exe' +connect_lobby <64-bit lobby Steam ID>'
GameAction [AppID 1361000, ActionID 18] : LaunchApp changed task to Completed with ""
Game process removed: AppID 1361000 "PROTON_LOG=1 /home/mikhail/.local/share/Steam/ubuntu12_32/reaper SteamLaunch AppId=1361000 -- /home/mikhail/.local/share/Steam/ubuntu12_32/steam-launch-wrapper -- '/home/mikhail/.local/share/Steam/steamapps/common/SteamLinuxRuntime_soldier'/_v2-entry-point --verb=waitforexitandrun -- '/home/mikhail/.local/share/Steam/steamapps/common/Proton Hotfix'/proton waitforexitandrun  '/home/mikhail/.local/share/Steam/steamapps/common/In Silence/In Silence.exe' +connect_lobby <64-bit lobby Steam ID>", ProcID 102965 
Uploaded AppInterfaceStats to Steam

Proton versions

Launch options

Upstream links