Client on Ubuntu 22.04.2 "User requested disconnect" error

[ajm199]

Premium plan based from Canada, using account in US.  Problem exists in both Geo's.

Device

Spotify snap 1.2.9.743.g85d9593d

Operating System

5.15.0-71-generic #78-Ubuntu SMP x86_64 x86_64 x86_64 GNU/Linux (Jammy 22.04.2 LTS)

My Question or Issue

Always had a problem with Spotify .deb or snap, across OS installs, app runs properly, but will not play song.  I can see my playlists, all functionality otherwise works.  I re-installed and changed from .deb based install to latest snap.  All config and cache files were deleted.  First indication of error in logs (--show-console) is:

22:17:21.156 I [connect:389                     ] Updated connection type from -1 to 6
22:17:21.156 I [f:134                           ] Connection type changed from unknown to ethernet
22:17:21.156 I [f:96                            ] Enabling all persistent connections
22:17:21.156 I [f:164                           ] Will reconnect to AP (not forced)
22:17:21.157 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
22:17:21.157 I [f:200                           ] Login5OAuthClient::Impl::loadToken: Loading token from network.
22:17:21.157 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
22:17:21.157 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
22:17:21.171 I [f:228                           ] aq: on GoOffline[Offline] -> [Offline]
22:17:21.171 E [f:210                           ] aq: OnError reason: 3, error:User requested disconnect
22:17:21.171 I [f:228                           ] aq: Disconnected
22:17:21.171 I [f:228                           ] aq: on GoOnline[Offline] -> [Online]
WARNING: Kernel has no file descriptor comparison support: Operation not permitted
22:17:21.203 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
22:17:21.203 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
22:17:21.212 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
22:17:21.212 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
22:17:21.212 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
22:17:21.212 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
22:17:21.212 I [f:1989                          ] Update! reason: initial-update, flags: 0x0f
22:17:21.212 I [f:2967                          ] == Offline Report ==
22:17:21.212 I [f:2968                          ] change_reason: startup-report
22:17:21.212 I [f:2969                          ] num_downloaded_tracks: 0
22:17:21.212 I [f:2970                          ] total_num_tracks: 0
22:17:21.212 I [f:2971                          ] num_downloaded_episodes: 0
22:17:21.213 I [f:2972                          ] total_num_episodes: 0
22:17:21.213 I [f:2973                          ] track_limit: 0
22:17:21.213 I [f:2974                          ] track_expiry: 2592000
22:17:21.213 I [f:2975                          ] episode_limit: 0
22:17:21.213 I [f:2976                          ] episode_expiry: 2592000
22:17:21.213 I [f:2977                          ] offline_keys: 0
22:17:21.213 I [f:2978                          ] cached_keys: 0
22:17:21.213 I [f:2979                          ] total_num_links: 0
22:17:21.213 I [f:2986                          ] ====================

[Prague]

Barking good to see you ajm199!
It seems that you're encountering an issue with Spotify on your Ubuntu system, where the app runs properly but fails to play songs. The log you provided indicates a problem with loading the authentication token and a disconnection from the network. There is also a warning about the kernel lacking file descriptor comparison support.

Here are a few suggestions to help resolve the issue:

1. Reauthorize your account: Go to Spotify's website and log in to your account. From your account settings, revoke access for the Spotify app. Then, relaunch the app and log in again to reauthorize it. This process may help refresh the authentication token and resolve any issues related to it.

2. Check your internet connection: Ensure that you have a stable and working internet connection. You can try accessing other websites or services to verify if your internet connection is functioning correctly.

3. Disable any VPN or proxy: If you're using a VPN or proxy service, try disabling it temporarily. Sometimes, these services can interfere with Spotify's network connectivity.

4. Update your system and Spotify: Make sure your Ubuntu system is up to date with the latest software updates. Additionally, check for any available updates for the Spotify app, whether it's the Snap or .deb version. Updating both your system and the app can address compatibility issues and fix known bugs.

5. Check firewall and antivirus settings: Verify that your firewall or antivirus software is not blocking Spotify's network access. Temporarily disable them or create exceptions for Spotify to ensure unrestricted access.

6. Reinstall Spotify: If none of the above steps resolve the issue, consider completely uninstalling Spotify and reinstalling it. You can remove the Snap package or the .deb package depending on which version you're currently using.

   • For the Snap version: Open a terminal and run the following commands:

     arduino

     sudo snap remove spotify sudo snap install spotify

   • For the .deb version: Open a terminal and run the following commands:

     arduino

     sudo apt remove spotify sudo apt install spotify

   After reinstalling, log in to your account and check if the issue persists.

Keep the tail wagging with updates on your situation,

-Prague the Dog

[ajm199]

Interesting, I went to profile -> Manage apps and only see Alexa there, there is no authorization for the Spotify app.  How do I get the app authorized?  I did log out of the Linux app, and when attempting to log in I received the message "A firewall may be blocking Spotify.  Please update your firewall to allow Spotify.  Additionally you could try changing the currently used proxy settings."  I restarted the snap, and tried again a few time and finally was able to log-in again, songs were not greyed out, but received message about offline mode being enabled.  I did notice on the command line the following....

Gtk-Message: 23:10:26.204: Failed to load module "appmenu-gtk-module"
Gtk-Message: 23:10:26.204: Not loading module "atk-bridge": The functionality is provided by GTK natively. Please try to not load it.
WARNING: Kernel has no file descriptor comparison support: Operation not permitted

I deleted the caches, then logged out, restarted app.  Now the snap can see the playlists, but when I click into any of them, "Couldn't find that Playlist" is the error message.

I don't use a proxy, but I do use pi-hole, so I disabled that.  I also remove and reinstalled the snap.  The error signature has changed a little:

4:26:43.306 I [f:47                            ] Track offlining: 0 tracks offlined
04:26:43.306 I [connect:279                     ] ConnectManager::start
04:26:43.306 D [connect:291                     ] Started before online, not sending putstate
04:26:43.307 D [connect_network:182             ] DeviceCapabilityMap::fetchCapabilities Fetching from DeviceCapabilities
04:26:43.307 I [f:168                           ] Emitting signal.
04:26:43.308 D [connect_tier1:162               ] Tier1LocalVerificationListener::startAccept: error: Operation aborted.
04:26:43.308 D [connect_tier1:221               ] Tier1LocalVerificationListener::enableLocalSocket: listening on port 33243
04:26:43.310 D [connect_dial:1058               ] DialManager::scanDevices
04:26:43.314 I [connect:389                     ] Updated connection type from -1 to 6
04:26:43.314 I [f:134                           ] Connection type changed from unknown to ethernet
04:26:43.314 I [f:96                            ] Enabling all persistent connections
04:26:43.314 I [f:164                           ] Will reconnect to AP (not forced)
04:26:43.315 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.315 I [f:200                           ] Login5OAuthClient::Impl::loadToken: Loading token from network.
04:26:43.315 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.315 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.331 I [f:539                           ] collection ban: Start full sync
04:26:43.331 I [f:539                           ] collection artistban: Start full sync
04:26:43.332 I [f:539                           ] collection collection: Start full sync
04:26:43.332 I [f:539                           ] collection listenlater: Start full sync
04:26:43.332 I [f:539                           ] collection show: Start full sync
04:26:43.332 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.332 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.332 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.332 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.332 I [f:539                           ] collection artist: Start full sync
04:26:43.333 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.333 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.333 I [f:1989                          ] Update! reason: initial-update, flags: 0x0f
04:26:43.333 I [f:2967                          ] == Offline Report ==
04:26:43.333 I [f:2968                          ] change_reason: startup-report
04:26:43.333 I [f:2969                          ] num_downloaded_tracks: 0
04:26:43.333 I [f:2970                          ] total_num_tracks: 0
04:26:43.333 I [f:2971                          ] num_downloaded_episodes: 0
04:26:43.333 I [f:2972                          ] total_num_episodes: 0
04:26:43.333 I [f:2973                          ] track_limit: 0
04:26:43.333 I [f:2974                          ] track_expiry: 2592000
04:26:43.333 I [f:2975                          ] episode_limit: 0
04:26:43.333 I [f:2976                          ] episode_expiry: 2592000
04:26:43.333 I [f:2977                          ] offline_keys: 0
04:26:43.333 I [f:2978                          ] cached_keys: 0
04:26:43.333 I [f:2979                          ] total_num_links: 0
04:26:43.333 I [f:2986                          ] ====================
04:26:43.336 5 [f:534                           ] synchronizeRootlist 'spotify:user:ajm199:rootlist'
04:26:43.336 3 [f:448                           ] [spotify:user:ajm199:rootlist] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
04:26:43.336 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.336 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.337 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.337 E [connect:471                     ] ConnectConnectivityListener: received empty connection_id

04:26:43.337 D [connect:956                     ] Connection lost
04:26:43.337 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.337 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.337 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.337 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.337 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.337 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.337 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.337 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.337 I [f:191                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
04:26:43.337 D [connect_network:231             ] Searching for devices on the outbound network interface 10.0.0.6 (multicast IPv4 socket)
04:26:43.337 D [connect_network:231             ] Searching for devices on the outbound network interface 10.0.0.6 (unicast IPv4 socket)
04:26:43.337 D [connect_network:231             ] Searching for devices on the outbound network interface 172.17.0.1 (multicast IPv4 socket)
04:26:43.337 D [connect_network:231             ] Searching for devices on the outbound network interface 172.17.0.1 (unicast IPv4 socket)
04:26:43.338 I [f:228                           ] aq: on GoOffline[Offline] -> [Offline]
04:26:43.338 E [f:210                           ] aq: OnError reason: 3, error:User requested disconnect
04:26:43.338 I [f:228                           ] aq: Disconnected
04:26:43.338 I [f:228                           ] aq: on GoOnline[Offline] -> [Online]
04:26:43.364 E [connect:471                     ] ConnectConnectivityListener: received empty connection_id

04:26:43.364 D [connect:956                     ] Connection lost
04:26:43.389 I [f:228                           ] aq: on GoOnline[Idle] -> [Resolving]
WARNING: Kernel has no file descriptor comparison support: Operation not permitted
04:26:43.410 D [connect_network:231             ] Searching for devices on the outbound network interface 10.0.0.6 (multicast IPv4 socket)
04:26:43.410 D [connect_network:231             ] Searching for devices on the outbound network interface 172.17.0.1 (multicast IPv4 socket)
04:26:43.410 D [connect_network:231             ] Searching for devices on the outbound network interface fe80::2e1:8cff:fe5d:a8cc%wlp1s0 (multicast IPv6 socket)
04:26:43.439 I [f:228                           ] aq: on OnResolved[Resolving] -> [Connecting]
04:26:43.439 I [f:228                           ] aq: LWS got LWS_CALLBACK_OPENSSL_LOAD_EXTRA_CLIENT_VERIFY_CERTS
04:26:43.439 I [f:228                           ] aq: LWS got LWS_CALLBACK_PROTOCOL_INIT
04:26:43.439 I [f:228                           ] aq: LWS got LWS__unknown__
04:26:43.474 I [f:228                           ] aq: LWS got LWS_CALLBACK_WSI_CREATE
04:26:43.474 I [f:228                           ] aq: LWS got LWS_CALLBACK_GET_THREAD_ID
04:26:43.474 I [f:228                           ] aq: LWS got LWS_CALLBACK_EVENT_WAIT_CANCELLED
04:26:43.491 I [f:130                           ] D-Bus name 'org.freedesktop.NetworkManager' exists on the system bus
04:26:43.492 I [f:135                           ] Notify subscribers on NetworkManager detection (current state is 'NM_STATE_DISCONNECTED')
04:26:43.492 I [f:391                           ] Reachability changed from ethernet (IP [10.0.0.6, 172.17.0.1, fe80::2e1:8cff:fe5d:a8cc%wlp1s0], roaming false) to none (IP [10.0.0.6, 172.17.0.1, fe80::2e1:8cff:fe5d:a8cc%wlp1s0], roaming false)
04:26:43.492 I [f:161                           ] Access allowance changed from online: 1 stream: 1, sync: 1, persistent conn: 1 to online: 0 stream: 0, sync: 0, persistent conn: 0
04:26:43.492 I [f:192                           ] Scheduling emission of signal.
04:26:43.492 I [f:168                           ] Emitting signal.
04:26:43.492 I [f:128                           ] connectivityChanged(), is_logged_in:0
04:26:43.492 I [f:186                           ] Successfully connected to 'org.freedesktop.NetworkManager'
04:26:43.492 I [f:2345                          ] sync level updated: none -> none. (online: 1 connection_allows_offlining: 0 has_device_token: 0 allow_spotify_tracks: 1)
04:26:43.493 D [connect_tier1:162               ] Tier1LocalVerificationListener::startAccept: error: Operation aborted.
04:26:43.493 W [connect_network:18              ] Transport endpoint is not connected
04:26:43.493 W [connect_network:18              ] Transport endpoint is not connected
04:26:43.493 W [connect_network:18              ] Transport endpoint is not connected
04:26:43.493 W [connect_network:18              ] Transport endpoint is not connected
04:26:43.493 W [connect_network:18              ] Transport endpoint is not connected
04:26:43.493 W [connect_network:18              ] Transport endpoint is not connected
04:26:43.493 D [connect_dial:154                ] DIAL read unicast failure: ec = 125, msg = 'Operation aborted.', sender = 0.0.0.0, bytes transferred = 0
04:26:43.493 D [connect_dial:154                ] DIAL read multicast failure: ec = 125, msg = 'Operation aborted.', sender = 172.17.0.1, bytes transferred = 0
04:26:43.493 D [connect_dial:154                ] DIAL read unicast failure: ec = 125, msg = 'Operation aborted.', sender = 0.0.0.0, bytes transferred = 0
04:26:43.493 D [connect_dial:154                ] DIAL read multicast failure: ec = 125, msg = 'Operation aborted.', sender = 172.17.0.1, bytes transferred = 0
04:26:43.493 W [connect_network:412             ] Socket with outbound interface 172.17.0.1 not found and not deleted
04:26:43.493 W [connect_network:412             ] Socket with outbound interface 172.17.0.1 not found and not deleted
04:26:43.493 W [connect_network:412             ] Socket with outbound interface 10.0.0.6 not found and not deleted
04:26:43.493 W [connect_network:412             ] Socket with outbound interface 10.0.0.6 not found and not deleted
04:26:43.493 W [connect_network:18              ] Transport endpoint is not connected
04:26:43.493 E [connect_mdns:323                ] Error 'Operation aborted.' while receiving from 172.17.0.1
04:26:43.493 D [connect_mdns:153                ] mDNS read failure: ec = 125, msg = 'Operation aborted.', sender = 172.17.0.1, bytes transferred = 0
04:26:43.494 E [connect_mdns:323                ] Error 'Operation aborted.' while receiving from fe80::2e1:8cff:fe5d:a8cc%wlp1s0
04:26:43.494 D [connect_mdns:153                ] mDNS read failure: ec = 125, msg = 'Operation aborted.', sender = fe80::2e1:8cff:fe5d:a8cc%wlp1s0, bytes transferred = 0
04:26:43.494 E [connect_mdns:323                ] Error 'Operation aborted.' while receiving from 172.17.0.1
04:26:43.494 D [connect_mdns:153                ] mDNS read failure: ec = 125, msg = 'Operation aborted.', sender = 172.17.0.1, bytes transferred = 0
04:26:43.494 W [connect_network:412             ] Socket with outbound interface 10.0.0.6 not found and not deleted
04:26:43.494 W [connect_network:412             ] Socket with outbound interface fe80::2e1:8cff:fe5d:a8cc%wlp1s0 not found and not deleted
04:26:43.494 W [connect_network:412             ] Socket with outbound interface 172.17.0.1 not found and not deleted

Steps 2-6 have already been attempted.  Ideas?

[ajm199]

I should add that I try to go into off-line mode with shift-ctl-o and online with another shift-ctl-o with no avail - still get offline message.

Thank you for your pointers.

[Sebasty]

Hey there @ajm199 

the error messages you're getting definitely look funky. Looking around, that's generally coming from connection issues affecting communication between client and servers as well as issues with the client.

On a whim I'd suggest going to the Software Center / App Store, navigating to Spotify and checking for its permissions. Maybe something that's required for Spotify to work is disabled in there.

There's a whole bunch. The above results in perfectly functional app.

I'm also curious if you're able to listen to music on the web app.

Let me know how it goes : )

[ajm199]

Hello,

Which software center are you using?  Ubuntu has software boutique and installed the .deb version that has the same issues.  Could not find the permissions area you show. 

Web app works without issue.

[Sebasty]

@ajm199 

Maybe that's just the snap thing, this spotify app I installed via snap. None of the deb installations have permissions tab, it seems. Software center is one that came with ubuntu studio. Edit - re-read and learned you are using the snap spotify right now.

You can run snap interfaces spotify in terminal to see what the current permissions are. Mine look like that:

See this article on how to edit the permissions.

Looking at the log, it definitely seems there's issues with connection, rather strange it doesn't affect the web player (and good too, at least you have that one going).
Run nano /etc/hosts to see if there's anything in your hosts file outside of introduction and general stuff.

It would be a good idea to restart your network adapter and flush the dns while you're at it.