NixOS / nixpkgs

Nix Packages collection & NixOS
MIT License
17.6k stars 13.76k forks source link

Spotify failed to start #316216

Open A1ca7raz opened 4 months ago

A1ca7raz commented 4 months ago

Describe the bug

A clear and concise description of what the bug is. Spotify failed to start.

Steps To Reproduce

Steps to reproduce the behavior:

  1. Run spotify in the terminal

Expected behavior

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

Screenshots

If applicable, add screenshots to help explain your problem.

Additional context

Add any other context about the problem here.

[nix-shell:~]$ spotify
/nix/store/b56s7fa6wjjh2wm0qrn7rjivxi6517yr-spotify-1.2.31.1205.g4d59ad7c/bin/spotify: /nix/store/75pcj32diphaf65qfh4ajy8yl4chzyb9-curl-8.7.1/lib/libcurl-gnutls.so.4: no version information available (required by /nix/store/b56s7fa6wjjh2wm0qrn7rjivxi6517yr-spotify-1.2.31.1205.g4d59ad7c/bin/spotify)
/nix/store/b56s7fa6wjjh2wm0qrn7rjivxi6517yr-spotify-1.2.31.1205.g4d59ad7c/share/spotify/.spotify-wrapped: /nix/store/75pcj32diphaf65qfh4ajy8yl4chzyb9-curl-8.7.1/lib/libcurl-gnutls.so.4: no version information available (required by /nix/store/b56s7fa6wjjh2wm0qrn7rjivxi6517yr-spotify-1.2.31.1205.g4d59ad7c/share/spotify/.spotify-wrapped)
/nix/store/b56s7fa6wjjh2wm0qrn7rjivxi6517yr-spotify-1.2.31.1205.g4d59ad7c/share/spotify/.spotify-wrapped: /nix/store/75pcj32diphaf65qfh4ajy8yl4chzyb9-curl-8.7.1/lib/libcurl-gnutls.so.4: no version information available (required by /nix/store/b56s7fa6wjjh2wm0qrn7rjivxi6517yr-spotify-1.2.31.1205.g4d59ad7c/share/spotify/.spotify-wrapped)

Notify maintainers

@jtojnar

Metadata

Please run nix-shell -p nix-info --run "nix-info -m" and paste the result.

[user@system:~]$ nix-shell -p nix-info --run "nix-info -m"
 - system: `"x86_64-linux"`
 - host os: `Linux 6.8.10-xanmod1, NixOS, 24.11 (Vicuña), 24.11.20240529.ad57eef`
 - multi-user?: `yes`
 - sandbox: `yes`
 - version: `nix-env (Nix) 2.18.2`
 - nixpkgs: `/nix/store/vydlb8hnc7rrihkh3lys2a5y3wcj98kb-qgbn0imyridkb9527v6gnv6z3jzzprb9-source`

Add a :+1: reaction to issues you find important.

ArikRahman commented 4 months ago

Crashes system after starting from terminal, also, spicetify-cli does not work with spotify correctly.

jtojnar commented 4 months ago

I do not see anything indicating a crash. Do you see anything in the journal? Could you get a stack trace? What nix-shell are you running it in? What desktop environment do you use?

On Fri, 31 May 2024, 19:53 Arik Rahman, @.***> wrote:

Crashes system after starting from terminal, also, spicetify-cli does not work with spotify correctly.

— Reply to this email directly, view it on GitHub https://github.com/NixOS/nixpkgs/issues/316216#issuecomment-2142727442, or unsubscribe https://github.com/notifications/unsubscribe-auth/AAFMEY5YEXBL624ABWTEQG3ZFC2LBAVCNFSM6AAAAABITFQZMWVHI2DSMVQWIX3LMV43OSLTON2WKQ3PNVWWK3TUHMZDCNBSG4ZDONBUGI . You are receiving this because you were mentioned.Message ID: @.***>

A1ca7raz commented 4 months ago

Weird. It works now. But if I run spotify in terminal, these messages still exist.

/etc/profiles/per-user/nomad/bin/spotify: /nix/store/75pcj32diphaf65qfh4ajy8yl4chzyb9-curl-8.7.1/lib/libcurl-gnutls.so.4: no version information available (required by /etc/profiles/per-user/nomad/bin/spotify)
/nix/store/l72qxf07psgfwdj7f9pjdvmdn6drbpad-spicetify-Dribbblish/share/spotify/.spotify-wrapped: /nix/store/75pcj32diphaf65qfh4ajy8yl4chzyb9-curl-8.7.1/lib/libcurl-gnutls.so.4: no version information available (required by /nix/store/l72qxf07psgfwdj7f9pjdvmdn6drbpad-spicetify-Dribbblish/share/spotify/.spotify-wrapped)
/nix/store/l72qxf07psgfwdj7f9pjdvmdn6drbpad-spicetify-Dribbblish/share/spotify/.spotify-wrapped: /nix/store/75pcj32diphaf65qfh4ajy8yl4chzyb9-curl-8.7.1/lib/libcurl-gnutls.so.4: no version information available (required by /nix/store/l72qxf07psgfwdj7f9pjdvmdn6drbpad-spicetify-Dribbblish/share/spotify/.spotify-wrapped)
Opening in existing browser session.
jtojnar commented 4 months ago

I see those libcurl messages too but Spotify works just fine for me.

On Fri, May 31 2024 at 11:13:54 -07:00:00, A1ca7raz @.***> wrote:

Weird. It works now. But if I run spotify in terminal, these messages still exist.

/etc/profiles/per-user/nomad/bin/spotify: /nix/store/75pcj32diphaf65qfh4ajy8yl4chzyb9-curl-8.7.1/lib/libcurl-gnutls.so.4: no version information available (required by /etc/profiles/per-user/nomad/bin/spotify) /nix/store/l72qxf07psgfwdj7f9pjdvmdn6drbpad-spicetify-Dribbblish/share/spotify/.spotify-wrapped: /nix/store/75pcj32diphaf65qfh4ajy8yl4chzyb9-curl-8.7.1/lib/libcurl-gnutls.so.4: no version information available (required by /nix/store/l72qxf07psgfwdj7f9pjdvmdn6drbpad-spicetify-Dribbblish/share/spotify/.spotify-wrapped) /nix/store/l72qxf07psgfwdj7f9pjdvmdn6drbpad-spicetify-Dribbblish/share/spotify/.spotify-wrapped: /nix/store/75pcj32diphaf65qfh4ajy8yl4chzyb9-curl-8.7.1/lib/libcurl-gnutls.so.4: no version information available (required by /nix/store/l72qxf07psgfwdj7f9pjdvmdn6drbpad-spicetify-Dribbblish/share/spotify/.spotify-wrapped) Opening in existing browser session. — Reply to this email directly, view it on GitHub, or unsubscribe. You are receiving this because you were mentioned.Message ID: @.***>

Ciflire commented 4 months ago

Spotify does not work for me

    nix-shell -p nix-info --run "nix-info -m"
 - system: `"x86_64-linux"`
 - host os: `Linux 6.9.2, NixOS, 24.11 (Vicuña), 24.11.20240529.ad57eef`
 - multi-user?: `yes`
 - sandbox: `yes`
 - version: `nix-env (Nix) 2.18.2`
 - channels(root): `"nixos, nixpkgs"`
 - channels(ciflire): `""`
 - nixpkgs: `/nix/store/qgbn0imyridkb9527v6gnv6z3jzzprb9-source`
AlexLJordan commented 3 months ago

Spotify won't display anything for me. Spotify_2024-06-07--001

if started from terminal, I see this:

/home/USER/.nix-profile/bin/spotify: /nix/store/75pcj32diphaf65qfh4ajy8yl4chzyb9-curl-8.7.1/lib/libcurl-gnutls.so.4: no version information available (required by /home/USER/.nix-profile/bin/spotify)
/nix/store/b56s7fa6wjjh2wm0qrn7rjivxi6517yr-spotify-1.2.31.1205.g4d59ad7c/share/spotify/.spotify-wrapped: /nix/store/75pcj32diphaf65qfh4ajy8yl4chzyb9-curl-8.7.1/lib/libcurl-gnutls.so.4: no version information available (required by /nix/store/b56s7fa6wjjh2wm0qrn7rjivxi6517yr-spotify-1.2.31.1205.g4d59ad7c/share/spotify/.spotify-wrapped)
/nix/store/b56s7fa6wjjh2wm0qrn7rjivxi6517yr-spotify-1.2.31.1205.g4d59ad7c/share/spotify/.spotify-wrapped: /nix/store/75pcj32diphaf65qfh4ajy8yl4chzyb9-curl-8.7.1/lib/libcurl-gnutls.so.4: no version information available (required by /nix/store/b56s7fa6wjjh2wm0qrn7rjivxi6517yr-spotify-1.2.31.1205.g4d59ad7c/share/spotify/.spotify-wrapped)
[libprotobuf ERROR wire_format_lite.cc:618] String field 'spotify.event_sender.proto.DroppedEvents.DroppedEventsBySequenceIdEntry.key' contains invalid UTF-8 data when parsing a protocol buffer. Use the 'bytes' type if you intend to send raw bytes.
/proc/self/exe: /nix/store/75pcj32diphaf65qfh4ajy8yl4chzyb9-curl-8.7.1/lib/libcurl-gnutls.so.4: no version information available (required by /proc/self/exe)

(spotify:100314): GLib-GIO-CRITICAL **: 14:02:53.495: g_settings_schema_source_lookup: assertion 'source != NULL' failed
/proc/self/exe: /nix/store/75pcj32diphaf65qfh4ajy8yl4chzyb9-curl-8.7.1/lib/libcurl-gnutls.so.4: no version information available (required by /proc/self/exe)

Nothing happens in the window, i could leave it open forever nothing would change.


I also attempted to open a spotify-url with the program. But it doesn't react. EDIT: I attempted to verify not just being in some kind of limbo-state of the GUI, while everything else still worked. Opening a link in spotify might've gotten me back to a known state of the app in that case.

Ciflire commented 3 months ago

It appears that clearing the cache fixes the issue

AlexLJordan commented 3 months ago

No change after rm -rf ~/.cache/spotify for me

EDIT: rm -rf ~/.config/spotify as well Same logs on the terminal too.

eclairevoyant commented 3 months ago

After deleting those folders again, please test #321902 and see if that fixes it @AlexLJordan

AlexLJordan commented 3 months ago

I updated my flake inputs for home manager with nix flake update and then ran home-manager switch. I'm pulling Spotify from the nixpkgs master branch. I checked, that it did in fact update.

command: $ spotify

attempt 1: without deleting any files

/home/$USER/.nix-profile/bin/spotify: /nix/store/pqlfvq6nm4vq3cpd0f3wdl43wdvj958f-curl-8.8.0/lib/libcurl-gnutls.so.4: no version information available (required by /home/$USER/.nix-profile/bin/spotify)
/nix/store/zix7g4ixlkh28j3270rsy0q5p0qdfp3m-spotify-1.2.31.1205.g4d59ad7c/share/spotify/.spotify-wrapped: /nix/store/pqlfvq6nm4vq3cpd0f3wdl43wdvj958f-curl-8.8.0/lib/libcurl-gnutls.so.4: no version information available (required by /nix/store/zix7g4ixlkh28j3270rsy0q5p0qdfp3m-spotify-1.2.31.1205.g4d59ad7c/share/spotify/.spotify-wrapped)
/nix/store/zix7g4ixlkh28j3270rsy0q5p0qdfp3m-spotify-1.2.31.1205.g4d59ad7c/share/spotify/.spotify-wrapped: /nix/store/pqlfvq6nm4vq3cpd0f3wdl43wdvj958f-curl-8.8.0/lib/libcurl-gnutls.so.4: no version information available (required by /nix/store/zix7g4ixlkh28j3270rsy0q5p0qdfp3m-spotify-1.2.31.1205.g4d59ad7c/share/spotify/.spotify-wrapped)
/proc/self/exe: /nix/store/pqlfvq6nm4vq3cpd0f3wdl43wdvj958f-curl-8.8.0/lib/libcurl-gnutls.so.4: no version information available (required by /proc/self/exe)
did not find extension DRI_Mesa version 2
did not find extension DRI_IMAGE_DRIVER version 2
failed to bind extensions
did not find extension DRI_Mesa version 2
did not find extension DRI_IMAGE_DRIVER version 2
failed to bind extensions
did not find extension DRI_Mesa version 2
did not find extension DRI_SWRast version 5
failed to bind extensions

/proc/self/exe: /nix/store/pqlfvq6nm4vq3cpd0f3wdl43wdvj958f-curl-8.8.0/lib/libcurl-gnutls.so.4: no version information available (required by /proc/self/exe)

Window shows no content, as per my previous comment.

Ctrl-C causes segfault

attempt 2: deleting cache and config

ran rm -rf ~/.{cache,config}/spotify first.

/home/alj/.nix-profile/bin/spotify: /nix/store/pqlfvq6nm4vq3cpd0f3wdl43wdvj958f-curl-8.8.0/lib/libcurl-gnutls.so.4: no version information available (required by /home/alj/.nix-profile/bin/spotify)
/nix/store/zix7g4ixlkh28j3270rsy0q5p0qdfp3m-spotify-1.2.31.1205.g4d59ad7c/share/spotify/.spotify-wrapped: /nix/store/pqlfvq6nm4vq3cpd0f3wdl43wdvj958f-curl-8.8.0/lib/libcurl-gnutls.so.4: no version information available (required by /nix/store/zix7g4ixlkh28j3270rsy0q5p0qdfp3m-spotify-1.2.31.1205.g4d59ad7c/share/spotify/.spotify-wrapped)
/nix/store/zix7g4ixlkh28j3270rsy0q5p0qdfp3m-spotify-1.2.31.1205.g4d59ad7c/share/spotify/.spotify-wrapped: /nix/store/pqlfvq6nm4vq3cpd0f3wdl43wdvj958f-curl-8.8.0/lib/libcurl-gnutls.so.4: no version information available (required by /nix/store/zix7g4ixlkh28j3270rsy0q5p0qdfp3m-spotify-1.2.31.1205.g4d59ad7c/share/spotify/.spotify-wrapped)
/proc/self/exe: /nix/store/pqlfvq6nm4vq3cpd0f3wdl43wdvj958f-curl-8.8.0/lib/libcurl-gnutls.so.4: no version information available (required by /proc/self/exe)
did not find extension DRI_Mesa version 2
did not find extension DRI_IMAGE_DRIVER version 2
failed to bind extensions
did not find extension DRI_Mesa version 2
did not find extension DRI_IMAGE_DRIVER version 2
failed to bind extensions
did not find extension DRI_Mesa version 2
did not find extension DRI_SWRast version 5
failed to bind extensions
/proc/self/exe: /nix/store/pqlfvq6nm4vq3cpd0f3wdl43wdvj958f-curl-8.8.0/lib/libcurl-gnutls.so.4: no version information available (required by /proc/self/exe)
^Czsh: segmentation fault (core dumped)  spotify

Unfortunately the same story. Window shows no content still.

my system info

maybe this helps

╰─ nix-shell -p nix-info --run "nix-info -m"                                                            ─╯
 - system: `"x86_64-linux"`
 - host os: `Linux 6.6.32, NixOS, 24.05 (Uakari), 24.05.20240531.805a384`
 - multi-user?: `yes`
 - sandbox: `yes`
 - version: `nix-env (Nix) 2.18.2`
 - channels(root): `""`
 - channels(alj): `"home-manager-23.05.tar.gz, nixpkgs-master, nixpkgs-unstable"`
 - nixpkgs: `/nix/store/i1aw9jjgxcvyd642s12kw3iasmarwd42-source`
╰─ wwhich spotify                                                                                       ─╯
/nix/store/zix7g4ixlkh28j3270rsy0q5p0qdfp3m-spotify-1.2.31.1205.g4d59ad7c/share/spotify/spotify

If there's any other information i can provide, please let me know :)

AlexLJordan commented 2 months ago

Hello, I just tried the absolute newest master commit and nothing changed from my description above.

My window takes ages to appear, and then it's just empty.

$ NIXPKGS_ALLOW_UNFREE=1 nix shell --impure github:NixOS/nixpkgs#spotify
$ spotify
did not find extension DRI_Mesa version 2
did not find extension DRI_IMAGE_DRIVER version 2
failed to bind extensions
did not find extension DRI_Mesa version 2
did not find extension DRI_IMAGE_DRIVER version 2
failed to bind extensions
did not find extension DRI_Mesa version 2
did not find extension DRI_SWRast version 5
failed to bind extensions
^C
^C
zsh: segmentation fault (core dumped)  spotify

I don't know what to do now. Is there any way to use GDB or something to get a better look at what's happening?

As far as I know this only started after I switched to 24.05 and with it to KDE Plasma 6.

Please, somebody help :(

AlexLJordan commented 2 months ago

I do not see anything indicating a crash. Do you see anything in the journal? Could you get a stack trace? What nix-shell are you running it in? What desktop environment do you use? On Fri, 31 May 2024, 19:53 Arik Rahman, @.> wrote: Crashes system after starting from terminal, also, spicetify-cli does not work with spotify correctly. — Reply to this email directly, view it on GitHub <#316216 (comment)>, or unsubscribe https://github.com/notifications/unsubscribe-auth/AAFMEY5YEXBL624ABWTEQG3ZFC2LBAVCNFSM6AAAAABITFQZMWVHI2DSMVQWIX3LMV43OSLTON2WKQ3PNVWWK3TUHMZDCNBSG4ZDONBUGI . You are receiving this because you were mentioned.Message ID: @.>

I ran strace spotify here: spotify-strace.log

I ran the command, waited for a window to appear and waited a little; there've been a lot of these lines:

poll([{fd=20, events=POLLIN}, {fd=35, events=POLLIN}, {fd=36, events=POLLIN}], 3, 0) = 0 (Timeout)
recvmsg(36, {msg_namelen=0}, 0)         = -1 EAGAIN (Resource temporarily unavailable)

Then I moved my mouse over the window a couple times, which made it print a lot more of those. At the end I used the close button of the spotify window.

Does anyone know what this could mean?

AlexLJordan commented 2 months ago

SOLUTION (for my case):

Since I had really dumb issues with discord waiting for kwallet (even tho i'm using KeepassXC as my Secret Service) and kwallet is disabled, i tried the following invocation (which fixed discord):

spotify --password-store=gnome-libsecret

both of them are electron apps, which apparently both sh*t the bed when KDE doesn't have kwallet running. Discord was recoverable, just took a million years to start up. Spotify apparently just waits forever, unable to load its GUI.

Hope this helps anyone in the future. I'm now gonna add that parameter in my spotify.desktop file and scream into the void.


i had it set to basic before which stores passwords in plaintext. I just now found these docs: https://chromium.googlesource.com/chromium/src/+/HEAD/docs/linux/password_storage.md

And then looked up what "gnome-libsecret" actually does, and tada, it talks to keepassxc's implementation of Secret Service. And Chrome/Electron is apparently unable to figure this out on its on. Welcome to 2024.


Just found out, that after logging in again, it doesn't even use the keyring. But it refuses to start without one.

ardijanr commented 4 weeks ago

I am having similar issues, tried the following:

  1. removal of both .cache/spotify and .config/spotify
  2. update from 24.05 to unstable

Also tried the suggestion from @AlexLJordan.

Starting it from terminal:

spotify --password-store=gnome-libsecret
[0903/185547.695069:WARNING:main_runner.cc(261)] Alloy bootstrap is deprecated and will be removed in ~M127. See https://github.com/chromiumembedded/cef/issues/3685
Fontconfig error: "/etc/fonts/fonts.conf", line 5: Specification mandates value for attribute yes

Fontconfig error: Cannot load config file from /etc/fonts/fonts.conf
Fontconfig error: "/etc/fonts/fonts.conf", line 5: not well-formed (invalid token)
Fontconfig error: Cannot load config file from /etc/fonts/fonts.conf

^C <--- closing
Segmentation fault (core dumped) 

Unsure as to what its complaining about in terms of fonts.

Running spotify using:

spotify --log-file=spotify.log --trace-file=spotify.trace

I got this log: (removed/renamed some user specific stuff)

17:03:40.995 E [f:22                            ] TCPServer: Failed to create port checker
17:03:40.999 I [f:229                           ] Starting step: core_init
17:03:40.999 I [f:159                           ] Access allowance changed from online: 0 stream: 0, sync: 0, persistent conn: 0 to online: 1 stream: 1, sync: 0, persistent conn: 1
17:03:40.999 I [f:77                            ] Connectivity policy is initially allow_all
17:03:40.999 I [f:79                            ] Connection type is initially unknown
17:03:40.999 I [f:96                            ] Enabling all persistent connections
17:03:40.999 I [f:139                           ] Open storage index header at /home/<unix-user>/.cache/spotify/Storage/index.dat
17:03:40.999 I [f:23                            ] Opened storage index at /home/<unix-user>/.cache/spotify/Storage OK
17:03:40.999 I [f:271                           ] NmNetworkNotifier is not aware of running NetworkManager (yet), assume 'ethernet'
17:03:41.000 I [f:248                           ] Ending step: core_init
17:03:41.000 E [f:1399                          ] Failed to set default autostart mode!
17:03:41.001 I [f:119                           ] Login5OAuthClient: Product state changed, force renew.
17:03:41.001 I [f:271                           ] NmNetworkNotifier is not aware of running NetworkManager (yet), assume 'ethernet'
17:03:41.001 I [f:385                           ] Reachability changed from unknown (IP [], roaming false) to ethernet (IP [10.10.10.20, 30.30.30.1, fe80::f00f:74ff:fe18:4062%enp10s0], roaming false)
17:03:41.001 I [f:159                           ] Access allowance changed from online: 1 stream: 1, sync: 0, persistent conn: 1 to online: 1 stream: 1, sync: 1, persistent conn: 1
17:03:41.001 I [f:190                           ] Scheduling emission of signal.
17:03:41.001 I [f:123                           ] connectivityChanged(), is_logged_in:1
17:03:41.001 I [f:243                           ] aq: Updating dealer URL from 'wss://gew4-dealer.spotify.com:443'
17:03:41.002 I [f:90                            ] Successfully loaded 3 log messages from '/home/<unix-user>/.config/spotify/Users/<username>/log'
17:03:41.002 I [f:33                            ] Using Hermes log transport
17:03:41.002 I [f:113                           ] Logger is now using a valid transport!
17:03:41.002 I [f:113                           ] Logger is now using a valid transport!
17:03:41.005 I [f:482                           ] Local device coordinator enabled write resources & delta updates
17:03:41.005 I [f:2608                          ] sync level updated: none -> none. (online: 0 connection_allows_offlining: 1 has_device_token: 0 allow_spotify_tracks: 1)
17:03:41.005 I [f:2608                          ] sync level updated: none -> none. (online: 1 connection_allows_offlining: 1 has_device_token: 0 allow_spotify_tracks: 1)
17:03:41.005 I [f:152                           ] Device coordinator was set online
17:03:41.005 5 [f:160                           ] [spotify:user:<spotify-user>:rootlist] Creating context
17:03:41.007 D [f:29                            ] Logging level was changed from 4 to 6
17:03:41.007 D [f:122                           ] allow_mft_to_mft_transfers = false
17:03:41.007 D [f:125                           ] use_injected_http_dial = false
17:03:41.007 D [f:128                           ] use_injected_http_mdns= false
17:03:41.007 D [f:131                           ] use_ipv6 = false
17:03:41.007 D [f:134                           ] pick_and_shuffle_restricted = false
17:03:41.007 D [f:138                           ] mdns_discovery_metrics_enabled = false
17:03:41.007 D [f:142                           ] getinfo_discovery_metrics_enabled = false
17:03:41.007 D [f:146                           ] show_offline_devices_in_core = true
17:03:41.007 D [f:151                           ] show_offline_amazon_devices_as_online = false
17:03:41.007 D [f:155                           ] cast_transfer_timeout = 30s
17:03:41.007 D [f:158                           ] complete_transfer_on_ack_id = 1
17:03:41.007 D [f:163                           ] cast_minimum_ms_to_abort_by_transfer_to_same_device = 30s
17:03:41.007 D [f:169                           ] playstation_minimum_ms_to_abort_by_transfer_to_same_device = 15000s
17:03:41.007 D [f:172                           ] report_active_device_race = 1
17:03:41.007 D [f:175                           ] enable_wol_mdns = 0
17:03:41.007 D [f:179                           ] send_successful_transfer_debug_log = 0
17:03:41.007 D [f:183                           ] should_retry_cast_on_casting_stopped = 0
17:03:41.007 D [f:189                           ] sonos_minimum_ms_to_abort_by_transfer_to_same_device = 15000ms
17:03:41.007 D [f:194                           ] sonos_zeroconf_add_user_timeout = 5000ms
17:03:41.007 D [f:198                           ] max_allowed_commands_per_window = 60
17:03:41.007 D [f:203                           ] command_rate_limiter_window = 60000ms
17:03:41.007 D [f:208                           ] confirm_local_player_for_connect_volume = 0
17:03:41.007 D [f:211                           ] enable_remote_install = 0
17:03:41.007 D [f:216                           ] install_over_connect_timeout = 600s
17:03:41.007 D [f:221                           ] dial_local_http_timeout = 20s
17:03:41.007 D [f:225                           ] dial_get_device_description_on_login = 0
17:03:41.007 D [f:230                           ] transfer_after_install_max_time = 120s
17:03:41.007 D [f:228                           ] Tier1LocalVerificationListener::enableLocalSocket: listening on port 53001
17:03:41.007 I [f:186                           ] ContextPlayer RoutingTrackPlayer: PauseOrAwaitPause not active)
17:03:41.009 I [f:229                           ] Starting step: cef_context_init
17:03:41.012 I [f:47                            ] Track offlining: 0 tracks offlined
17:03:41.012 I [f:290                           ] ConnectManager::start
17:03:41.012 D [f:302                           ] Started before online, not sending putstate
17:03:41.012 I [f:134                           ] D-Bus name 'org.freedesktop.NetworkManager' exists on the system bus
17:03:41.013 I [f:139                           ] Notify subscribers on NetworkManager detection (current state is 'NM_STATE_CONNECTED_LOCAL')
17:03:41.013 D [f:156                           ] DeviceCapabilityMap::fetchCapabilities Fetching from DeviceCapabilities
17:03:41.013 I [f:190                           ] Successfully connected to 'org.freedesktop.NetworkManager'
17:03:41.014 I [f:166                           ] Emitting signal.
17:03:41.014 D [f:228                           ] Tier1LocalVerificationListener::enableLocalSocket: listening on port 38673
17:03:41.016 I [f:248                           ] Ending step: cef_context_init
17:03:41.017 I [f:479                           ] Updated connection type from -1 to 6
17:03:41.017 I [f:134                           ] Connection type changed from unknown to ethernet
17:03:41.017 I [f:96                            ] Enabling all persistent connections
17:03:41.017 I [f:164                           ] Will reconnect to AP (not forced)
17:03:41.017 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.017 I [f:188                           ] Login5OAuthClient::Impl::loadToken: Loading token from network.
17:03:41.017 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.017 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.022 I [f:566                           ] collection ban: Starting delta sync
17:03:41.022 I [f:566                           ] collection artistban: Starting delta sync
17:03:41.022 I [f:566                           ] collection collection: Starting delta sync
17:03:41.022 I [f:566                           ] collection artist: Starting delta sync
17:03:41.023 I [f:566                           ] collection listenlater: Starting delta sync
17:03:41.023 I [f:566                           ] collection show: Starting delta sync
17:03:41.023 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.023 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.049 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.049 I [f:566                           ] collection prerelease: Starting delta sync
17:03:41.049 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.049 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.049 I [f:2143                          ] Update! reason: initial-update, flags: 0x0f
17:03:41.049 I [f:3298                          ] == Offline Report ==
17:03:41.049 I [f:3299                          ] change_reason: startup-report
17:03:41.049 I [f:3300                          ] num_downloaded_tracks: 0
17:03:41.049 I [f:3301                          ] total_num_tracks: 0
17:03:41.049 I [f:3302                          ] num_downloaded_episodes: 0
17:03:41.049 I [f:3303                          ] total_num_episodes: 0
17:03:41.049 I [f:3304                          ] track_limit: 0
17:03:41.049 I [f:3305                          ] track_expiry: 2592000
17:03:41.049 I [f:3306                          ] episode_limit: 0
17:03:41.049 I [f:3307                          ] episode_expiry: 2592000
17:03:41.049 I [f:3308                          ] offline_keys: 0
17:03:41.049 I [f:3309                          ] cached_keys: 0
17:03:41.049 I [f:3310                          ] total_num_links: 0
17:03:41.049 I [f:3318                          ] schedule_stats_map: 'initial-update,15': 1
17:03:41.049 I [f:3318                          ] schedule_stats_map: 'allow-offline-spotify-tracks-changed,2': 1
17:03:41.049 I [f:3318                          ] schedule_stats_map: 'log-report,8': 1
17:03:41.049 I [f:3318                          ] schedule_stats_map: 'sync-level-updated,4': 2
17:03:41.049 I [f:3321                          ] update_stats_map: 'initial-update,15': 1
17:03:41.049 I [f:3323                          ] num_update_all_lists: 0
17:03:41.049 I [f:3324                          ] ====================
17:03:41.050 I [f:2369                          ] Token status changed! Want token: 0 (offlined lists: 0, OPC RC enabled: 0, OPC toggle: empty, Auto OPC: empty), return needed: 0, force: 1
17:03:41.050 I [f:206                           ] Queuing a license request: 0 0.
17:03:41.050 5 [f:706                           ] synchronizeRootlist 'spotify:user:<spotify-user>:rootlist'
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_A] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_R] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_Q] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_P] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_B] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_c] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_D] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_E] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_F] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_M] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_N] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_O] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_G] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_H] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_I] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_J] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_S] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_T] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_U] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_L] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_V] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_K] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_W] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_X] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_y] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_Z] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_AA] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_AB] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_AC] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_AD] Creating context
17:03:41.051 5 [f:160                           ] [spotify:playlist:playlist_AE] Creating context
17:03:41.051 I [f:243                           ] aq: on GoOffline[Offline] -> [Offline]
17:03:41.051 E [f:216                           ] aq: OnError reason: 3, error:User requested disconnect
17:03:41.051 I [f:243                           ] aq: Disconnected
17:03:41.051 I [f:243                           ] aq: on GoOnline[Offline] -> [Online]
17:03:41.052 I [f:385                           ] Reachability changed from ethernet (IP [10.10.10.20, 30.30.30.1, fe80::f00f:74ff:fe18:4062%enp10s0], roaming false) to none (IP [10.10.10.20, 30.30.30.1, fe80::f00f:74ff:fe18:4062%enp10s0], roaming false)
17:03:41.052 I [f:159                           ] Access allowance changed from online: 1 stream: 1, sync: 1, persistent conn: 1 to online: 0 stream: 0, sync: 0, persistent conn: 0
17:03:41.052 I [f:190                           ] Scheduling emission of signal.
17:03:41.052 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.052 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.052 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.052 E [f:479                           ] ConnectConnectivityListener: received empty connection_id

17:03:41.052 D [f:1033                          ] Connection lost
17:03:41.052 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.052 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.052 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.052 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.052 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.052 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.052 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.052 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.052 I [f:166                           ] Emitting signal.
17:03:41.052 I [f:123                           ] connectivityChanged(), is_logged_in:0
17:03:41.052 I [f:243                           ] aq: Updating dealer URL from 'wss://gew4-dealer.spotify.com:443'
17:03:41.052 I [f:2608                          ] sync level updated: none -> none. (online: 1 connection_allows_offlining: 0 has_device_token: 0 allow_spotify_tracks: 1)
17:03:41.052 D [f:166                           ] Tier1LocalVerificationListener::startAccept: error: Operation aborted.
17:03:41.052 D [f:271                           ] Searching for devices on the outbound network interface 10.10.10.20 (unicast IPv4 socket)
17:03:41.052 D [f:271                           ] Searching for devices on the outbound network interface 10.10.10.20 (multicast IPv4 socket)
17:03:41.052 E [f:956                           ] Error 'Operation aborted.' while receiving from 0.0.0.0
17:03:41.052 D [f:722                           ] DIAL read multicast failure: ec = 125, msg = 'Operation aborted.', sender = 0.0.0.0, bytes transferred = 0
17:03:41.052 E [f:956                           ] Error 'Operation aborted.' while receiving from 0.0.0.0
17:03:41.052 D [f:722                           ] DIAL read unicast failure: ec = 125, msg = 'Operation aborted.', sender = 0.0.0.0, bytes transferred = 0
17:03:41.054 I [f:479                           ] Updated connection type from 6 to 0
17:03:41.054 D [f:467                           ] ConnectConnectivityListener: connection lost because connectivity_manager->connectionType() == connectivity::kConnectionTypeNone
17:03:41.054 I [f:717                           ] collection: ban: Online state changed
17:03:41.054 I [f:717                           ] collection: artistban: Online state changed
17:03:41.054 I [f:717                           ] collection: collection: Online state changed
17:03:41.054 I [f:717                           ] collection: artist: Online state changed
17:03:41.054 I [f:717                           ] collection: listenlater: Online state changed
17:03:41.054 I [f:717                           ] collection: show: Online state changed
17:03:41.054 I [f:717                           ] collection: prerelease: Online state changed
17:03:41.054 I [f:112                           ] Connectivity policy changed from allow_all to disallow_all
17:03:41.054 I [f:94                            ] Disabling all persistent connections
17:03:41.054 I [f:152                           ] Device coordinator was set offline
17:03:41.054 I [f:134                           ] Connection type changed from ethernet to none
17:03:41.054 I [f:94                            ] Disabling all persistent connections
17:03:41.054 I [f:140                           ] Cowardly refusing to reconnect, connectivity is none
17:03:41.054 E [f:758                           ] Connection error: ap_network_disabled
17:03:41.054 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.054 I [f:2143                          ] Update! reason: sync-level-updated, flags: 0x04
17:03:41.054 I [f:179                           ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
17:03:41.061 I [f:229                           ] Starting step: main_view_init
17:03:41.084 E [f:479                           ] ConnectConnectivityListener: received empty connection_id

17:03:41.084 D [f:1033                          ] Connection lost
17:03:41.084 E [f:479                           ] ConnectConnectivityListener: received empty connection_id

17:03:41.084 D [f:1033                          ] Connection lost
17:03:41.101 I [f:243                           ] aq: on GoOnline[Idle] -> [Resolving]
17:03:41.102 I [f:243                           ] aq: on GoOffline[Online] -> [Offline]
17:03:41.102 E [f:216                           ] aq: OnError reason: 3, error:User requested disconnect
17:03:41.102 I [f:243                           ] aq: Disconnected
17:03:41.134 E [f:479                           ] ConnectConnectivityListener: received empty connection_id

17:03:41.134 D [f:1033                          ] Connection lost
17:03:41.285 I [f:420                           ] High request latency: https://apresolve.spotify.com/ took 233 ms
17:03:41.285 I [f:180                           ] Resolved rpc host: https://gew4-spclient.spotify.com:443
17:03:41.285 I [f:180                           ] Resolved streaming host: wss://gew4-dealer.spotify.com:443
17:03:41.287 I [f:420                           ] High request latency: https://apresolve.spotify.com/ took 263 ms
17:03:41.287 E [f:758                           ] Connection error: ap_network_disabled
17:03:41.317 I [f:420                           ] High request latency: https://login5.spotify.com/v3 took 263 ms
17:03:41.317 I [f:203                           ] Login5OAuthClient::Impl::loadTokenFromNetwork: Got Login5 reply.
17:03:41.317 I [f:254                           ] Login5OAuthClient: Successful authentication
17:03:41.318 I [f:168                           ] aq: AuthorizeWithToken
17:03:41.352 I [f:243                           ] aq: on GoOnline[Offline] -> [Online]
17:03:41.402 I [f:243                           ] aq: on GoOnline[Idle] -> [Resolving]
17:03:41.424 W [f:186                           ] Request to social-connect failed, client error=0, server status=404
17:03:41.425 I [f:146                           ] Disabled device
17:03:41.452 I [f:243                           ] aq: on OnResolved[Resolving] -> [Connecting]
17:03:41.456 I [f:243                           ] aq: LWS got LWS_CALLBACK_OPENSSL_LOAD_EXTRA_CLIENT_VERIFY_CERTS
17:03:41.456 I [f:243                           ] aq: LWS got LWS_CALLBACK_PROTOCOL_INIT
17:03:41.456 I [f:243                           ] aq: LWS got LWS__unknown__
17:03:41.456 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_AE)
17:03:41.456 I [f:25                            ] curation_state: Will sync a playlist, 17 left to sync.
17:03:41.456 3 [f:680                           ] [spotify:playlist:playlist_AE] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:41.456 5 [f:408                           ] [spotify:playlist:playlist_AE] 'GET' request complete
17:03:41.457 3 [f:954                           ] [spotify:playlist:playlist_AE]  synchronization error: Error category: http, code 11 (forced offline)
17:03:41.457 3 [f:979                           ] [spotify:playlist:playlist_AE]   - transient error, retrying.
17:03:41.458 I [f:243                           ] aq: LWS got LWS_CALLBACK_WSI_CREATE
17:03:41.458 I [f:243                           ] aq: LWS got LWS_CALLBACK_GET_THREAD_ID
17:03:41.458 I [f:243                           ] aq: LWS got LWS_CALLBACK_EVENT_WAIT_CANCELLED
17:03:41.492 I [f:243                           ] aq: LWS got LWS_CALLBACK_OPENSSL_PERFORM_SERVER_CERT_VERIFICATION
17:03:41.492 I [f:243                           ] aq: LWS got LWS_CALLBACK_OPENSSL_PERFORM_SERVER_CERT_VERIFICATION
17:03:41.492 I [f:243                           ] aq: LWS got LWS_CALLBACK_OPENSSL_PERFORM_SERVER_CERT_VERIFICATION
17:03:41.492 I [f:243                           ] aq: LWS got LWS_CALLBACK_CLIENT_APPEND_HANDSHAKE_HEADER
17:03:41.492 I [f:243                           ] aq: Aqueduct set User-Agent:Spotify/1.2.42.290.g242057a2 Linux/6.6.48 (Aqueduct/v1.2.143-5b2345ad)
17:03:41.532 I [f:243                           ] aq: LWS got LWS_CALLBACK_CLIENT_FILTER_PRE_ESTABLISH
17:03:41.532 I [f:243                           ] aq: LWS got LWS_CALLBACK_CLIENT_ESTABLISHED
17:03:41.538 I [f:243                           ] aq: LWS got LWS_CALLBACK_CLIENT_RECEIVE
17:03:41.538 I [f:243                           ] aq: on OnConnected[Connecting] -> [Connected]
17:03:41.538 I [f:187                           ] aq: OnNewConnectionId: <Some long id or key A>
17:03:41.538 I [f:243                           ] aq: LWS got LWS_CALLBACK_CLIENT_WRITEABLE
17:03:41.538 I [f:243                           ] aq: LWS got LWS_CALLBACK_CLIENT_WRITEABLE
17:03:41.576 I [f:243                           ] aq: LWS got LWS_CALLBACK_CLIENT_RECEIVE_PONG
17:03:41.583 I [f:196                           ] aq: OnNewConnectionId: <Some long id or key A>
17:03:41.583 I [f:68                            ] New pubsub connection id received
17:03:41.583 E [f:483                           ] ConnectConnectivityListener: received new_connection_id <Some long id or key A> - starting 

17:03:41.583 D [f:1017                          ] Connection established
17:03:41.583 D [f:1021                          ] connection lost was scheduled, time left 4551 ms
17:03:41.585 I [f:717                           ] collection: ban: Online state changed
17:03:41.585 I [f:717                           ] collection: artistban: Online state changed
17:03:41.585 I [f:717                           ] collection: collection: Online state changed
17:03:41.585 I [f:717                           ] collection: artist: Online state changed
17:03:41.585 I [f:717                           ] collection: listenlater: Online state changed
17:03:41.585 I [f:717                           ] collection: show: Online state changed
17:03:41.585 I [f:717                           ] collection: prerelease: Online state changed
17:03:41.585 D [f:600                           ] put state error: http client error 'http_error_forced_offline'
17:03:41.585 W [f:219                           ] Request to social-connect failed, client error=11
17:03:41.694 I [f:420                           ] High request latency: https://gew4-spclient.spotify.com:443/clientsettings took 375 ms
17:03:42.928 I [f:182                           ] mdata: d5d899a9: Task from `profile` finished. 1/1 entity URIs requested/needed (0.0% cached). 1/1 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 1 unfinished task(s).
17:03:43.087 I [f:248                           ] Ending step: main_view_init
17:03:43.176 I [f:182                           ] mdata: e845d8e8: Task from `your_library` finished. 1/1 entity URIs requested/needed (0.0% cached). 1/1 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 4 unfinished task(s).
17:03:43.176 I [f:182                           ] mdata: 605a8fda: Task from `your_library` finished. 1/1 entity URIs requested/needed (0.0% cached). 1/1 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 3 unfinished task(s).
17:03:43.176 I [f:182                           ] mdata: 5a60734c: Task from `your_library` finished. 1/1 entity URIs requested/needed (0.0% cached). 1/1 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 2 unfinished task(s).
17:03:43.176 I [f:182                           ] mdata: 2f97642f: Task from `your_library` finished. 1/1 entity URIs requested/needed (0.0% cached). 1/1 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 1 unfinished task(s).
17:03:43.236 I [f:182                           ] mdata: 122b84b0: Task from `your_library` finished. 7/7 entity URIs requested/needed (0.0% cached). 7/7 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 1 unfinished task(s).
17:03:43.287 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_A)
17:03:43.288 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_R)
17:03:43.288 3 [f:680                           ] [spotify:playlist:playlist_A] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.288 3 [f:680                           ] [spotify:playlist:playlist_R] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.288 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_Q)
17:03:43.288 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_P)
17:03:43.288 3 [f:680                           ] [spotify:playlist:playlist_Q] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.288 3 [f:680                           ] [spotify:playlist:playlist_P] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.288 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_B)
17:03:43.288 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_c)
17:03:43.288 5 [f:408                           ] [spotify:playlist:playlist_A] 'GET' request complete
17:03:43.288 3 [f:954                           ] [spotify:playlist:playlist_A]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.288 3 [f:979                           ] [spotify:playlist:playlist_A]   - transient error, retrying.
17:03:43.288 5 [f:408                           ] [spotify:playlist:playlist_R] 'GET' request complete
17:03:43.288 3 [f:954                           ] [spotify:playlist:playlist_R]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.288 3 [f:979                           ] [spotify:playlist:playlist_R]   - transient error, retrying.
17:03:43.288 3 [f:680                           ] [spotify:playlist:playlist_B] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.288 3 [f:680                           ] [spotify:playlist:playlist_c] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.289 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_D)
17:03:43.289 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_E)
17:03:43.289 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_F)
17:03:43.289 5 [f:408                           ] [spotify:playlist:playlist_Q] 'GET' request complete
17:03:43.289 3 [f:954                           ] [spotify:playlist:playlist_Q]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.289 3 [f:979                           ] [spotify:playlist:playlist_Q]   - transient error, retrying.
17:03:43.289 5 [f:408                           ] [spotify:playlist:playlist_P] 'GET' request complete
17:03:43.289 3 [f:954                           ] [spotify:playlist:playlist_P]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.289 3 [f:979                           ] [spotify:playlist:playlist_P]   - transient error, retrying.
17:03:43.289 3 [f:680                           ] [spotify:playlist:playlist_E] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.289 3 [f:680                           ] [spotify:playlist:playlist_F] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.289 3 [f:680                           ] [spotify:playlist:playlist_D] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.289 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_M)
17:03:43.289 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_N)
17:03:43.289 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_O)
17:03:43.289 5 [f:408                           ] [spotify:playlist:playlist_B] 'GET' request complete
17:03:43.289 3 [f:954                           ] [spotify:playlist:playlist_B]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.289 3 [f:979                           ] [spotify:playlist:playlist_B]   - transient error, retrying.
17:03:43.289 5 [f:408                           ] [spotify:playlist:playlist_c] 'GET' request complete
17:03:43.289 3 [f:954                           ] [spotify:playlist:playlist_c]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.289 3 [f:979                           ] [spotify:playlist:playlist_c]   - transient error, retrying.
17:03:43.289 3 [f:680                           ] [spotify:playlist:playlist_O] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.289 3 [f:680                           ] [spotify:playlist:playlist_N] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.289 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_G)
17:03:43.289 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_H)
17:03:43.289 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_I)
17:03:43.289 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_J)
17:03:43.289 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_K)
17:03:43.290 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_L)
17:03:43.290 5 [f:408                           ] [spotify:playlist:playlist_E] 'GET' request complete
17:03:43.290 3 [f:954                           ] [spotify:playlist:playlist_E]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.290 3 [f:979                           ] [spotify:playlist:playlist_E]   - transient error, retrying.
17:03:43.290 5 [f:408                           ] [spotify:playlist:playlist_F] 'GET' request complete
17:03:43.290 3 [f:954                           ] [spotify:playlist:playlist_F]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.290 3 [f:979                           ] [spotify:playlist:playlist_F]   - transient error, retrying.
17:03:43.290 5 [f:408                           ] [spotify:playlist:playlist_D] 'GET' request complete
17:03:43.290 3 [f:954                           ] [spotify:playlist:playlist_D]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.290 3 [f:979                           ] [spotify:playlist:playlist_D]   - transient error, retrying.
17:03:43.290 3 [f:680                           ] [spotify:playlist:playlist_G] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.290 3 [f:680                           ] [spotify:playlist:playlist_H] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.290 3 [f:680                           ] [spotify:playlist:playlist_I] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.290 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_T)
17:03:43.290 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_U)
17:03:43.290 5 [f:408                           ] [spotify:playlist:playlist_O] 'GET' request complete
17:03:43.290 3 [f:954                           ] [spotify:playlist:playlist_O]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.290 3 [f:979                           ] [spotify:playlist:playlist_O]   - transient error, retrying.
17:03:43.290 5 [f:408                           ] [spotify:playlist:playlist_N] 'GET' request complete
17:03:43.290 3 [f:954                           ] [spotify:playlist:playlist_N]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.290 3 [f:979                           ] [spotify:playlist:playlist_N]   - transient error, retrying.
17:03:43.290 3 [f:680                           ] [spotify:playlist:playlist_T] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.290 3 [f:680                           ] [spotify:playlist:playlist_U] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.291 5 [f:408                           ] [spotify:playlist:playlist_G] 'GET' request complete
17:03:43.291 3 [f:954                           ] [spotify:playlist:playlist_G]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.291 3 [f:979                           ] [spotify:playlist:playlist_G]   - transient error, retrying.
17:03:43.291 5 [f:408                           ] [spotify:playlist:playlist_H] 'GET' request complete
17:03:43.291 3 [f:954                           ] [spotify:playlist:playlist_H]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.291 3 [f:979                           ] [spotify:playlist:playlist_H]   - transient error, retrying.
17:03:43.291 5 [f:408                           ] [spotify:playlist:playlist_I] 'GET' request complete
17:03:43.291 3 [f:954                           ] [spotify:playlist:playlist_I]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.291 3 [f:979                           ] [spotify:playlist:playlist_I]   - transient error, retrying.
17:03:43.291 5 [f:408                           ] [spotify:playlist:playlist_T] 'GET' request complete
17:03:43.291 3 [f:954                           ] [spotify:playlist:playlist_T]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.291 3 [f:979                           ] [spotify:playlist:playlist_T]   - transient error, retrying.
17:03:43.291 5 [f:408                           ] [spotify:playlist:playlist_U] 'GET' request complete
17:03:43.291 3 [f:954                           ] [spotify:playlist:playlist_U]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.291 3 [f:979                           ] [spotify:playlist:playlist_U]   - transient error, retrying.
17:03:43.325 5 [f:160                           ] [spotify:playlist:playlist_AF] Creating context
17:03:43.325 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_AF)
17:03:43.326 3 [f:680                           ] [spotify:playlist:playlist_AF] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.326 5 [f:408                           ] [spotify:playlist:playlist_AF] 'GET' request complete
17:03:43.326 3 [f:954                           ] [spotify:playlist:playlist_AF]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.326 3 [f:979                           ] [spotify:playlist:playlist_AF]   - transient error, retrying.
17:03:43.399 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_U)
17:03:43.399 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_AA)
17:03:43.399 3 [f:680                           ] [spotify:playlist:playlist_AA] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.399 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_Z)
17:03:43.399 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_y)
17:03:43.399 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_X)
17:03:43.399 3 [f:680                           ] [spotify:playlist:playlist_y] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.399 3 [f:680                           ] [spotify:playlist:playlist_X] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.400 3 [f:680                           ] [spotify:playlist:playlist_Z] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.400 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_W)
17:03:43.400 5 [f:408                           ] [spotify:playlist:playlist_AA] 'GET' request complete
17:03:43.400 3 [f:954                           ] [spotify:playlist:playlist_AA]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.400 3 [f:979                           ] [spotify:playlist:playlist_AA]   - transient error, retrying.
17:03:43.400 3 [f:680                           ] [spotify:playlist:playlist_W] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.400 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_V)
17:03:43.400 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_AB)
17:03:43.400 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_AC)
17:03:43.400 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_AD)
17:03:43.400 5 [f:408                           ] [spotify:playlist:playlist_y] 'GET' request complete
17:03:43.400 3 [f:954                           ] [spotify:playlist:playlist_y]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.400 3 [f:979                           ] [spotify:playlist:playlist_y]   - transient error, retrying.
17:03:43.400 5 [f:408                           ] [spotify:playlist:playlist_X] 'GET' request complete
17:03:43.400 3 [f:954                           ] [spotify:playlist:playlist_X]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.400 3 [f:979                           ] [spotify:playlist:playlist_X]   - transient error, retrying.
17:03:43.400 5 [f:408                           ] [spotify:playlist:playlist_Z] 'GET' request complete
17:03:43.400 3 [f:954                           ] [spotify:playlist:playlist_Z]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.400 3 [f:979                           ] [spotify:playlist:playlist_Z]   - transient error, retrying.
17:03:43.400 3 [f:680                           ] [spotify:playlist:playlist_V] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.401 3 [f:680                           ] [spotify:playlist:playlist_AB] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.401 3 [f:680                           ] [spotify:playlist:playlist_AC] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.401 3 [f:680                           ] [spotify:playlist:playlist_AD] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:43.401 5 [f:408                           ] [spotify:playlist:playlist_W] 'GET' request complete
17:03:43.401 3 [f:954                           ] [spotify:playlist:playlist_W]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.401 3 [f:979                           ] [spotify:playlist:playlist_W]   - transient error, retrying.
17:03:43.401 5 [f:408                           ] [spotify:playlist:playlist_V] 'GET' request complete
17:03:43.401 3 [f:954                           ] [spotify:playlist:playlist_V]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.401 3 [f:979                           ] [spotify:playlist:playlist_V]   - transient error, retrying.
17:03:43.401 5 [f:408                           ] [spotify:playlist:playlist_AB] 'GET' request complete
17:03:43.401 3 [f:954                           ] [spotify:playlist:playlist_AB]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.401 3 [f:979                           ] [spotify:playlist:playlist_AB]   - transient error, retrying.
17:03:43.401 5 [f:408                           ] [spotify:playlist:playlist_AC] 'GET' request complete
17:03:43.401 3 [f:954                           ] [spotify:playlist:playlist_AC]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.401 3 [f:979                           ] [spotify:playlist:playlist_AC]   - transient error, retrying.
17:03:43.401 5 [f:408                           ] [spotify:playlist:playlist_AD] 'GET' request complete
17:03:43.401 3 [f:954                           ] [spotify:playlist:playlist_AD]  synchronization error: Error category: http, code 11 (forced offline)
17:03:43.401 3 [f:979                           ] [spotify:playlist:playlist_AD]   - transient error, retrying.
17:03:43.437 I [f:182                           ] mdata: 733d15fe: Task from `your_library` finished. 7/7 entity URIs requested/needed (0.0% cached). 7/7 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 1 unfinished task(s).
17:03:44.002 I [f:629                           ] OfflinePlayableCache - 0 tracks found in batch 1 in 0 ms, (0 played, 0 missing file, 0 missing key, 0 missing metadata, 0 expired but in use, 0 missing in audio index)
17:03:44.002 I [f:376                           ] OfflinePlayableCache - 0 tracks found in a total of 1 batches in 0 ms, (0 missing file, 0 missing key, 0 missing metadata, 0 expired but in use, 0 missing in audio index)
17:03:44.218 I [f:182                           ] mdata: 5dac0e4f: Task from `your_library` finished. 7/7 entity URIs requested/needed (0.0% cached). 7/7 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 1 unfinished task(s).
17:03:44.424 I [f:182                           ] mdata: fbd37fbc: Task from `played_state` finished. 1/1 entity URIs requested/needed (0.0% cached). 1/1 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 0 unfinished task(s).
17:03:45.094 5 [f:160                           ] [spotify:playlist:playlist_AG] Creating context
17:03:45.095 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_AG)
17:03:45.095 3 [f:680                           ] [spotify:playlist:playlist_AG] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:45.095 5 [f:408                           ] [spotify:playlist:playlist_AG] 'GET' request complete
17:03:45.095 3 [f:954                           ] [spotify:playlist:playlist_AG]  synchronization error: Error category: http, code 11 (forced offline)
17:03:45.095 3 [f:979                           ] [spotify:playlist:playlist_AG]   - transient error, retrying.
17:03:45.095 5 [f:160                           ] [spotify:playlist:playlist_AG] Creating context
17:03:45.095 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_AG)
17:03:45.095 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_AG)
17:03:45.096 3 [f:680                           ] [spotify:playlist:playlist_AG] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:03:45.096 5 [f:408                           ] [spotify:playlist:playlist_AG] 'GET' request complete
17:03:45.096 3 [f:954                           ] [spotify:playlist:playlist_AG]  synchronization error: Error category: http, code 11 (forced offline)
17:03:45.096 3 [f:979                           ] [spotify:playlist:playlist_AG]   - transient error, retrying.
17:03:45.097 5 [f:163                           ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:playlist_AG)
17:03:46.326 W [f:64                            ] Downloaded list anomaly event:
{"listUri":"spotify:playlist:playlist_AF","featureId":2,"synchronizationState":1,"cacheExists":true,"cacheReadStatus":"OK","cacheHasRevision":false,"cacheHasPendingChanges":false,"cacheHasRequestedLenses":false,"numItemsInCache":0,"esperantoWaitedForS":2,"esperantoWaitingToLoadList":true,"esperantoWaitingForListMetadata":true,"esperantoWaitingForListRows":true,"esperantoWaitingForRecommendations":false,"esperantoWaitingForItemMetadata":true,"esperantoWaitingForItemMetadataExtensions":true,"listIsInTransaction":false}
17:03:46.326 W [f:620                           ] Cache entry:
{"cacheInfo":{"cacheGenerationVersion":1,"cacheRefreshNeeded":false,"headlessState":false},"selectedListContent":{"revision":"","attributes":{},"contents":{"pos":0,"truncated":false},"ownerUsername":"","abuseReportingEnabled":true},"playlistMembers":{},"pendingChanges":{},"additionalData":{},"permissions":{}}
17:03:46.584 I [f:221                           ] Received notification for a new pubsub connection, initiating a delta.
17:03:48.095 W [f:64                            ] Downloaded list anomaly event:
{"listUri":"spotify:playlist:playlist_AG","featureId":2,"synchronizationState":1,"cacheExists":true,"cacheReadStatus":"OK","cacheHasRevision":false,"cacheHasPendingChanges":false,"cacheHasRequestedLenses":false,"numItemsInCache":0,"esperantoWaitedForS":3,"esperantoWaitingToLoadList":true,"esperantoWaitingForListMetadata":true,"esperantoWaitingForListRows":true,"esperantoWaitingForRecommendations":false,"esperantoWaitingForItemMetadata":true,"esperantoWaitingForItemMetadataExtensions":true,"listIsInTransaction":false}
17:03:48.095 W [f:620                           ] Cache entry:
{"cacheInfo":{"cacheGenerationVersion":1,"cacheRefreshNeeded":false,"headlessState":false},"selectedListContent":{"revision":"","attributes":{},"contents":{"pos":0,"truncated":false},"ownerUsername":"","abuseReportingEnabled":true},"playlistMembers":{},"pendingChanges":{},"additionalData":{},"permissions":{}}
17:03:51.002 I [f:985                           ] Enumerated files in cache. Permanent: keep 1 files, 11764 bytes; remove 0 files, 0 bytes. Volatile: keep 0 files, 0 bytes; remove 0 files, 0 bytes. Missing: 0 files, 0 bytes
17:03:51.003 I [f:3096                          ] Storage has been cleaned
17:03:51.023 I [f:301                           ] Found 32 collection items out of 31 checked that need to be indexed (0ms).
17:03:51.023 I [f:301                           ] Found 29 collection items out of 29 checked that need to be indexed (0ms).
17:03:51.023 I [f:303                           ] Completed a full check of the collection index.
17:03:51.023 I [f:301                           ] Found 1 collection items out of 1 checked that need to be indexed (0ms).
17:03:51.023 I [f:303                           ] Completed a full check of the collection index.
17:03:51.023 I [f:301                           ] Found 5 collection items out of 5 checked that need to be indexed (0ms).
17:03:51.023 I [f:303                           ] Completed a full check of the collection index.
17:03:51.024 W [f:270                           ] collection: Index repairer: Index links failed: NotFound: ALBUM_V4 not found for album
17:03:51.024 I [f:182                           ] mdata: a15a4d2e: Task from `collection` finished. 32/32 entity URIs requested/needed (0.0% cached). 32/32 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 3 unfinished task(s).
17:03:51.024 I [f:182                           ] mdata: 7923c950: Task from `collection` finished. 29/29 entity URIs requested/needed (0.0% cached). 29/29 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 2 unfinished task(s).
17:03:51.025 I [f:182                           ] mdata: df76621f: Task from `collection` finished. 1/1 entity URIs requested/needed (0.0% cached). 1/1 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 1 unfinished task(s).
17:03:51.025 I [f:182                           ] mdata: d00b47c9: Task from `collection` finished. 5/5 entity URIs requested/needed (0.0% cached). 10/10 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 0 unfinished task(s).
17:03:51.028 I [f:182                           ] mdata: e6e67009: Task from `your_library` finished. 1/1 entity URIs requested/needed (0.0% cached). 1/1 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 4 unfinished task(s).
17:03:51.028 I [f:182                           ] mdata: 3124e093: Task from `your_library` finished. 1/1 entity URIs requested/needed (0.0% cached). 1/1 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 3 unfinished task(s).
17:03:51.028 I [f:182                           ] mdata: 513e7401: Task from `your_library` finished. 1/1 entity URIs requested/needed (0.0% cached). 1/1 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 2 unfinished task(s).
17:03:51.028 I [f:182                           ] mdata: 31d0dc35: Task from `your_library` finished. 1/1 entity URIs requested/needed (0.0% cached). 1/1 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 1 unfinished task(s).
17:03:51.028 I [f:182                           ] mdata: 7139bef5: Task from `your_library` finished. 7/7 entity URIs requested/needed (0.0% cached). 7/7 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 0 unfinished task(s).
17:03:51.323 I [f:301                           ] Found 240 collection items out of 240 checked that need to be indexed (0ms).
17:03:51.323 I [f:303                           ] Completed a full check of the collection index.
17:03:51.326 I [f:182                           ] mdata: 5dd3aa48: Task from `collection` finished. 240/240 entity URIs requested/needed (0.0% cached). 240/240 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 0 unfinished task(s).
17:03:51.327 I [f:182                           ] mdata: a4d58e: Task from `your_library` finished. 1/1 entity URIs requested/needed (0.0% cached). 1/1 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 3 unfinished task(s).
17:03:51.327 I [f:182                           ] mdata: a8a38358: Task from `your_library` finished. 1/1 entity URIs requested/needed (0.0% cached). 1/1 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 2 unfinished task(s).
17:03:51.327 I [f:182                           ] mdata: 6c4ac3e1: Task from `your_library` finished. 1/1 entity URIs requested/needed (0.0% cached). 1/1 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 1 unfinished task(s).
17:03:51.328 I [f:182                           ] mdata: 1819e4d3: Task from `your_library` finished. 1/1 entity URIs requested/needed (0.0% cached). 1/1 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 0 unfinished task(s).
17:03:51.330 I [f:182                           ] mdata: 5e0e9832: Task from `your_library` finished. 7/7 entity URIs requested/needed (0.0% cached). 7/7 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 0 unfinished task(s).
17:04:02.713 3 [f:680                           ] [spotify:playlist:playlist_Z] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:02.714 5 [f:408                           ] [spotify:playlist:playlist_Z] 'GET' request complete
17:04:02.714 3 [f:954                           ] [spotify:playlist:playlist_Z]  synchronization error: Error category: http, code 11 (forced offline)
17:04:02.714 3 [f:979                           ] [spotify:playlist:playlist_Z]   - transient error, retrying.
17:04:03.215 3 [f:680                           ] [spotify:playlist:playlist_E] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:03.215 5 [f:408                           ] [spotify:playlist:playlist_E] 'GET' request complete
17:04:03.215 3 [f:954                           ] [spotify:playlist:playlist_E]  synchronization error: Error category: http, code 11 (forced offline)
17:04:03.215 3 [f:979                           ] [spotify:playlist:playlist_E]   - transient error, retrying.
17:04:03.502 3 [f:680                           ] [spotify:playlist:playlist_N] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:03.502 5 [f:408                           ] [spotify:playlist:playlist_N] 'GET' request complete
17:04:03.502 3 [f:954                           ] [spotify:playlist:playlist_N]  synchronization error: Error category: http, code 11 (forced offline)
17:04:03.502 3 [f:979                           ] [spotify:playlist:playlist_N]   - transient error, retrying.
17:04:03.659 3 [f:680                           ] [spotify:playlist:playlist_X] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:03.659 5 [f:408                           ] [spotify:playlist:playlist_X] 'GET' request complete
17:04:03.659 3 [f:954                           ] [spotify:playlist:playlist_X]  synchronization error: Error category: http, code 11 (forced offline)
17:04:03.659 3 [f:979                           ] [spotify:playlist:playlist_X]   - transient error, retrying.
17:04:03.847 3 [f:680                           ] [spotify:playlist:playlist_G] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:03.847 5 [f:408                           ] [spotify:playlist:playlist_G] 'GET' request complete
17:04:03.847 3 [f:954                           ] [spotify:playlist:playlist_G]  synchronization error: Error category: http, code 11 (forced offline)
17:04:03.847 3 [f:979                           ] [spotify:playlist:playlist_G]   - transient error, retrying.
17:04:04.488 3 [f:680                           ] [spotify:playlist:playlist_I] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:04.488 5 [f:408                           ] [spotify:playlist:playlist_I] 'GET' request complete
17:04:04.488 3 [f:954                           ] [spotify:playlist:playlist_I]  synchronization error: Error category: http, code 11 (forced offline)
17:04:04.488 3 [f:979                           ] [spotify:playlist:playlist_I]   - transient error, retrying.
17:04:04.493 3 [f:680                           ] [spotify:playlist:playlist_A] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:04.493 5 [f:408                           ] [spotify:playlist:playlist_A] 'GET' request complete
17:04:04.493 3 [f:954                           ] [spotify:playlist:playlist_A]  synchronization error: Error category: http, code 11 (forced offline)
17:04:04.493 3 [f:979                           ] [spotify:playlist:playlist_A]   - transient error, retrying.
17:04:05.514 I [f:182                           ] mdata: f183bbe2: Task from `extended_metadata` finished. 1/1 entity URIs requested/needed (0.0% cached). 1/1 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 0 unfinished task(s).
17:04:06.463 3 [f:680                           ] [spotify:playlist:playlist_AB] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:06.463 5 [f:408                           ] [spotify:playlist:playlist_AB] 'GET' request complete
17:04:06.463 3 [f:954                           ] [spotify:playlist:playlist_AB]  synchronization error: Error category: http, code 11 (forced offline)
17:04:06.463 3 [f:979                           ] [spotify:playlist:playlist_AB]   - transient error, retrying.
17:04:06.562 3 [f:680                           ] [spotify:playlist:playlist_AG] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:06.562 5 [f:408                           ] [spotify:playlist:playlist_AG] 'GET' request complete
17:04:06.562 3 [f:954                           ] [spotify:playlist:playlist_AG]  synchronization error: Error category: http, code 11 (forced offline)
17:04:06.562 3 [f:979                           ] [spotify:playlist:playlist_AG]   - transient error, retrying.
17:04:07.232 3 [f:680                           ] [spotify:playlist:playlist_AC] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:07.232 5 [f:408                           ] [spotify:playlist:playlist_AC] 'GET' request complete
17:04:07.232 3 [f:954                           ] [spotify:playlist:playlist_AC]  synchronization error: Error category: http, code 11 (forced offline)
17:04:07.232 3 [f:979                           ] [spotify:playlist:playlist_AC]   - transient error, retrying.
17:04:08.546 3 [f:680                           ] [spotify:playlist:playlist_B] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:08.546 5 [f:408                           ] [spotify:playlist:playlist_B] 'GET' request complete
17:04:08.546 3 [f:954                           ] [spotify:playlist:playlist_B]  synchronization error: Error category: http, code 11 (forced offline)
17:04:08.546 3 [f:979                           ] [spotify:playlist:playlist_B]   - transient error, retrying.
17:04:09.159 3 [f:680                           ] [spotify:playlist:playlist_D] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:09.159 5 [f:408                           ] [spotify:playlist:playlist_D] 'GET' request complete
17:04:09.159 3 [f:954                           ] [spotify:playlist:playlist_D]  synchronization error: Error category: http, code 11 (forced offline)
17:04:09.159 3 [f:979                           ] [spotify:playlist:playlist_D]   - transient error, retrying.
17:04:09.945 3 [f:680                           ] [spotify:playlist:playlist_P] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:09.945 5 [f:408                           ] [spotify:playlist:playlist_P] 'GET' request complete
17:04:09.945 3 [f:954                           ] [spotify:playlist:playlist_P]  synchronization error: Error category: http, code 11 (forced offline)
17:04:09.945 3 [f:979                           ] [spotify:playlist:playlist_P]   - transient error, retrying.
17:04:09.950 3 [f:680                           ] [spotify:playlist:playlist_W] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:09.950 5 [f:408                           ] [spotify:playlist:playlist_W] 'GET' request complete
17:04:09.950 3 [f:954                           ] [spotify:playlist:playlist_W]  synchronization error: Error category: http, code 11 (forced offline)
17:04:09.950 3 [f:979                           ] [spotify:playlist:playlist_W]   - transient error, retrying.
17:04:11.005 I [f:2650                          ] Pruning unused resources
17:04:11.005 I [f:2742                          ] Nothing to prune
17:04:11.012 I [f:1598                          ] No product state was retrieved, unable to update
17:04:11.234 3 [f:680                           ] [spotify:playlist:playlist_U] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:11.234 5 [f:408                           ] [spotify:playlist:playlist_U] 'GET' request complete
17:04:11.234 3 [f:954                           ] [spotify:playlist:playlist_U]  synchronization error: Error category: http, code 11 (forced offline)
17:04:11.234 3 [f:979                           ] [spotify:playlist:playlist_U]   - transient error, retrying.
17:04:11.478 3 [f:680                           ] [spotify:playlist:playlist_F] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:11.478 5 [f:408                           ] [spotify:playlist:playlist_F] 'GET' request complete
17:04:11.478 3 [f:954                           ] [spotify:playlist:playlist_F]  synchronization error: Error category: http, code 11 (forced offline)
17:04:11.478 3 [f:979                           ] [spotify:playlist:playlist_F]   - transient error, retrying.
17:04:12.013 I [f:156                           ] AudioIndex Success! Added:
  #tracks: 0 (0)
  #episodes: 0 (0)
17:04:12.020 I [f:243                           ] aq: LWS got LWS_CALLBACK_CLIENT_WRITEABLE
17:04:12.025 3 [f:680                           ] [spotify:playlist:playlist_AF] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:12.025 5 [f:408                           ] [spotify:playlist:playlist_AF] 'GET' request complete
17:04:12.025 3 [f:954                           ] [spotify:playlist:playlist_AF]  synchronization error: Error category: http, code 11 (forced offline)
17:04:12.025 3 [f:979                           ] [spotify:playlist:playlist_AF]   - transient error, retrying.
17:04:12.059 I [f:243                           ] aq: LWS got LWS_CALLBACK_CLIENT_RECEIVE_PONG
17:04:12.076 3 [f:680                           ] [spotify:playlist:playlist_c] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:12.076 5 [f:408                           ] [spotify:playlist:playlist_c] 'GET' request complete
17:04:12.076 3 [f:954                           ] [spotify:playlist:playlist_c]  synchronization error: Error category: http, code 11 (forced offline)
17:04:12.076 3 [f:979                           ] [spotify:playlist:playlist_c]   - transient error, retrying.
17:04:12.220 3 [f:680                           ] [spotify:playlist:playlist_T] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:12.220 5 [f:408                           ] [spotify:playlist:playlist_T] 'GET' request complete
17:04:12.220 3 [f:954                           ] [spotify:playlist:playlist_T]  synchronization error: Error category: http, code 11 (forced offline)
17:04:12.220 3 [f:979                           ] [spotify:playlist:playlist_T]   - transient error, retrying.
17:04:12.400 3 [f:680                           ] [spotify:playlist:playlist_O] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:12.400 5 [f:408                           ] [spotify:playlist:playlist_O] 'GET' request complete
17:04:12.400 3 [f:954                           ] [spotify:playlist:playlist_O]  synchronization error: Error category: http, code 11 (forced offline)
17:04:12.400 3 [f:979                           ] [spotify:playlist:playlist_O]   - transient error, retrying.
17:04:13.506 3 [f:680                           ] [spotify:playlist:playlist_R] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:13.506 5 [f:408                           ] [spotify:playlist:playlist_R] 'GET' request complete
17:04:13.506 3 [f:954                           ] [spotify:playlist:playlist_R]  synchronization error: Error category: http, code 11 (forced offline)
17:04:13.506 3 [f:979                           ] [spotify:playlist:playlist_R]   - transient error, retrying.
17:04:14.003 I [f:202                           ] ConcurrentCollector ran mark cycle for 0.017ms, total: 0.017ms.
17:04:14.231 3 [f:680                           ] [spotify:playlist:playlist_AE] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:14.231 5 [f:408                           ] [spotify:playlist:playlist_AE] 'GET' request complete
17:04:14.231 3 [f:954                           ] [spotify:playlist:playlist_AE]  synchronization error: Error category: http, code 11 (forced offline)
17:04:14.231 3 [f:979                           ] [spotify:playlist:playlist_AE]   - transient error, retrying.
17:04:14.674 3 [f:680                           ] [spotify:playlist:playlist_AD] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:14.674 5 [f:408                           ] [spotify:playlist:playlist_AD] 'GET' request complete
17:04:14.674 3 [f:954                           ] [spotify:playlist:playlist_AD]  synchronization error: Error category: http, code 11 (forced offline)
17:04:14.674 3 [f:979                           ] [spotify:playlist:playlist_AD]   - transient error, retrying.
17:04:15.190 3 [f:680                           ] [spotify:playlist:playlist_Q] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:15.190 5 [f:408                           ] [spotify:playlist:playlist_Q] 'GET' request complete
17:04:15.190 3 [f:954                           ] [spotify:playlist:playlist_Q]  synchronization error: Error category: http, code 11 (forced offline)
17:04:15.190 3 [f:979                           ] [spotify:playlist:playlist_Q]   - transient error, retrying.
17:04:17.049 3 [f:680                           ] [spotify:playlist:playlist_H] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:17.049 5 [f:408                           ] [spotify:playlist:playlist_H] 'GET' request complete
17:04:17.049 3 [f:954                           ] [spotify:playlist:playlist_H]  synchronization error: Error category: http, code 11 (forced offline)
17:04:17.049 3 [f:979                           ] [spotify:playlist:playlist_H]   - transient error, retrying.
17:04:17.062 3 [f:680                           ] [spotify:playlist:playlist_AA] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000) 
17:04:17.062 5 [f:408                           ] [spotify:playlist:playlist_AA] 'GET' request complete
17:04:17.062 3 [f:954                           ] [spotify:playlist:playlist_AA]  synchronization error: Error category: http, code 11 (forced offline)
17:04:17.062 3 [f:979                           ] [spotify:playlist:playlist_AA]   - transient error, retrying.
17:04:18.425 I [f:1598                          ] No product state was retrieved, unable to update
17:04:18.425 I [f:3298                          ] == Offline Report ==
17:04:18.425 I [f:3299                          ] change_reason: shutdown-report
17:04:18.425 I [f:3300                          ] num_downloaded_tracks: 0
17:04:18.425 I [f:3301                          ] total_num_tracks: 0
17:04:18.425 I [f:3302                          ] num_downloaded_episodes: 0
17:04:18.425 I [f:3303                          ] total_num_episodes: 0
17:04:18.425 I [f:3304                          ] track_limit: 0
17:04:18.425 I [f:3305                          ] track_expiry: 2592000
17:04:18.425 I [f:3306                          ] episode_limit: 0
17:04:18.425 I [f:3307                          ] episode_expiry: 2592000
17:04:18.425 I [f:3308                          ] offline_keys: 0
17:04:18.426 I [f:3309                          ] cached_keys: 0
17:04:18.426 I [f:3310                          ] total_num_links: 0
17:04:18.426 I [f:3318                          ] schedule_stats_map: 'log-report,8': 1
17:04:18.426 I [f:3318                          ] schedule_stats_map: 'sync-level-updated,4': 1
17:04:18.426 I [f:3321                          ] update_stats_map: 'sync-level-updated,4': 1
17:04:18.426 I [f:3323                          ] num_update_all_lists: 1
17:04:18.426 I [f:3324                          ] ====================
17:04:18.427 I [f:61                            ] Saved 4 log messages to '/home/<unix-user>/.config/spotify/Users/<spotify-user>/log'
17:04:18.430 D [f:212                           ] WoL: Mark timer as being shutdown
17:04:18.430 D [f:329                           ] Storing dial devices []
17:04:18.432 I [f:123                           ] Logger currently has no transport!
17:04:18.432 I [f:123                           ] Logger currently has no transport!

Still not working on either 24.11 or unstable

ardijanr commented 4 weeks ago

Finally fixed it, seems like my issue was due to me configuring static IP and at the same time having nmcli enabled. Somehow this bugged spotify out. Everything else worked well.