search

ValveSoftware / steam-for-linux

Issue tracking for the Steam for Linux beta client
4.25k stars 175 forks source link

'Waiting for network' delays Steam launch when using systemd-networkd #9966

Open epicbattle1 opened 1 year ago

epicbattle1 commented 1 year ago

Your system information

Please describe your issue in as much detail as possible:

Steam hangs on 'Waiting for network' for about 4-5 seconds before launching like usual. I used NetworkManager before and the issue was not present.

Steps for reproducing this issue:

  1. Use systemd-networkd as one's network manager
  2. Run Steam
epicbattle1 commented 1 year ago

The same can be observed with connman on Arch. And strangely, I didn't have this problem when using connman on Artix so it may be a systemd issue. It would be great if someone else tested this on another distribution.

colemickens commented 1 year ago

Same thing happens frequently to me on NixOS, I use systemd-network + iwd (not that it should matter, and I'm usually on ethernet if I'm using Steam)

sysms commented 1 year ago

Same 'Waiting for network'-pause is happening on Debian systems using network configuration in '/etc/network/interfaces' (standard Debian network setup configuration).

TTimo commented 1 year ago

Hello,

Can you provide your client_networkmanager.txt - can you correlate this delay with output in that log?

epicbattle1 commented 1 year ago

Hello,

Here's the log: client_networkmanager.txt

And no, the output appears as soon as the client starts, so when I look at this 'Waiting for network' window, it already says in the logs that NetworkManager client is successfully created.

Natr1x commented 1 year ago

I seem to be having the same issue. Going by the connection_log.txt it might be a failing IPv6 UDP connectivity test which is causing it.

[2023-08-27 15:44:21] Client version: 1690583737
[2023-08-27 15:44:21] Connectivity test: Starting test, fetching 'http://test.steampowered.com/204'
[2023-08-27 15:44:21] [Logged Off, 0, 0] [U:1:0] CCMInterface::SetSteamID( [U:1:0] )
[2023-08-27 15:44:21] IPv6 HTTP connectivity test (ipv6check-http.steamserver.net / [2a01:bc80:8:107::9b85:fc42]:80 ([2a01:bc80:8:107::9b85:fc42]:80)) - TIMEOUT
[2023-08-27 15:44:21] Connectivity test: OK!
[2023-08-27 15:44:21] Connectivity test: result=Connected (since 0.0s ago), prev=Unknown, in progress=0
[2023-08-27 15:44:21] [Logged Off, 0, 0] [U:1:32423023] CCMInterface::SetSteamID( [U:1:32423023] )
[2023-08-27 15:44:23] IPv6 UDP connectivity test (ipv6check-udp.steamserver.net / 2a01:bc80:8:107::9b85:fc43) - TIMEOUT
[2023-08-27 15:44:31] [Logged Off, 0, 0] [U:1:32423023] CCMInterface::SetSteamID( [U:1:32423023] )
[2023-08-27 15:44:31] [Logged Off, 4, 0] [U:1:32423023] LogOn() called; not connected yet, scheduling connection. Schedule init returned 1
[2023-08-27 15:44:31] CCMInterface::YieldingConnect -- calling ISteamDirectory/GetCMListForConnect web api
[2023-08-27 15:44:31] GetCMListForConnect -- got 5 Netfilter CMs and 75 WebSocket CMs
[2023-08-27 15:44:31] GetCMListForConnect -- DC 'sto1' count: 21
[2023-08-27 15:44:31] GetCMListForConnect -- DC 'sto2' count: 22
[2023-08-27 15:44:31] GetCMListForConnect -- DC 'fra2' count: 19
[2023-08-27 15:44:31] GetCMListForConnect -- DC 'fra1' count: 18
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext1-sto1.steamserver.net:27033) starting...
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext1-sto2.steamserver.net:27019) starting...
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext1-sto1.steamserver.net:27034) starting...
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext1-sto2.steamserver.net:27031) starting...
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext1-sto2.steamserver.net:27019) results: 27.8645ms + load 85.0000
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext1-sto1.steamserver.net:443) starting...
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext1-sto1.steamserver.net:27033) results: 42.4395ms + load 78.0000
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext2-sto2.steamserver.net:443) starting...
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext1-sto1.steamserver.net:27034) results: 20.6985ms + load 83.0000
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext1-fra2.steamserver.net:27036) starting...
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext1-sto2.steamserver.net:27031) results: 35.0943ms + load 88.0000
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext1-fra2.steamserver.net:27031) starting...
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext2-sto2.steamserver.net:443) results: 11.6501ms + load 105.0000
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext2-fra1.steamserver.net:27033) starting...
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext1-sto1.steamserver.net:443) results: 55.1586ms + load 101.0000
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext1-fra1.steamserver.net:27030) starting...
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext1-fra2.steamserver.net:27036) results: 48.7490ms + load 84.0000
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext1-fra2.steamserver.net:27031) results: 92.1459ms + load 85.0000
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext2-fra1.steamserver.net:27033) results: 92.0334ms + load 81.0000
[2023-08-27 15:44:31] [Connecting, 4, 0] [U:1:32423023] PingWebSocketCM() (ext1-fra1.steamserver.net:27030) results: 84.7511ms + load 83.0000
[2023-08-27 15:44:31] [Connecting, 4, 7] [U:1:32423023] Connect() starting connection (eNetQOSLevelHigh, ext1-sto1.steamserver.net:27034, WebSocket)
[2023-08-27 15:44:31] [Connecting, 4, 7] [U:1:32423023] ConnectionCompleted() (162.254.198.44:27034, WebSocket) local address (192.168.0.228:53519)
[2023-08-27 15:44:31] [Connecting, 4, 7] [U:1:32423023] Client thinks it can connect via: UDP - yes, TCP - yes, WebSocket:443 - yes, WebSocket:Non443 - yes
[2023-08-27 15:44:31] [Connected, 4, 7] [U:1:32423023] Logging on [U:1:32423023]
[2023-08-27 15:44:31] [Logging On, 4, 7] [U:1:32423023] Using JWT
[2023-08-27 15:44:32] [Logging On, 4, 7] [U:1:32423023] RecvMsgClientLogOnResponse() : [U:1:32423023] 'OK'
[2023-08-27 15:44:32] CClientJobGetClientUpdateHosts: cached version not expired
[2023-08-27 15:44:40] [Logged On, 4, 7] [U:1:32423023] LogOff()
[2023-08-27 15:44:40] [Logging Off, 4, 7] [U:1:32423023] AsyncDisconnect( bDontWaitOnTCPShutdown: false )
[2023-08-27 15:44:40] [Logged Off, 0, 0] [U:1:32423023] ConnectionDisconnected('Disconnected By Remote Host') : 'OK' (162.254.198.44:27034, WebSocket)
[2023-08-27 15:44:40] [Logged Off, 0, 0] [U:1:32423023] ConnectionDisconnected() not auto reconnecting due to user initiated logoff
[2023-08-27 15:44:40] [Logged Off, 0, 0] [U:1:32423023] Sending SteamServersDisconnected_t because we were logged on
[2023-08-27 15:44:42] [Logged Off, 0, 0] [U:1:32423023] ResetVoiceRecording() (was recording: 0) (last get voice time: 1693143882 seconds ago)
[2023-08-27 15:44:42] [Logged Off, 0, 0] [U:1:32423023] Log session ended
TTimo commented 1 year ago

Please make sure to test with the current steam beta. We removed an unnecessary check for online connectivity since last time I posted here.

colemickens commented 1 year ago

I haven't really noticed this recently, whereas I was weeks ago. I'm opted into client beta.

epicbattle1 commented 1 year ago

I still experience the issue unfortunately, opted into Beta and nothing changed. IPv6 shouldn't be an issue because I tested with it being disabled.

(systemd-networkd, IPv6 disabled)
[2023-09-02 23:05:47] Client version: 1693527575
[2023-09-02 23:05:47] Connectivity test: Starting test, fetching 'http://test.steampowered.com/204'
[2023-09-02 23:05:47] [Logged Off, 0, 0] [U:1:0] CCMInterface::SetSteamID( [U:1:0] )
[2023-09-02 23:05:48] Connectivity test: OK!
[2023-09-02 23:05:48] Connectivity test: result=Connected (since 0.0s ago), prev=Unknown, in progress=0
[2023-09-02 23:05:51] [Logged Off, 0, 0] [U:1:241666834] CCMInterface::SetSteamID( [U:1:241666834] )
[2023-09-02 23:06:01] [Logged Off, 0, 0] [U:1:241666834] CCMInterface::SetSteamID( [U:1:241666834] )
[2023-09-02 23:06:01] [Logged Off, 4, 0] [U:1:241666834] LogOn() called; not connected yet, scheduling connection. Schedule init returned 1
[2023-09-02 23:06:01] CCMInterface::YieldingConnect -- calling ISteamDirectory/GetCMListForConnect web api
[2023-09-02 23:06:01] GetCMListForConnect -- got 5 Netfilter CMs and 75 WebSocket CMs
[2023-09-02 23:06:01] GetCMListForConnect -- DC 'sto2' count: 20
[2023-09-02 23:06:01] GetCMListForConnect -- DC 'sto1' count: 22
[2023-09-02 23:06:01] GetCMListForConnect -- DC 'fra1' count: 21
[2023-09-02 23:06:01] GetCMListForConnect -- DC 'fra2' count: 17
[2023-09-02 23:06:01] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto1.steamserver.net:27031) starting...
[2023-09-02 23:06:01] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-sto2.steamserver.net:27021) starting...
[2023-09-02 23:06:01] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto1.steamserver.net:27022) starting...
[2023-09-02 23:06:01] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-sto2.steamserver.net:443) starting...
[2023-09-02 23:06:02] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-sto2.steamserver.net:27021) results: 70.7378ms + load 88.0000
[2023-09-02 23:06:02] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-sto2.steamserver.net:27037) starting...
[2023-09-02 23:06:02] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto1.steamserver.net:27022) results: 78.9213ms + load 94.0000
[2023-09-02 23:06:02] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-sto1.steamserver.net:443) starting...
[2023-09-02 23:06:02] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-sto2.steamserver.net:443) results: 95.4094ms + load 108.0000
[2023-09-02 23:06:02] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-fra1.steamserver.net:27036) starting...
[2023-09-02 23:06:02] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto1.steamserver.net:27031) results: 77.3640ms + load 93.0000
[2023-09-02 23:06:02] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-fra1.steamserver.net:27038) starting...
[2023-09-02 23:06:02] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-sto2.steamserver.net:27037) results: 55.6192ms + load 87.0000
[2023-09-02 23:06:02] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-fra2.steamserver.net:27033) starting...
[2023-09-02 23:06:02] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-sto1.steamserver.net:443) results: 48.9016ms + load 109.0000
[2023-09-02 23:06:02] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-fra2.steamserver.net:27022) starting...
[2023-09-02 23:06:02] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-fra1.steamserver.net:27036) results: 84.5461ms + load 94.0000
[2023-09-02 23:06:02] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-fra1.steamserver.net:27038) results: 141.1476ms + load 93.0000
[2023-09-02 23:06:02] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-fra2.steamserver.net:27033) results: 91.8350ms + load 89.0000
[2023-09-02 23:06:02] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-fra2.steamserver.net:27022) results: 84.9094ms + load 90.0000
[2023-09-02 23:06:02] [Connecting, 4, 7] [U:1:241666834] Connect() starting connection (eNetQOSLevelHigh, ext2-sto2.steamserver.net:27037, WebSocket)
[2023-09-02 23:06:02] [Connecting, 4, 7] [U:1:241666834] ConnectionCompleted() (155.133.252.54:27037, WebSocket) local address (100.125.148.56:43887)
[2023-09-02 23:06:02] [Connecting, 4, 7] [U:1:241666834] Client thinks it can connect via: UDP - yes, TCP - yes, WebSocket:443 - yes, WebSocket:Non443 - yes
[2023-09-02 23:06:02] [Connected, 4, 7] [U:1:241666834] Logging on [U:1:241666834]
[2023-09-02 23:06:02] [Logging On, 4, 7] [U:1:241666834] Using JWT
[2023-09-02 23:06:02] [Logging On, 4, 7] [U:1:241666834] RecvMsgClientLogOnResponse() : [U:1:241666834] 'OK'
[2023-09-02 23:06:03] CClientJobGetClientUpdateHosts: cached version not expired
[2023-09-02 23:07:52] [Logged On, 4, 7] [U:1:241666834] LogOff()
[2023-09-02 23:07:52] [Logging Off, 4, 7] [U:1:241666834] AsyncDisconnect( bDontWaitOnTCPShutdown: false )
[2023-09-02 23:07:52] [Logged Off, 0, 0] [U:1:241666834] ConnectionDisconnected('Disconnected By Remote Host') : 'OK' (155.133.252.54:27037, WebSocket)
[2023-09-02 23:07:52] [Logged Off, 0, 0] [U:1:241666834] ConnectionDisconnected() not auto reconnecting due to user initiated logoff
[2023-09-02 23:07:52] [Logged Off, 0, 0] [U:1:241666834] Sending SteamServersDisconnected_t because we were logged on
[2023-09-02 23:07:58] [Logged Off, 0, 0] [U:1:241666834] ResetVoiceRecording() (was recording: 0) (last get voice time: 1693685278 seconds ago)
[2023-09-02 23:07:58] [Logged Off, 0, 0] [U:1:241666834] Log session ended

(systemd-networkd, IPv6 enabled)
[2023-09-02 23:10:30] Client version: 1693527575
[2023-09-02 23:10:30] Connectivity test: Starting test, fetching 'http://test.steampowered.com/204'
[2023-09-02 23:10:30] [Logged Off, 0, 0] [U:1:0] CCMInterface::SetSteamID( [U:1:0] )
[2023-09-02 23:10:30] Connectivity test: OK!
[2023-09-02 23:10:30] Connectivity test: result=Connected (since 0.0s ago), prev=Unknown, in progress=0
[2023-09-02 23:10:31] IPv6 HTTP connectivity test (ipv6check-http.steamserver.net / [2a01:bc80:4:100::4]:80 ([2a01:bc80:4:100::4]:80)) - TIMEOUT
[2023-09-02 23:10:31] [Logged Off, 0, 0] [U:1:241666834] CCMInterface::SetSteamID( [U:1:241666834] )
[2023-09-02 23:10:33] IPv6 UDP connectivity test (ipv6check-udp.steamserver.net / 2a01:bc80:5:111::3) - TIMEOUT
[2023-09-02 23:10:41] [Logged Off, 0, 0] [U:1:241666834] CCMInterface::SetSteamID( [U:1:241666834] )
[2023-09-02 23:10:41] [Logged Off, 4, 0] [U:1:241666834] LogOn() called; not connected yet, scheduling connection. Schedule init returned 1
[2023-09-02 23:10:41] CCMInterface::YieldingConnect -- calling ISteamDirectory/GetCMListForConnect web api
[2023-09-02 23:10:41] GetCMListForConnect -- got 7 Netfilter CMs and 73 WebSocket CMs
[2023-09-02 23:10:41] GetCMListForConnect -- DC 'sto2' count: 22
[2023-09-02 23:10:41] GetCMListForConnect -- DC 'sto1' count: 21
[2023-09-02 23:10:41] GetCMListForConnect -- DC 'fra1' count: 20
[2023-09-02 23:10:41] GetCMListForConnect -- DC 'fra2' count: 17
[2023-09-02 23:10:41] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto2.steamserver.net:27034) starting...
[2023-09-02 23:10:41] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto2.steamserver.net:27022) starting...
[2023-09-02 23:10:41] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-sto2.steamserver.net:443) starting...
[2023-09-02 23:10:41] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto1.steamserver.net:443) starting...
[2023-09-02 23:10:41] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto2.steamserver.net:27034) results: 72.0005ms + load 85.0000
[2023-09-02 23:10:41] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto1.steamserver.net:27034) starting...
[2023-09-02 23:10:41] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto2.steamserver.net:27022) results: 64.8870ms + load 85.0000
[2023-09-02 23:10:41] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto1.steamserver.net:27030) starting...
[2023-09-02 23:10:41] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-sto2.steamserver.net:443) results: 43.0841ms + load 106.0000
[2023-09-02 23:10:41] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-fra1.steamserver.net:27032) starting...
[2023-09-02 23:10:41] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto1.steamserver.net:443) results: 84.3945ms + load 103.0000
[2023-09-02 23:10:41] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-fra2.steamserver.net:27029) starting...
[2023-09-02 23:10:42] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto1.steamserver.net:27030) results: 71.8584ms + load 88.0000
[2023-09-02 23:10:42] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-fra1.steamserver.net:27038) starting...
[2023-09-02 23:10:42] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto1.steamserver.net:27034) results: 67.5259ms + load 92.0000
[2023-09-02 23:10:42] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-fra2.steamserver.net:27020) starting...
[2023-09-02 23:10:42] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-fra1.steamserver.net:27032) results: 115.6931ms + load 84.0000
[2023-09-02 23:10:42] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-fra2.steamserver.net:27029) results: 130.0945ms + load 90.0000
[2023-09-02 23:10:42] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-fra1.steamserver.net:27038) results: 81.9359ms + load 85.0000
[2023-09-02 23:10:42] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-fra2.steamserver.net:27020) results: 82.0580ms + load 91.0000
[2023-09-02 23:10:42] [Connecting, 4, 7] [U:1:241666834] Connect() starting connection (eNetQOSLevelHigh, ext2-sto2.steamserver.net:443, WebSocket)
[2023-09-02 23:10:42] [Connecting, 4, 7] [U:1:241666834] ConnectionCompleted() (155.133.252.54:443, WebSocket) local address (100.125.66.253:39479)
[2023-09-02 23:10:42] [Connecting, 4, 7] [U:1:241666834] Client thinks it can connect via: UDP - yes, TCP - yes, WebSocket:443 - yes, WebSocket:Non443 - yes
[2023-09-02 23:10:42] [Connected, 4, 7] [U:1:241666834] Logging on [U:1:241666834]
[2023-09-02 23:10:42] [Logging On, 4, 7] [U:1:241666834] Using JWT
[2023-09-02 23:10:42] [Logging On, 4, 7] [U:1:241666834] RecvMsgClientLogOnResponse() : [U:1:241666834] 'OK'
[2023-09-02 23:10:43] CClientJobGetClientUpdateHosts: cached version not expired

(NetworkManager)
[2023-09-03 00:21:23] Client version: 1693527575
[2023-09-03 00:21:23] Connectivity test: Starting test, fetching 'http://test.steampowered.com/204'
[2023-09-03 00:21:23] [Logged Off, 0, 0] [U:1:0] CCMInterface::SetSteamID( [U:1:0] )
[2023-09-03 00:21:23] IPv6 HTTP connectivity test (ipv6check-http.steamserver.net / [2a01:bc80:7:100::9b85:f80d]:80 ([2a01:bc80:7:100::9b85:f80d]:80)) - TIMEOUT
[2023-09-03 00:21:23] Connectivity test: OK!
[2023-09-03 00:21:23] Connectivity test: result=Connected (since 0.0s ago), prev=Unknown, in progress=0
[2023-09-03 00:21:24] [Logged Off, 0, 0] [U:1:241666834] CCMInterface::SetSteamID( [U:1:241666834] )
[2023-09-03 00:21:24] [Logged Off, 0, 0] [U:1:241666834] CCMInterface::SetSteamID( [U:1:241666834] )
[2023-09-03 00:21:24] [Logged Off, 4, 0] [U:1:241666834] LogOn() called; not connected yet, scheduling connection. Schedule init returned 1
[2023-09-03 00:21:24] CCMInterface::YieldingConnect -- calling ISteamDirectory/GetCMListForConnect web api
[2023-09-03 00:21:25] GetCMListForConnect -- got 3 Netfilter CMs and 77 WebSocket CMs
[2023-09-03 00:21:25] GetCMListForConnect -- DC 'sto2' count: 22
[2023-09-03 00:21:25] GetCMListForConnect -- DC 'sto1' count: 20
[2023-09-03 00:21:25] GetCMListForConnect -- DC 'fra1' count: 21
[2023-09-03 00:21:25] GetCMListForConnect -- DC 'fra2' count: 17
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-sto2.steamserver.net:27021) starting...
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto2.steamserver.net:27023) starting...
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto1.steamserver.net:27029) starting...
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto1.steamserver.net:27028) starting...
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-sto2.steamserver.net:27021) results: 129.8134ms + load 75.0000
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-fra1.steamserver.net:27020) starting...
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto2.steamserver.net:27023) results: 111.8565ms + load 76.0000
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto1.steamserver.net:443) starting...
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto1.steamserver.net:27028) results: 92.1428ms + load 76.0000
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-sto2.steamserver.net:443) starting...
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto1.steamserver.net:27029) results: 79.5135ms + load 76.0000
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-fra1.steamserver.net:27021) starting...
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-sto2.steamserver.net:443) results: 69.7112ms + load 87.0000
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-fra2.steamserver.net:27023) starting...
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-sto1.steamserver.net:443) results: 69.3395ms + load 88.0000
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-fra2.steamserver.net:27028) starting...
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-fra1.steamserver.net:27021) results: 93.1845ms + load 76.0000
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-fra1.steamserver.net:27020) results: 188.2005ms + load 78.0000
[2023-09-03 00:21:25] IPv6 UDP connectivity test (ipv6check-udp.steamserver.net / 2a01:bc80:5:112::2) - TIMEOUT
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext2-fra2.steamserver.net:27028) results: 128.6743ms + load 77.0000
[2023-09-03 00:21:25] [Connecting, 4, 0] [U:1:241666834] PingWebSocketCM() (ext1-fra2.steamserver.net:27023) results: 103.3664ms + load 79.0000
[2023-09-03 00:21:25] [Connecting, 4, 7] [U:1:241666834] Connect() starting connection (eNetQOSLevelHigh, ext1-sto1.steamserver.net:27029, WebSocket)
[2023-09-03 00:21:26] [Connecting, 4, 7] [U:1:241666834] ConnectionCompleted() (162.254.198.44:27029, WebSocket) local address (100.125.88.24:53567)
[2023-09-03 00:21:26] [Connecting, 4, 7] [U:1:241666834] Client thinks it can connect via: UDP - yes, TCP - yes, WebSocket:443 - yes, WebSocket:Non443 - yes
[2023-09-03 00:21:26] [Connected, 4, 7] [U:1:241666834] Logging on [U:1:241666834]
[2023-09-03 00:21:26] [Logging On, 4, 7] [U:1:241666834] Using JWT
[2023-09-03 00:21:26] [Logging On, 4, 7] [U:1:241666834] RecvMsgClientLogOnResponse() : [U:1:241666834] 'OK'
[2023-09-03 00:21:26] CClientJobGetClientUpdateHosts: cached version not expired
epicbattle1 commented 1 year ago

Also, here's my .network file, maybe it's somehow relevant

[Match]
Name=en*
Name=eth*

[Network]
DHCP=yes
DNS=1.1.1.1 1.0.0.1
MulticastDNS=yes
#LinkLocalAddressing=ipv4
#IPv6AcceptRA=no

[DHCPv4]
UseDNS=0
sysms commented 1 year ago

Problem got even worse after the latest (11.09.23) stable Steam Client update. Before it was a pause around 5 seconds., now it's a 10 second pause. :(

TTimo commented 1 year ago

We removed an unnecessary check for online connectivity since last time I posted here.

I was wrong we haven't done that yet.

epicbattle1 commented 1 year ago

Any update on the issue?

kisak-valve commented 1 year ago

Hello, is anyone still experiencing this issue on an up to date system?

sysms commented 12 months ago

@kisak-valve on the latest stable steam client on an up to date Debian 12 system, the problem still persists. I'm still getting the 10 second pause on "waiting for network".

Steam Version: 1698777785 Steam Client Build Date: Tue, Oct 31 03:09 UTC -08:00

jupjohn commented 12 months ago

Can also replicate a ~10s "waiting for network" delay on the following beta install:

Steam Version:  1699498641
Steam Client Build Date:  Thu, Nov 9 2:56 PM UTC -08:00
Steam Web Build Date:  Thu, Nov 9 3:44 PM UTC -08:00
Steam API Version:  SteamClient021
sysms commented 11 months ago

The latest stable client (Steam Version: 1700160213, Steam Client Build Date: Thu, Nov 16 19:23 UTC -08:00) still has this.

sysms commented 11 months ago

Still present in the latest update (Steam Version: 1701289036, Steam Client Build Date: Wed, Nov 29 20:59 UTC -08:00).

XenHat commented 9 months ago

Still present in the latest version on my distribution:


Steam Beta Branch:  Steam Deck Stable
Steam Version:  1705108172
Steam Client Build Date:  Fri, Jan 12 19:52 UTC -08:00
Steam Web Build Date:  Fri, Jan 12 12:02 UTC -08:00```
bblacher commented 8 months ago

I do experience this as well, latest beta, using systemd-networkd.

When using NetworkManager, the delay is gone.

bloodbornedisease commented 3 months ago

exact same issue on void linux. i am using DHCPCD and connected through ethernet. here is an excerpt from the "connection_log.txt" file from steam.

[2024-07-28 20:41:10] Client version: 1721173382
[2024-07-28 20:41:10] Connectivity test: Starting test, fetching 'http://test.steampowered.com/204'
[2024-07-28 20:41:10] [Logged Off, 0, 0] [U:1:0] CCMInterface::SetSteamID( [U:1:0] )
[2024-07-28 20:41:10] Connectivity test (23.219.157.185:80 (23.219.157.185:80)): OK!
[2024-07-28 20:41:10] Connectivity test: result=Connected (since 0.0s ago), prev=Unknown, in progress=0
[2024-07-28 20:41:10] IPv6 HTTP connectivity test (ipv6check-http.steamserver.net / [2602:801:f006:100::a2fe:c30e]:80 ([2602:801:f006:100::a2fe:c30e]:80)) - SUCCESS
[2024-07-28 20:41:10] IPv6 HTTP connectivity test (ipv6check-http.steamserver.net / [2602:801:f006:100::a2fe:c30e]:80 ([2602:801:f006:100::a2fe:c30e]:80)) - server indicated we are using ipv6, external address = '**REDACTED**'
[2024-07-28 20:41:10] IPv6 UDP connectivity test (ipv6check-udp.steamserver.net / [2602:801:f002:101::a2fe:c00f]:27019) - SUCCESS`
[2024-07-28 20:41:11] [Logged Off, 0, 0] [U:1:913393195] CCMInterface::SetSteamID( [U:1:913393195] )
[2024-07-28 20:41:21] [Logged Off, 0, 0] [U:1:913393195] CCMInterface::SetSteamID( [U:1:913393195] )
[2024-07-28 20:41:21] [Logged Off, 4, 0] [U:1:913393195] LogOn() called; not connected yet, scheduling connection. Schedule init returned 1`
[2024-07-28 20:41:21] CCMInterface::YieldingConnect -- calling ISteamDirectory/GetCMListForConnect web api
[2024-07-28 20:41:21] GetCMListForConnect -- got 7 Netfilter CMs and 73 WebSocket CMs
[2024-07-28 20:41:21] GetCMListForConnect -- DC 'dfw1' count: 20
[2024-07-28 20:41:21] GetCMListForConnect -- DC 'lax1' count: 20
[2024-07-28 20:41:21] GetCMListForConnect -- DC 'atl3' count: 21
[2024-07-28 20:41:21] GetCMListForConnect -- DC 'ord1' count: 19
[2024-07-28 20:41:21] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext1-dfw1.steamserver.net:27032) starting...
[2024-07-28 20:41:21] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext2-dfw1.steamserver.net:27028) starting...
[2024-07-28 20:41:21] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext2-dfw1.steamserver.net:443) starting...
[2024-07-28 20:41:21] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext2-lax1.steamserver.net:27028) starting...
[2024-07-28 20:41:22] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext1-dfw1.steamserver.net:27032) results: 49.2811ms + load 29.0000
[2024-07-28 20:41:22] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() stopping early for good host (ext1-dfw1.steamserver.net:27032)
[2024-07-28 20:41:22] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext2-dfw1.steamserver.net:27028) results: 45.6791ms + load 29.0000
[2024-07-28 20:41:22] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext2-dfw1.steamserver.net:443) results: 42.0017ms + load 42.0000
[2024-07-28 20:41:22] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext2-lax1.steamserver.net:27028) results: 94.2812ms + load 26.0000
[2024-07-28 20:41:22] [Connecting, 4, 7] [U:1:913393195] Connect() starting connection (eNetQOSLevelHigh, ext2-dfw1.steamserver.net:27028, WebSocket)
[2024-07-28 20:41:22] [Connecting, 4, 7] [U:1:913393195] ConnectionCompleted() (155.133.253.50:27028, WebSocket) local address (192.168.0.68:57529)
[2024-07-28 20:41:22] [Connecting, 4, 7] [U:1:913393195] Client thinks it can connect via: UDP - yes, TCP - yes, WebSocket:443 - yes, WebSocket:Non443 - yes
[2024-07-28 20:41:22] [Connected, 4, 7] [U:1:913393195] Logging on [U:1:913393195]
[2024-07-28 20:41:22] [Logging On, 4, 7] [U:1:913393195] Using JWT
[2024-07-28 20:41:22] [Logging On, 4, 7] [U:1:913393195] RecvMsgClientLogOnResponse() : [U:1:913393195] 'OK'
[2024-07-28 20:41:22] CClientJobGetClientUpdateHosts: cached version not expired

the most interesting bit is [2024-07-28 20:41:11] [Logged Off, 0, 0] [U:1:913393195] CCMInterface::SetSteamID( [U:1:913393195] ) [2024-07-28 20:41:21] [Logged Off, 0, 0] [U:1:913393195] CCMInterface::SetSteamID( [U:1:913393195] ) [2024-07-28 20:41:21] [Logged Off, 4, 0] [U:1:913393195] LogOn() called; not connected yet, scheduling connection. Schedule init returned 1

i would assume it's an issue with my own system, but if so many other people are experiencing this issue, and i don't have problems with any other applications on my PC, it leads me to believe it's a problem with steam itself. perhaps i'll try to see if network manager gives me the same problems.

bloodbornedisease commented 3 months ago

I do experience this as well, latest beta, using systemd-networkd.

When using NetworkManager, the delay is gone.

setting up networkmanager did indeed fix the issue. this should be looked into, as i can't say i prefer running this over the default void network manager.

here is what the steam connection_log.txt file shows while my system utilizes network manager. it's still showing that "logged off" portion, so i guess that isn't a problem. steam now boots in about five seconds without showing the "waiting for network" splash screen, which is satisfactory.

[2024-07-29 11:27:58] Connectivity test: Starting test, fetching 'http://test.steampowered.com/204'
[2024-07-29 11:27:58] [Logged Off, 0, 0] [U:1:0] CCMInterface::SetSteamID( [U:1:0] )
[2024-07-29 11:27:59] Connectivity test (23.219.157.185:80 (23.219.157.185:80)): OK!
[2024-07-29 11:27:59] Connectivity test: result=Connected (since 0.0s ago), prev=Unknown, in progress=0
[2024-07-29 11:27:59] IPv6 HTTP connectivity test (ipv6check-http.steamserver.net / [2602:801:f002:101::a2fe:c005]:80 ([2602:801:f002:101::a2fe:c005]:80)) - SUCCESS
[2024-07-29 11:27:59] IPv6 HTTP connectivity test (ipv6check-http.steamserver.net / [2602:801:f002:101::a2fe:c005]:80 ([2602:801:f002:101::a2fe:c005]:80)) - server indicated we are using ipv6, external address = 'REDACTED'
[2024-07-29 11:27:59] IPv6 UDP connectivity test (ipv6check-udp.steamserver.net / [2602:801:f002:101::a2fe:c007]:27019) - SUCCESS
[2024-07-29 11:28:01] [Logged Off, 0, 0] [U:1:913393195] CCMInterface::SetSteamID( [U:1:913393195] )
[2024-07-29 11:28:01] [Logged Off, 0, 0] [U:1:913393195] CCMInterface::SetSteamID( [U:1:913393195] )
[2024-07-29 11:28:01] [Logged Off, 4, 0] [U:1:913393195] LogOn() called; not connected yet, scheduling connection. Schedule init returned 1
[2024-07-29 11:28:01] CCMInterface::YieldingConnect -- calling ISteamDirectory/GetCMListForConnect web api
[2024-07-29 11:28:02] GetCMListForConnect -- got 6 Netfilter CMs and 74 WebSocket CMs
[2024-07-29 11:28:02] GetCMListForConnect -- DC 'dfw1' count: 20
[2024-07-29 11:28:02] GetCMListForConnect -- DC 'lax1' count: 20
[2024-07-29 11:28:02] GetCMListForConnect -- DC 'atl3' count: 22
[2024-07-29 11:28:02] GetCMListForConnect -- DC 'ord1' count: 18
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext1-dfw1.steamserver.net:27031) starting...
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext2-dfw1.steamserver.net:27033) starting...
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext1-dfw1.steamserver.net:443) starting...
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext2-lax1.steamserver.net:27038) starting...
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext1-dfw1.steamserver.net:27031) results: 67.0648ms + load 22.0000
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext2-atl3.steamserver.net:27034) starting...
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext1-dfw1.steamserver.net:443) results: 67.1413ms + load 32.0000
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext2-lax1.steamserver.net:27036) starting...
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext2-dfw1.steamserver.net:27033) results: 67.2402ms + load 21.0000
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext1-atl3.steamserver.net:27029) starting...
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext2-lax1.steamserver.net:27038) results: 65.5389ms + load 18.0000
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext1-lax1.steamserver.net:443) starting...
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext2-atl3.steamserver.net:27034) results: 73.1087ms + load 26.0000
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext2-ord1.steamserver.net:27038) starting...
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext2-lax1.steamserver.net:27036) results: 64.2838ms + load 19.0000
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() stopping after 400.00ms timeout and using good enough host (ext2-lax1.steamserver.net:27036)
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext1-atl3.steamserver.net:27029) results: 60.7371ms + load 26.0000
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext1-lax1.steamserver.net:443) results: 95.0499ms + load 25.0000
[2024-07-29 11:28:02] [Connecting, 4, 0] [U:1:913393195] PingWebSocketCM() (ext2-ord1.steamserver.net:27038) results: 95.4266ms + load 27.0000
[2024-07-29 11:28:02] [Connecting, 4, 7] [U:1:913393195] Connect() starting connection (eNetQOSLevelHigh, ext2-lax1.steamserver.net:27036, WebSocket)
[2024-07-29 11:28:02] [Connecting, 4, 7] [U:1:913393195] ConnectionCompleted() (162.254.195.71:27036, WebSocket) local address (192.168.0.61:41037)
[2024-07-29 11:28:02] [Connecting, 4, 7] [U:1:913393195] Client thinks it can connect via: UDP - yes, TCP - yes, WebSocket:443 - yes, WebSocket:Non443 - yes
[2024-07-29 11:28:02] [Connected, 4, 7] [U:1:913393195] Logging on [U:1:913393195]
[2024-07-29 11:28:02] [Logging On, 4, 7] [U:1:913393195] Using JWT
[2024-07-29 11:28:03] [Logging On, 4, 7] [U:1:913393195] RecvMsgClientLogOnResponse() : [U:1:913393195] 'OK'
[2024-07-29 11:28:03] CClientJobGetClientUpdateHosts: cached version not expired

(note: steam games and updates download SIGNIFICANTLY FASTER while using network manager.)

voidpointertonull commented 1 month ago

Steam takes often takes 20+ seconds to load for me, so figured I'd look into why it's so slow. Initially I suspected the IPv6 test timeouts to be the problem, but IPv6 not working on Windows isn't a problem, and looking around here apparently that doesn't make a difference on Linux either.

Just having NetworkManager is surely not a solution on its own as I have that to begin with while experiencing the at least 10 seconds "Waiting for network" delay described here.

Without any success, a non-exhaustive list of attempted changes:

Didn't have ideal conditions for a network capture due to other programs generating noise, but haven't seen anything horribly bad on the network. Could see the initial test connection, then a bit more than 10 seconds later the connection to the API server showed up, but couldn't really see anything interesting in-between as if Steam was just not doing anything on the network. There are no signs of network issues elsewhere either, other programs can make connections with satisfying performance.

bloodbornedisease commented 1 month ago

i completely agree with voidpointer. this still isn't fixed over a year later. please look into the issue.