Skip to content

Instantly share code, notes, and snippets.

@calscks
Created March 26, 2023 15:37
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save calscks/33ef37af4aa3b1be3221e048472d3cdd to your computer and use it in GitHub Desktop.
Save calscks/33ef37af4aa3b1be3221e048472d3cdd to your computer and use it in GitHub Desktop.
spotify log
15:27:21.349 I [/f:161 ] Access allowance changed from online: 0 stream: 0, sync: 0, persistent conn: 0 to online: 1 stream: 1, sync: 0, persistent conn: 1
15:27:21.350 I [/f:77 ] Connectivity policy is initially allow_all
15:27:21.350 I [/f:79 ] Connection type is initially unknown
15:27:21.350 I [/f:96 ] Enabling all persistent connections
15:27:21.350 I [/f:139 ] Open storage index header at C:\Users\ckseo\AppData\Local\Spotify\Storage\index.dat
15:27:21.350 I [/f:23 ] Opened storage index at C:\Users\ckseo\AppData\Local\Spotify\Storage OK
15:27:21.394 I [/f:145 ] Login5OAuthClient: Product state changed, force renew.
15:27:21.398 E [/f:259 ] WinHttpGetProxyForUrl failed
15:27:21.414 I [/f:391 ] Reachability changed from unknown (IP [], roaming false) to unknown (IP [192.168.1.199, 2001:0:2851:fcb0:20db:38bb:5076:2434, 2001:e68:5419:8466:1a67:5b7a:922c:5a48, 2001:e68:5419:8466:9c5d:74d8:55db:b2ed, fe80::e41:c64a:bc89:1914%4, fe80::20db:38bb:5076:2434%13], roaming false)
15:27:21.414 I [/f:192 ] Scheduling emission of signal.
15:27:21.415 I [/f:131 ] connectivityChanged(), is_logged_in:0
15:27:21.441 I [/f:33 ] Using Hermes log transport
15:27:21.441 I [/f:113 ] Logger is now using a valid transport!
15:27:21.441 I [/f:113 ] Logger is now using a valid transport!
15:27:21.459 E [/f:71 ] no element found at line 1, column 0
15:27:21.462 I [/f:480 ] Local device coordinator enabled write resources & delta updates
15:27:21.462 I [/f:2328 ] sync level updated: none -> none. (online: 0 connection_allows_offlining: 1 has_device_token: 0 allow_spotify_tracks: 1)
15:27:21.462 I [/f:2328 ] sync level updated: none -> none. (online: 1 connection_allows_offlining: 1 has_device_token: 0 allow_spotify_tracks: 1)
15:27:21.462 I [/f:141 ] Device coordinator was set online
15:27:21.462 I [/f:2328 ] sync level updated: none -> none. (online: 1 connection_allows_offlining: 0 has_device_token: 0 allow_spotify_tracks: 1)
15:27:21.463 5 [/f:115 ] [spotify:user:lolzcalvin:rootlist] Creating context
15:27:21.466 D [/connect:29 ] Logging level was changed from 4 to 6
15:27:21.466 D [/connect:147 ] allow_mft_to_mft_transfers = false
15:27:21.466 D [/connect:150 ] use_injected_http_dial = false
15:27:21.466 D [/connect:153 ] use_injected_http_mdns= false
15:27:21.466 D [/connect:156 ] use_ipv6 = false
15:27:21.466 D [/connect:159 ] pick_and_shuffle_restricted = false
15:27:21.466 D [/connect:163 ] mdns_discovery_metrics_enabled = false
15:27:21.466 D [/connect:167 ] getinfo_discovery_metrics_enabled = false
15:27:21.466 D [/connect:169 ] mdns_retry_count = 1
15:27:21.466 D [/connect:172 ] show_offline_devices_in_core = true
15:27:21.466 D [/connect:178 ] show_offline_amazon_devices_as_online = false
15:27:21.466 D [/connect:182 ] cast_transfer_timeout = 30s
15:27:21.466 D [/connect:186 ] strip_update_context_commands = 1
15:27:21.466 D [/connect:189 ] incarnations_on_desktop = 1
15:27:21.466 D [/connect:192 ] use_xresolve = 0
15:27:21.466 D [/connect:195 ] reduce_state_updates = 1
15:27:21.466 D [/connect:198 ] call_set_device_inactive = 1
15:27:21.467 D [/connect_tier1:223 ] Tier1LocalVerificationListener::enableLocalSocket: listening on port 1705
15:27:21.467 D [/connect_onboarding:402 ] added filter for device type 4 with brand
15:27:21.467 D [/connect_onboarding:402 ] added filter for device type 6 with brand
15:27:21.467 D [/connect_onboarding:402 ] added filter for device type 8 with brand
15:27:21.467 D [/connect_onboarding:402 ] added filter for device type 11 with brand
15:27:21.467 D [/connect_onboarding:402 ] added filter for device type 10 with brand
15:27:21.467 D [/connect_onboarding:402 ] added filter for device type 9 with brand
15:27:21.467 D [/connect_onboarding:402 ] added filter for device type 1 with brand
15:27:21.467 D [/connect_onboarding:402 ] added filter for device type 2 with brand
15:27:21.468 D [/connect_onboarding:402 ] added filter for device type 3 with brand
15:27:21.482 I [/f:66 ] GetAdapterConnectionType: Couldn't get result object! 0x1
15:27:21.492 I [/f:46 ] Track offlining: 0 tracks offlined
15:27:21.492 I [/connect:279 ] ConnectManager::start
15:27:21.493 D [/connect:291 ] Started before online, not sending putstate
15:27:21.493 D [/connect_network:185 ] DeviceCapabilityMap::fetchCapabilities Fetching from DeviceCapabilities
15:27:21.496 I [/f:168 ] Emitting signal.
15:27:21.496 I [/f:131 ] connectivityChanged(), is_logged_in:0
15:27:21.496 D [/connect_tier1:164 ] Tier1LocalVerificationListener::startAccept: error: The I/O operation has been aborted because of either a thread exit or an application request.
15:27:21.496 D [/connect_tier1:223 ] Tier1LocalVerificationListener::enableLocalSocket: listening on port 1706
15:27:21.507 D [/connect_dial:1062 ] DialManager::scanDevices
15:27:21.520 I [/connect:383 ] Updated connection type from -1 to -1
15:27:21.525 I [/f:191 ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
15:27:21.525 I [/f:200 ] Login5OAuthClient::Impl::loadToken: Loading token from network.
15:27:21.541 I [/f:191 ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
15:27:21.541 I [/f:191 ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
15:27:21.552 I [/f:191 ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
15:27:21.552 I [/f:191 ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
15:27:21.552 I [/f:191 ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
15:27:21.556 I [/f:191 ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
15:27:21.556 I [/f:1974 ] Update! reason: initial-update, flags: 0x0f
15:27:21.556 I [/f:2950 ] == Offline Report ==
15:27:21.556 I [/f:2951 ] change_reason: startup-report
15:27:21.556 I [/f:2952 ] num_downloaded_tracks: 0
15:27:21.556 I [/f:2953 ] total_num_tracks: 0
15:27:21.556 I [/f:2954 ] num_downloaded_episodes: 0
15:27:21.556 I [/f:2955 ] total_num_episodes: 0
15:27:21.556 I [/f:2956 ] track_limit: 0
15:27:21.556 I [/f:2957 ] track_expiry: 2592000
15:27:21.556 I [/f:2958 ] episode_limit: 0
15:27:21.556 I [/f:2959 ] episode_expiry: 2592000
15:27:21.556 I [/f:2960 ] offline_keys: 0
15:27:21.556 I [/f:2961 ] cached_keys: 55
15:27:21.556 I [/f:2962 ] total_num_links: 0
15:27:21.556 I [/f:2969 ] ====================
15:27:21.559 5 [/f:547 ] synchronizeRootlist 'spotify:user:lolzcalvin:rootlist'
15:27:21.560 5 [/f:115 ] [spotify:playlist:3HMpb3UJdFRlO1in2wjzF4] Creating context
15:27:21.560 5 [/f:115 ] [spotify:playlist:1tjc3cEI635mV5dnPIasaI] Creating context
15:27:21.560 5 [/f:115 ] [spotify:playlist:6FDIX9JhlfeleIGhvo7Aca] Creating context
15:27:21.560 5 [/f:115 ] [spotify:playlist:7FKDCZuhS7WzhiFCGAklKD] Creating context
15:27:21.560 5 [/f:115 ] [spotify:playlist:4n6rgAnbmMAYciqvl7w5vi] Creating context
15:27:21.560 5 [/f:115 ] [spotify:playlist:2XJ5b63kF3rbZ5hCtE4H4R] Creating context
15:27:21.560 5 [/f:115 ] [spotify:playlist:6DzJWxxEVFW59HcEbviz3t] Creating context
15:27:21.560 5 [/f:115 ] [spotify:playlist:1eAFjFoz6DoPUQWdAoXSEw] Creating context
15:27:21.560 5 [/f:115 ] [spotify:playlist:5X68rWZjXTIM1ILJMMGacS] Creating context
15:27:21.560 5 [/f:115 ] [spotify:playlist:1rE8nWDLaxIhNTcWfLK5eB] Creating context
15:27:21.560 5 [/f:115 ] [spotify:playlist:3IjVyKx0ZvRqJQwc7SIibh] Creating context
15:27:21.560 5 [/f:115 ] [spotify:playlist:4m3aVgY0Qm6fTgLIMpbipK] Creating context
15:27:21.560 5 [/f:115 ] [spotify:playlist:5mXxoAbOOlIwBhrFLKfc9j] Creating context
15:27:21.561 3 [/f:436 ] [spotify:user:lolzcalvin:rootlist] Synchronization starting: DIFF (from revision 76,ba82c7ba18c9619ae1d38a7af934afbf166330df)
15:27:21.563 I [/f:191 ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
15:27:21.567 I [/f:191 ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
15:27:21.567 I [/f:191 ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
15:27:21.567 I [/f:191 ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
15:27:21.567 I [/f:191 ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
15:27:21.567 I [/f:191 ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
15:27:21.567 I [/f:191 ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
15:27:21.571 I [/f:191 ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
15:27:21.582 I [/f:185 ] mdata: fa01a253: Task from `collection` finished. 0/10 entity URIs requested/needed (100.0% cached). 0/10 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 0 unfinished task(s).
15:27:21.582 I [/f:191 ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
15:27:21.583 I [/f:191 ] Login5OAuthClient::Impl::loadToken: Token not cached, or expired.
15:27:21.583 E [/connect:465 ] ConnectConnectivityListener: received empty connection_id
15:27:21.583 D [/connect:950 ] Connection lost
15:27:21.583 E [/connect:465 ] ConnectConnectivityListener: received empty connection_id
15:27:21.583 D [/connect:950 ] Connection lost
15:27:21.585 D [/connect_network:231 ] Searching for devices on the outbound network interface 192.168.1.199 (multicast IPv4 socket)
15:27:21.585 D [/connect_network:231 ] Searching for devices on the outbound network interface 192.168.1.199 (unicast IPv4 socket)
15:27:21.588 I [/f:228 ] aq: on GoOffline[Offline] -> [Offline]
15:27:21.588 E [/f:210 ] aq: OnError reason: 3, error:User requested disconnect
15:27:21.588 I [/f:228 ] aq: Disconnected
15:27:21.588 I [/f:228 ] aq: on GoOffline[Offline] -> [Offline]
15:27:21.588 E [/f:210 ] aq: OnError reason: 3, error:User requested disconnect
15:27:21.588 I [/f:228 ] aq: Disconnected
15:27:21.614 I [/f:508 ] Connecting to AP ap-gae2.spotify.com:4070
15:27:21.616 D [/connect_network:231 ] Searching for devices on the outbound network interface 192.168.1.199 (multicast IPv4 socket)
15:27:21.616 D [/connect_network:231 ] Searching for devices on the outbound network interface 2001:e68:5419:8466:1a67:5b7a:922c:5a48 (multicast IPv6 socket)
15:27:21.616 D [/connect_network:231 ] Searching for devices on the outbound network interface 2001:e68:5419:8466:9c5d:74d8:55db:b2ed (multicast IPv6 socket)
15:27:21.617 D [/connect_network:231 ] Searching for devices on the outbound network interface fe80::e41:c64a:bc89:1914%4 (multicast IPv6 socket)
15:27:21.619 E [/connect:465 ] ConnectConnectivityListener: received empty connection_id
15:27:21.619 D [/connect:950 ] Connection lost
15:27:21.619 E [/connect:465 ] ConnectConnectivityListener: received empty connection_id
15:27:21.619 D [/connect:950 ] Connection lost
15:27:21.621 I [/f:59 ] Resolved ap-gae2.spotify.com to 104.199.240.237
15:27:21.681 I [/f:139 ] Connected to AP: 104.199.240.237:4070
15:27:21.734 I [/f:460 ] High request latency: https://login5.spotify.com/v3 took 151 ms
15:27:21.735 I [/f:218 ] Login5OAuthClient::Impl::loadTokenFromNetwork: Got Login5 reply.
15:27:21.735 I [/f:257 ] Login5OAuthClient: Successful authentication
15:27:21.746 5 [/f:168 ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:7FKDCZuhS7WzhiFCGAklKD)
15:27:21.746 5 [/f:168 ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:1rE8nWDLaxIhNTcWfLK5eB)
15:27:21.746 5 [/f:168 ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:1tjc3cEI635mV5dnPIasaI)
15:27:21.746 5 [/f:168 ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:5X68rWZjXTIM1ILJMMGacS)
15:27:21.746 5 [/f:168 ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:2XJ5b63kF3rbZ5hCtE4H4R)
15:27:21.746 5 [/f:168 ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:3HMpb3UJdFRlO1in2wjzF4)
15:27:21.748 5 [/f:168 ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:5mXxoAbOOlIwBhrFLKfc9j)
15:27:21.749 5 [/f:168 ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:1eAFjFoz6DoPUQWdAoXSEw)
15:27:21.751 I [/f:185 ] mdata: ed0889e9: Task from `unknown` finished. 0/0 entity URIs requested/needed (0.0% cached). 0/0 extensions requested/needed (0.0% w/ ETags, 0.0% cached). 0 unfinished task(s).
15:27:21.755 3 [/f:436 ] [spotify:playlist:3HMpb3UJdFRlO1in2wjzF4] Synchronization starting: DIFF (from revision 74,a57e3496bb64d5bc0bfab4ecca458dbcc9f9b722)
15:27:21.763 3 [/f:436 ] [spotify:playlist:1tjc3cEI635mV5dnPIasaI] Synchronization starting: DIFF (from revision 188,522dba0210df7f17b43152832bd06a0c3dd4cf5f)
15:27:21.769 3 [/f:436 ] [spotify:playlist:7FKDCZuhS7WzhiFCGAklKD] Synchronization starting: DIFF (from revision 147,f38f8b6e17a5e76b112169e61abdd3b3daa9a559)
15:27:21.777 3 [/f:436 ] [spotify:playlist:1eAFjFoz6DoPUQWdAoXSEw] Synchronization starting: DIFF (from revision 270,3b418668f172c996dc699153b9d63756e87d3f94)
15:27:21.789 I [/f:185 ] mdata: 9532283c: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 0 unfinished task(s).
15:27:21.833 5 [/f:278 ] [spotify:user:lolzcalvin:rootlist] 'DIFF' request complete
15:27:21.833 3 [/f:1127 ] [spotify:user:lolzcalvin:rootlist] Delta-updated to revision 76,ba82c7ba18c9619ae1d38a7af934afbf166330df (no new changes)
15:27:21.835 W [/social_listening:190 ] Request to social-connect failed, client error=0, server status=404
15:27:21.839 I [/f:141 ] parseLoadedContext done: 200 context://spotify:artist:23fqKkggKUBHNkbKtXEls4 (24 tracks)
15:27:21.848 I [/f:311 ] AP login welcome, len 270
15:27:21.849 I [/f:989 ] Logging connection info message, current ap: ap-gae2.spotify.com:4070
15:27:21.850 I [/f:342 ] AP login complete, len 0
15:27:21.857 3 [/f:436 ] [spotify:playlist:2XJ5b63kF3rbZ5hCtE4H4R] Synchronization starting: DIFF (from revision 23,66cc81b46456d81856c6b9476ae486d7907b85b8)
15:27:21.858 5 [/f:278 ] [spotify:playlist:1tjc3cEI635mV5dnPIasaI] 'DIFF' request complete
15:27:21.858 3 [/f:1127 ] [spotify:playlist:1tjc3cEI635mV5dnPIasaI] Delta-updated to revision 188,522dba0210df7f17b43152832bd06a0c3dd4cf5f (no new changes)
15:27:21.863 3 [/f:436 ] [spotify:playlist:5X68rWZjXTIM1ILJMMGacS] Synchronization starting: DIFF (from revision 111,44dff984db16e6d23ab5f2609184b318fb285d0a)
15:27:21.863 5 [/f:278 ] [spotify:playlist:3HMpb3UJdFRlO1in2wjzF4] 'DIFF' request complete
15:27:21.863 3 [/f:1127 ] [spotify:playlist:3HMpb3UJdFRlO1in2wjzF4] Delta-updated to revision 74,a57e3496bb64d5bc0bfab4ecca458dbcc9f9b722 (no new changes)
15:27:21.864 5 [/f:278 ] [spotify:playlist:1eAFjFoz6DoPUQWdAoXSEw] 'DIFF' request complete
15:27:21.864 3 [/f:1127 ] [spotify:playlist:1eAFjFoz6DoPUQWdAoXSEw] Delta-updated to revision 270,3b418668f172c996dc699153b9d63756e87d3f94 (no new changes)
15:27:21.866 5 [/f:278 ] [spotify:playlist:7FKDCZuhS7WzhiFCGAklKD] 'DIFF' request complete
15:27:21.866 3 [/f:1127 ] [spotify:playlist:7FKDCZuhS7WzhiFCGAklKD] Delta-updated to revision 147,f38f8b6e17a5e76b112169e61abdd3b3daa9a559 (no new changes)
15:27:21.898 3 [/f:436 ] [spotify:playlist:1rE8nWDLaxIhNTcWfLK5eB] Synchronization starting: DIFF (from revision 36,2ea0fb4994103d55fc11805f427f83a036b94556)
15:27:21.898 3 [/f:436 ] [spotify:playlist:5mXxoAbOOlIwBhrFLKfc9j] Synchronization starting: GET (from revision 91,f08732e6f0d12c46d3d57e2cecd76fb3a9a27a47)
15:27:21.930 5 [/f:278 ] [spotify:playlist:2XJ5b63kF3rbZ5hCtE4H4R] 'DIFF' request complete
15:27:21.930 3 [/f:1127 ] [spotify:playlist:2XJ5b63kF3rbZ5hCtE4H4R] Delta-updated to revision 23,66cc81b46456d81856c6b9476ae486d7907b85b8 (no new changes)
15:27:21.931 5 [/f:278 ] [spotify:playlist:5X68rWZjXTIM1ILJMMGacS] 'DIFF' request complete
15:27:21.931 3 [/f:1127 ] [spotify:playlist:5X68rWZjXTIM1ILJMMGacS] Delta-updated to revision 111,44dff984db16e6d23ab5f2609184b318fb285d0a (no new changes)
15:27:21.957 I [/f:141 ] Device coordinator was set online
15:27:21.958 5 [/f:547 ] synchronizeRootlist 'spotify:user:lolzcalvin:rootlist'
15:27:21.965 5 [/f:278 ] [spotify:playlist:5mXxoAbOOlIwBhrFLKfc9j] 'GET' request complete
15:27:21.965 5 [/f:319 ] Removed playlist attribute: LIST_DESCRIPTION
15:27:21.965 5 [/f:319 ] Removed playlist attribute: LIST_COLLABORATIVE
15:27:21.965 5 [/f:319 ] Removed playlist attribute: LIST_PL3_VERSION
15:27:21.965 5 [/f:319 ] Removed playlist attribute: LIST_DELETED_BY_OWNER
15:27:21.965 5 [/f:319 ] Removed playlist attribute: LIST_CLIENT_ID
15:27:21.965 5 [/f:319 ] Removed playlist attribute: LIST_FORMAT
15:27:21.965 5 [/f:319 ] Removed playlist attribute: LIST_FORMAT_ATTRIBUTES
15:27:21.965 5 [/f:319 ] Removed playlist attribute: LIST_PICTURE_SIZE
15:27:21.965 5 [/f:183 ] Add 34 tracks
15:27:21.968 3 [/f:1214 ] [spotify:playlist:5mXxoAbOOlIwBhrFLKfc9j] Snapshot-updated to revision 91,f08732e6f0d12c46d3d57e2cecd76fb3a9a27a47
15:27:21.968 I [/f:185 ] mdata: d1fa3f65: Task from `profile` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 0 unfinished task(s).
15:27:21.970 5 [/f:278 ] [spotify:playlist:1rE8nWDLaxIhNTcWfLK5eB] 'DIFF' request complete
15:27:21.970 3 [/f:1127 ] [spotify:playlist:1rE8nWDLaxIhNTcWfLK5eB] Delta-updated to revision 36,2ea0fb4994103d55fc11805f427f83a036b94556 (no new changes)
15:27:21.990 D [/connect_dial:1062 ] DialManager::scanDevices
15:27:22.024 I [/f:185 ] mdata: 22599d4a: Task from `profile` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 0 unfinished task(s).
15:27:22.102 I [/f:153 ] Current score: WRITABLE: 1 (0), UNPLAYED: 2 (0), IN_PROGRESS: 3 (0), BY_SPOTIFY: 4 (0), BY_YOU: 5 (0), DOWNLOADED: 231 (0), ARTIST: 237 (0), ALBUM: 238 (0), BOOK: 239 (0), SHOW: 240 (0), PLAYLIST: 241 (0),
15:27:22.103 5 [/f:115 ] [spotify:playlist:37i9dQZF1EYkqdzj48dyYq] Creating context
15:27:22.103 5 [/f:168 ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:37i9dQZF1EYkqdzj48dyYq)
15:27:22.105 I [/f:185 ] mdata: aedb70a0: Task from `playlist` finished. -1/-1 entity URIs requested/needed (-0.0% cached). -1/-1 extensions requested/needed (100.0% w/ ETags, -0.0% cached). 0 unfinished task(s).
15:27:22.105 3 [/f:436 ] [spotify:playlist:37i9dQZF1EYkqdzj48dyYq] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000)
15:27:22.108 I [/f:424 ] usable_state: process_start:0,app_init:27,win_cef_initialized:115,win_os_inits:116,win_bs_built:150,core_init:151,core_init_done:193,initial_ap_connection:196,zlink_load_start:301,initial_ap_connection_done:760,usable_state:909
15:27:22.165 I [/f:460 ] High request latency: https://spclient.wg.spotify.com/clientsettings took 428 ms
15:27:22.168 5 [/f:278 ] [spotify:playlist:37i9dQZF1EYkqdzj48dyYq] 'GET' request complete
15:27:22.168 3 [/f:698 ] [spotify:playlist:37i9dQZF1EYkqdzj48dyYq] synchronization error: Error category: http, code 404 (404 - not found)
15:27:22.168 3 [/f:732 ] [spotify:playlist:37i9dQZF1EYkqdzj48dyYq] - list does not exist.
15:27:23.251 5 [/f:115 ] [spotify:playlist:37i9dQZF1DX1Mj6nQRkc5p] Creating context
15:27:23.251 5 [/f:168 ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:37i9dQZF1DX1Mj6nQRkc5p)
15:27:23.251 5 [/f:115 ] [spotify:playlist:37i9dQZEVXbMDoHDwVN2tF] Creating context
15:27:23.251 5 [/f:168 ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:37i9dQZEVXbMDoHDwVN2tF)
15:27:23.266 I [/f:185 ] mdata: 7d1a4876: Task from `recently_played` finished. 0/17 entity URIs requested/needed (100.0% cached). 0/17 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 2 unfinished task(s).
15:27:23.268 I [/f:185 ] mdata: 8257471: Task from `recently_played` finished. 0/19 entity URIs requested/needed (100.0% cached). 0/19 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 1 unfinished task(s).
15:27:23.268 I [/f:185 ] mdata: d2592a75: Task from `recently_played` finished. 0/3 entity URIs requested/needed (100.0% cached). 0/3 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 0 unfinished task(s).
15:27:23.270 5 [/f:168 ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:37i9dQZF1DX1Mj6nQRkc5p)
15:27:23.270 5 [/f:168 ] PlaylistBackendManager::resyncDelayedPlaylistNow(spotify:playlist:37i9dQZEVXbMDoHDwVN2tF)
15:27:23.274 3 [/f:436 ] [spotify:playlist:37i9dQZF1DX1Mj6nQRkc5p] Synchronization starting: DIFF (from revision 1660110405,0000000010a608e6ca2d347cc9f2a7a4b37ffd8b)
15:27:23.277 3 [/f:436 ] [spotify:playlist:37i9dQZEVXbMDoHDwVN2tF] Synchronization starting: DIFF (from revision 733159200,000000000000000000000000000000000000656e)
15:27:23.291 I [/f:185 ] mdata: f3fad82e: Task from `recently_played` finished. 0/17 entity URIs requested/needed (100.0% cached). 0/17 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 2 unfinished task(s).
15:27:23.292 I [/f:185 ] mdata: d51f4b19: Task from `recently_played` finished. 0/19 entity URIs requested/needed (100.0% cached). 0/19 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 1 unfinished task(s).
15:27:23.292 I [/f:185 ] mdata: 955bfcf9: Task from `recently_played` finished. 0/3 entity URIs requested/needed (100.0% cached). 0/3 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 0 unfinished task(s).
15:27:23.307 I [/f:185 ] mdata: b2928066: Task from `recently_played` finished. 0/17 entity URIs requested/needed (100.0% cached). 0/17 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 2 unfinished task(s).
15:27:23.309 I [/f:185 ] mdata: 5afb05ba: Task from `recently_played` finished. 0/19 entity URIs requested/needed (100.0% cached). 0/19 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 1 unfinished task(s).
15:27:23.309 I [/f:185 ] mdata: dc1aafbc: Task from `recently_played` finished. 0/3 entity URIs requested/needed (100.0% cached). 0/3 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 0 unfinished task(s).
15:27:23.380 5 [/f:278 ] [spotify:playlist:37i9dQZEVXbMDoHDwVN2tF] 'DIFF' request complete
15:27:23.380 3 [/f:1127 ] [spotify:playlist:37i9dQZEVXbMDoHDwVN2tF] Delta-updated to revision 733159200,000000000000000000000000000000000000656e (no new changes)
15:27:23.386 5 [/f:278 ] [spotify:playlist:37i9dQZF1DX1Mj6nQRkc5p] 'DIFF' request complete
15:27:23.386 3 [/f:1127 ] [spotify:playlist:37i9dQZF1DX1Mj6nQRkc5p] Delta-updated to revision 1660110405,0000000010a608e6ca2d347cc9f2a7a4b37ffd8b (no new changes)
15:27:23.401 3 [/f:436 ] [spotify:playlist:37i9dQZF1DX1Mj6nQRkc5p] Synchronization starting: DIFF (from revision 1660110405,0000000010a608e6ca2d347cc9f2a7a4b37ffd8b)
15:27:23.484 5 [/f:278 ] [spotify:playlist:37i9dQZF1DX1Mj6nQRkc5p] 'DIFF' request complete
15:27:23.484 3 [/f:1127 ] [spotify:playlist:37i9dQZF1DX1Mj6nQRkc5p] Delta-updated to revision 1660110405,0000000010a608e6ca2d347cc9f2a7a4b37ffd8b (no new changes)
15:27:31.481 I [/f:965 ] Enumerated files in cache. Permanent: keep 15 files, 126148 bytes; remove 2 files, 5158 bytes. Volatile: keep 436 files, 664467855 bytes; remove 0 files, 0 bytes.
15:27:31.484 I [/f:697 ] Deleted 2 files from storage. Time spent: 0 ms. (files that was scheduled for delayed wipe)
15:27:31.484 I [/f:699 ] 1 in pl4 realm
15:27:31.484 I [/f:703 ] 1 in unknown realm
15:27:31.485 I [/f:2804 ] Storage has been cleaned
15:27:31.557 I [/f:301 ] Found 0 collection items out of 157 checked that need to be indexed (2ms).
15:27:31.557 I [/f:303 ] Completed a full check of the collection index.
15:27:31.557 I [/f:301 ] Found 0 collection items out of 0 checked that need to be indexed (0ms).
15:27:31.557 I [/f:303 ] Completed a full check of the collection index.
15:27:31.557 I [/f:301 ] Found 0 collection items out of 10 checked that need to be indexed (0ms).
15:27:31.557 I [/f:303 ] Completed a full check of the collection index.
15:27:43.083 I [/f:185 ] mdata: 9fb3a004: Task from `unknown` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 0 unfinished task(s).
15:27:51.467 I [/f:2376 ] Pruning unused resources
15:27:51.467 I [/f:2531 ] Offline manager prune stats:
15:27:51.467 I [/f:2532 ] 0 files locked in storage
15:27:51.467 I [/f:2533 ] 0 files unlocked in storage
15:27:51.467 I [/f:2534 ] 0 track keys in store
15:27:51.467 I [/f:2535 ] 0 episode keys in store
15:27:51.467 I [/f:2537 ] 0 track keys removed
15:27:51.467 I [/f:2538 ] 0 track keys moved to cache
15:27:51.467 I [/f:2539 ] 0 episode keys removed
15:27:51.467 I [/f:2540 ] 0 episode keys moved to cache
15:27:51.467 I [/f:2541 ] 0 keys removed from cache
15:27:51.467 I [/f:2542 ] 55 keys left in cache
15:27:54.468 I [/f:205 ] ConcurrentCollector ran mark cycle for 16.014ms, total: 16.014ms.
15:27:55.774 3 [/f:436 ] [spotify:playlist:37i9dQZF1EYkqdzj48dyYq] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000)
15:27:55.837 5 [/f:278 ] [spotify:playlist:37i9dQZF1EYkqdzj48dyYq] 'GET' request complete
15:27:55.837 3 [/f:698 ] [spotify:playlist:37i9dQZF1EYkqdzj48dyYq] synchronization error: Error category: http, code 404 (404 - not found)
15:27:55.837 3 [/f:732 ] [spotify:playlist:37i9dQZF1EYkqdzj48dyYq] - list does not exist.
15:28:03.149 I [/f:310 ] play, context uri: spotify:album:7bN4OM5mtWq0UrAxdN6qMC, url: context://spotify:album:7bN4OM5mtWq0UrAxdN6qMC
15:28:03.176 D [/connect_onboarding:262 ] Onboarding::evaluateOnboarding bailing, local=0, track=0, within=1
15:28:03.259 I [/f:141 ] parseLoadedContext done: 200 context://spotify:album:7bN4OM5mtWq0UrAxdN6qMC (1 tracks)
15:28:03.276 I [/f:615 ] Open playback of track (playback_id 8a3c03bade157dcd679594c740c1f52f, parent_playback_id 00000000000000000000000000000000)
15:28:03.276 I [/f:259 ] Creating track player for track (playback_id 8a3c03bade157dcd679594c740c1f52f)
15:28:03.276 I [/f:261 ] track_uri: spotify:track:373gDROnujxNTFa1FojYIl
15:28:03.276 I [/f:263 ] ad: no
15:28:03.276 I [/f:264 ] speed: 1.000000
15:28:03.276 I [/f:265 ] normalization: track
15:28:03.276 I [/f:266 ] stream_type: default
15:28:03.276 I [/f:267 ] urgency: now
15:28:03.276 I [/f:268 ] track_select_flag: 0
15:28:03.277 I [/f:498 ] Dispatch metadata extensions request (retry count: 0)
15:28:03.277 I [/f:296 ] Open track player (playback_id 8a3c03bade157dcd679594c740c1f52f)
15:28:03.277 I [/f:297 ] position: 0 ms
15:28:03.277 I [/f:298 ] paused: false
15:28:03.277 I [/f:299 ] transition: none
15:28:03.277 I [/f:950 ] spotify::audio_track_player::AudioSessionImpl::getStreamReportingHolderToResumeWith - nothing to resume
15:28:03.277 I [/f:416 ] spotify::audio_track_player::AudioSessionImpl::openTrackPlayer - did receive StreamReportingHolder to resume with: FALSE
15:28:03.278 I [/f:468 ] Playing music set to 'true'
15:28:03.278 I [/f:192 ] Scheduling emission of signal.
15:28:03.290 I [/f:168 ] Emitting signal.
15:28:03.291 I [/connect:383 ] Updated connection type from -1 to -1
15:28:03.291 I [/f:185 ] mdata: 795d4bcf: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 43 unfinished task(s).
15:28:03.291 I [/f:185 ] mdata: 4cc3dcd: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 42 unfinished task(s).
15:28:03.291 I [/f:185 ] mdata: 64f2e07b: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 41 unfinished task(s).
15:28:03.291 I [/f:185 ] mdata: 756685a9: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 40 unfinished task(s).
15:28:03.291 I [/f:185 ] mdata: db4a15c5: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 39 unfinished task(s).
15:28:03.291 I [/f:185 ] mdata: 1cfc8765: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 38 unfinished task(s).
15:28:03.291 I [/f:185 ] mdata: f56551ac: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 37 unfinished task(s).
15:28:03.291 I [/f:185 ] mdata: f51ecb8b: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 36 unfinished task(s).
15:28:03.291 I [/f:185 ] mdata: 7dbbf6f9: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 35 unfinished task(s).
15:28:03.291 I [/f:185 ] mdata: 26f647a5: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 34 unfinished task(s).
15:28:03.291 I [/f:185 ] mdata: 2f7b3c3d: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 33 unfinished task(s).
15:28:03.291 I [/f:185 ] mdata: b2dfed6a: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 32 unfinished task(s).
15:28:03.291 I [/f:185 ] mdata: 27afed90: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 31 unfinished task(s).
15:28:03.292 I [/f:185 ] mdata: da4882a9: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 30 unfinished task(s).
15:28:03.292 I [/f:185 ] mdata: 2bfd27f7: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 29 unfinished task(s).
15:28:03.292 I [/f:185 ] mdata: 3923f3c5: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 28 unfinished task(s).
15:28:03.292 I [/f:185 ] mdata: 244f564c: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 27 unfinished task(s).
15:28:03.292 I [/f:185 ] mdata: 9a37b854: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 26 unfinished task(s).
15:28:03.292 I [/f:185 ] mdata: bca8192f: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 25 unfinished task(s).
15:28:03.292 I [/f:185 ] mdata: e30282c5: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 24 unfinished task(s).
15:28:03.292 I [/f:185 ] mdata: d0747535: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 23 unfinished task(s).
15:28:03.292 I [/f:185 ] mdata: 3ed6ac76: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 22 unfinished task(s).
15:28:03.292 I [/f:185 ] mdata: 551655ff: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 21 unfinished task(s).
15:28:03.292 I [/f:185 ] mdata: f86b1fb4: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 20 unfinished task(s).
15:28:03.292 I [/f:185 ] mdata: 5181d9ff: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 19 unfinished task(s).
15:28:03.292 I [/f:185 ] mdata: b481c152: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 18 unfinished task(s).
15:28:03.292 I [/f:185 ] mdata: dc023845: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 17 unfinished task(s).
15:28:03.292 I [/f:185 ] mdata: 269a0d03: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 16 unfinished task(s).
15:28:03.293 I [/f:185 ] mdata: 5734b0ac: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 15 unfinished task(s).
15:28:03.293 I [/f:185 ] mdata: 82725f95: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 14 unfinished task(s).
15:28:03.293 I [/f:185 ] mdata: 7a907331: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 13 unfinished task(s).
15:28:03.293 I [/f:185 ] mdata: c12f1bf9: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 12 unfinished task(s).
15:28:03.293 I [/f:185 ] mdata: b7ccdfeb: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 11 unfinished task(s).
15:28:03.293 I [/f:185 ] mdata: 227f2525: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 10 unfinished task(s).
15:28:03.293 I [/f:185 ] mdata: 34f25b7c: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 9 unfinished task(s).
15:28:03.293 I [/f:185 ] mdata: fbb8f491: Task from `legacy_track_info_provider` finished. 0/1 entity URIs requested/needed (100.0% cached). 0/1 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 8 unfinished task(s).
15:28:03.306 I [/f:185 ] mdata: 132ec29f: Task from `recently_played` finished. 0/17 entity URIs requested/needed (100.0% cached). 0/17 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 7 unfinished task(s).
15:28:03.308 I [/f:185 ] mdata: 880ead7: Task from `recently_played` finished. 0/19 entity URIs requested/needed (100.0% cached). 0/19 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 6 unfinished task(s).
15:28:03.308 I [/f:185 ] mdata: 5b674722: Task from `recently_played` finished. 0/3 entity URIs requested/needed (100.0% cached). 0/3 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 5 unfinished task(s).
15:28:03.386 I [/f:185 ] mdata: 3652da88: Task from `legacy_track_info_provider` finished. 1/1 entity URIs requested/needed (0.0% cached). 1/1 extensions requested/needed (100.0% w/ ETags, 0.0% cached). 4 unfinished task(s).
15:28:03.395 I [/f:185 ] mdata: e77da6ec: Task from `context_mdata` 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).
15:28:03.405 I [/f:185 ] mdata: 88d4fbc6: Task from `legacy_track_info_provider` finished. 1/1 entity URIs requested/needed (0.0% cached). 1/1 extensions requested/needed (100.0% w/ ETags, 0.0% cached). 2 unfinished task(s).
15:28:03.405 I [/f:185 ] mdata: 1e3c6cb2: Task from `prefetch` 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).
15:28:03.405 I [/f:185 ] mdata: c6580cca: Task from `audio_track_player` 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).
15:28:03.405 I [/f:560 ] Metadata extensions request successful
15:28:03.405 I [/f:278 ] Created driver (1F30D320), paused by default
15:28:03.405 I [/f:105 ] karaoke: Disabled
15:28:03.406 I [/f:406 ] Entry (1F43AE78) is buffering
15:28:03.406 I [/f:349 ] Created entry (1F43AE78) for player in AudioPlayerQueue
15:28:03.406 I [/f:350 ] stream type: default
15:28:03.406 I [/f:351 ] paused: false
15:28:03.406 I [/f:352 ] transition: none
15:28:03.406 I [/f:353 ] fade in start: -1 ms
15:28:03.406 I [/f:354 ] fade in duration: -1 ms
15:28:03.406 I [/f:355 ] fade out start: -1 ms
15:28:03.406 I [/f:356 ] fade out duration: -1 ms
15:28:03.407 I [/f:195 ] Requesting head file from CDN url: https://heads-fa.scdn.co/head/26709d3e13f8aa363519f9d84475bcfd5dc49038
15:28:03.407 I [/f:969 ] Unpause driver (1F30D320)
15:28:03.480 I [/f:789 ] Opening driver (1F30D320)
15:28:03.480 I [/f:790 ] sample rate: 44100 Hz
15:28:03.480 I [/f:791 ] channels: 2
15:28:03.480 I [/f:792 ] sample format: 32-bit-float-pcm
15:28:03.480 I [/f:793 ] stream type: default
15:28:03.480 I [/f:794 ] audio content type: music
15:28:03.480 I [/f:795 ] paused: false
15:28:03.480 I [/f:287 ] Opening sink for playback.
15:28:03.480 I [/f:288 ] codec: vorbis
15:28:03.480 I [/f:289 ] bitrate: 320000 bits/s
15:28:03.480 I [/f:290 ] sample rate: 44100 Hz
15:28:03.480 I [/f:291 ] channels: 2
15:28:03.480 I [/f:292 ] sample format: 32-bit-float-pcm
15:28:03.480 I [/f:298 ] duration: 199342 ms
15:28:03.482 I [/f:237 ] Requesting data (0 -> 524288)
15:28:03.567 I [/f:614 ] Starting playback.
15:28:03.567 I [/f:616 ] min limit 4000 ms.
15:28:03.567 I [/f:617 ] compressed buffer: 82992 bytes
15:28:03.567 I [/f:619 ] decompressed buffer: 0 ms
15:28:03.567 I [/f:621 ] sink buffer: 2043 ms
15:28:03.567 I [/f:622 ] final source buffer: false
15:28:03.567 I [/f:406 ] Entry (1F43AE78) is not buffering
15:28:03.593 I [/f:529 ] Request for file 26709d3e13f8aa363519f9d84475bcfd5dc49038 complete (code: success (0))
15:28:03.593 I [/f:237 ] Requesting data (524288 -> 1048576)
15:28:03.678 I [/f:529 ] Request for file 26709d3e13f8aa363519f9d84475bcfd5dc49038 complete (code: success (0))
15:28:03.678 I [/f:237 ] Requesting data (1048576 -> 1572864)
15:28:03.709 I [/f:529 ] Request for file 26709d3e13f8aa363519f9d84475bcfd5dc49038 complete (code: success (0))
15:28:03.709 I [/f:237 ] Requesting data (1572864 -> 2097152)
15:28:03.731 I [/f:1136 ] Encrypted data 64k-latency: 104 ms. Play latency: 325 ms
15:28:03.746 I [/f:529 ] Request for file 26709d3e13f8aa363519f9d84475bcfd5dc49038 complete (code: success (0))
15:28:03.746 I [/f:237 ] Requesting data (2097152 -> 2621440)
15:28:03.789 I [/f:529 ] Request for file 26709d3e13f8aa363519f9d84475bcfd5dc49038 complete (code: success (0))
15:28:03.789 I [/f:237 ] Requesting data (2621440 -> 3145728)
15:28:03.816 I [/f:529 ] Request for file 26709d3e13f8aa363519f9d84475bcfd5dc49038 complete (code: success (0))
15:28:03.816 I [/f:237 ] Requesting data (3145728 -> 3670016)
15:28:03.841 I [/f:529 ] Request for file 26709d3e13f8aa363519f9d84475bcfd5dc49038 complete (code: success (0))
15:28:03.841 I [/f:237 ] Requesting data (3670016 -> 4194304)
15:28:03.909 I [/f:529 ] Request for file 26709d3e13f8aa363519f9d84475bcfd5dc49038 complete (code: success (0))
15:28:03.909 I [/f:237 ] Requesting data (4194304 -> 4718592)
15:28:03.944 I [/f:529 ] Request for file 26709d3e13f8aa363519f9d84475bcfd5dc49038 complete (code: success (0))
15:28:03.944 I [/f:237 ] Requesting data (4718592 -> 5242880)
15:28:03.981 I [/f:529 ] Request for file 26709d3e13f8aa363519f9d84475bcfd5dc49038 complete (code: success (0))
15:28:03.982 I [/f:237 ] Requesting data (5242880 -> 5767168)
15:28:04.006 I [/f:529 ] Request for file 26709d3e13f8aa363519f9d84475bcfd5dc49038 complete (code: success (0))
15:28:04.006 I [/f:237 ] Requesting data (5767168 -> 6291456)
15:28:04.030 I [/f:529 ] Request for file 26709d3e13f8aa363519f9d84475bcfd5dc49038 complete (code: success (0))
15:28:04.030 I [/f:237 ] Requesting data (6291456 -> 6815744)
15:28:04.055 I [/f:529 ] Request for file 26709d3e13f8aa363519f9d84475bcfd5dc49038 complete (code: success (0))
15:28:04.055 I [/f:237 ] Requesting data (6815744 -> 7340032)
15:28:04.096 I [/f:529 ] Request for file 26709d3e13f8aa363519f9d84475bcfd5dc49038 complete (code: success (0))
15:28:04.096 I [/f:237 ] Requesting data (7340032 -> 7798088)
15:28:04.132 I [/f:529 ] Request for file 26709d3e13f8aa363519f9d84475bcfd5dc49038 complete (code: success (0))
15:28:04.531 I [/f:1974 ] Update! reason: cached-key-provided, flags: 0x00
15:28:08.519 I [/f:36 ] Prefetch: key prefetched for track: spotify:track:373gDROnujxNTFa1FojYIl (file: 26709d3e13f8aa363519f9d84475bcfd5dc49038)
15:28:09.533 I [/f:1974 ] Update! reason: cached-key-provided, flags: 0x00
15:28:14.442 I [/f:773 ] Pause track (playback_id 8a3c03bade157dcd679594c740c1f52f)
15:28:14.442 I [/f:969 ] Pause driver (1F30D320)
15:28:14.443 I [/f:468 ] Playing music set to 'false'
15:28:14.443 I [/f:192 ] Scheduling emission of signal.
15:28:14.458 I [/f:168 ] Emitting signal.
15:28:14.458 I [/connect:383 ] Updated connection type from -1 to -1
15:28:24.468 I [/f:205 ] ConcurrentCollector ran mark cycle for 16.014ms, total: 32.028ms.
15:28:31.571 I [/f:301 ] Found 0 collection items out of 157 checked that need to be indexed (2ms).
15:28:31.571 I [/f:303 ] Completed a full check of the collection index.
15:28:31.571 I [/f:301 ] Found 0 collection items out of 0 checked that need to be indexed (0ms).
15:28:31.571 I [/f:303 ] Completed a full check of the collection index.
15:28:31.571 I [/f:301 ] Found 0 collection items out of 10 checked that need to be indexed (0ms).
15:28:31.571 I [/f:303 ] Completed a full check of the collection index.
15:28:37.411 I [/f:185 ] mdata: 1bc73b0e: Task from `collection` finished. 0/18 entity URIs requested/needed (100.0% cached). 0/18 extensions requested/needed (0.0% w/ ETags, 100.0% cached). 0 unfinished task(s).
15:28:42.705 I [/f:310 ] play, context uri: spotify:artist:23fqKkggKUBHNkbKtXEls4, url: context://spotify:artist:23fqKkggKUBHNkbKtXEls4
15:28:42.722 I [/f:664 ] Close track (playback_id 8a3c03bade157dcd679594c740c1f52f)
15:28:42.722 I [/f:665 ] source_end: unknown
15:28:42.722 I [/f:666 ] reason_end: endplay
15:28:42.722 I [/f:667 ] resumable_session: false
15:28:42.724 I [/f:61 ] Saved 1 log messages to 'C:\Users\ckseo\AppData\Roaming\Spotify\Users\lolzcalvin-user\log-tracked'
15:28:42.729 D [/connect_onboarding:262 ] Onboarding::evaluateOnboarding bailing, local=0, track=0, within=1
15:28:42.736 I [/f:353 ] New max active requests for job type 8: 1 (throttling at 250)
15:28:42.817 I [/f:141 ] parseLoadedContext done: 200 context://spotify:artist:23fqKkggKUBHNkbKtXEls4 (24 tracks)
15:28:42.831 I [/f:171 ] parseLoadedContextPage done: 200 hm://artistplaycontext/v1/page/spotify/album/4NavAbvW8BjjV2DFqqaZVw/km_artist (1 tracks) next_page_url =
15:28:47.675 3 [/f:436 ] [spotify:playlist:37i9dQZF1EYkqdzj48dyYq] Synchronization starting: GET (from revision 0,726f6f7400000000000000000000000000000000)
15:28:47.753 5 [/f:278 ] [spotify:playlist:37i9dQZF1EYkqdzj48dyYq] 'GET' request complete
15:28:47.753 3 [/f:698 ] [spotify:playlist:37i9dQZF1EYkqdzj48dyYq] synchronization error: Error category: http, code 404 (404 - not found)
15:28:47.753 3 [/f:732 ] [spotify:playlist:37i9dQZF1EYkqdzj48dyYq] - list does not exist.
15:28:54.462 I [/f:205 ] ConcurrentCollector ran mark cycle for 16.013ms, total: 48.041ms.
15:28:57.536 I [/f:310 ] play, context uri: spotify:artist:23fqKkggKUBHNkbKtXEls4, url: context://spotify:artist:23fqKkggKUBHNkbKtXEls4
15:28:57.557 D [/connect_onboarding:262 ] Onboarding::evaluateOnboarding bailing, local=0, track=0, within=1
15:28:57.637 I [/f:141 ] parseLoadedContext done: 200 context://spotify:artist:23fqKkggKUBHNkbKtXEls4 (24 tracks)
15:29:02.070 I [/f:310 ] play, context uri: spotify:artist:23fqKkggKUBHNkbKtXEls4, url: context://spotify:artist:23fqKkggKUBHNkbKtXEls4
15:29:02.090 D [/connect_onboarding:262 ] Onboarding::evaluateOnboarding bailing, local=0, track=0, within=1
15:29:02.162 I [/f:141 ] parseLoadedContext done: 200 context://spotify:artist:23fqKkggKUBHNkbKtXEls4 (24 tracks)
15:29:09.544 I [/f:1974 ] Update! reason: periodic, flags: 0x00
15:29:17.846 I [/f:310 ] play, context uri: spotify:artist:23fqKkggKUBHNkbKtXEls4, url: context://spotify:artist:23fqKkggKUBHNkbKtXEls4
15:29:17.866 D [/connect_onboarding:262 ] Onboarding::evaluateOnboarding bailing, local=0, track=0, within=1
15:29:17.948 I [/f:141 ] parseLoadedContext done: 200 context://spotify:artist:23fqKkggKUBHNkbKtXEls4 (24 tracks)
15:29:21.395 I [/f:310 ] play, context uri: spotify:artist:23fqKkggKUBHNkbKtXEls4, url: context://spotify:artist:23fqKkggKUBHNkbKtXEls4
15:29:21.416 D [/connect_onboarding:262 ] Onboarding::evaluateOnboarding bailing, local=0, track=0, within=1
15:29:21.500 I [/f:141 ] parseLoadedContext done: 200 context://spotify:artist:23fqKkggKUBHNkbKtXEls4 (24 tracks)
15:29:24.459 I [/f:205 ] ConcurrentCollector ran mark cycle for 16.013ms, total: 64.054ms.
15:29:31.581 I [/f:301 ] Found 0 collection items out of 157 checked that need to be indexed (2ms).
15:29:31.581 I [/f:303 ] Completed a full check of the collection index.
15:29:31.581 I [/f:301 ] Found 0 collection items out of 0 checked that need to be indexed (0ms).
15:29:31.581 I [/f:303 ] Completed a full check of the collection index.
15:29:31.581 I [/f:301 ] Found 0 collection items out of 10 checked that need to be indexed (0ms).
15:29:31.581 I [/f:303 ] Completed a full check of the collection index.
15:29:37.175 I [/f:310 ] play, context uri: spotify:artist:23fqKkggKUBHNkbKtXEls4, url: context://spotify:artist:23fqKkggKUBHNkbKtXEls4
15:29:37.196 D [/connect_onboarding:262 ] Onboarding::evaluateOnboarding bailing, local=0, track=0, within=1
15:29:37.276 I [/f:141 ] parseLoadedContext done: 200 context://spotify:artist:23fqKkggKUBHNkbKtXEls4 (24 tracks)
15:29:45.985 I [/f:310 ] play, context uri: spotify:artist:23fqKkggKUBHNkbKtXEls4, url: context://spotify:artist:23fqKkggKUBHNkbKtXEls4
15:29:46.006 D [/connect_onboarding:262 ] Onboarding::evaluateOnboarding bailing, local=0, track=0, within=1
15:29:46.088 I [/f:141 ] parseLoadedContext done: 200 context://spotify:artist:23fqKkggKUBHNkbKtXEls4 (24 tracks)
15:29:51.267 I [/f:1533 ] No product state was retrieved, unable to update
15:29:51.267 I [/f:2950 ] == Offline Report ==
15:29:51.267 I [/f:2951 ] change_reason: shutdown-report
15:29:51.267 I [/f:2952 ] num_downloaded_tracks: 0
15:29:51.267 I [/f:2953 ] total_num_tracks: 0
15:29:51.267 I [/f:2954 ] num_downloaded_episodes: 0
15:29:51.267 I [/f:2955 ] total_num_episodes: 0
15:29:51.267 I [/f:2956 ] track_limit: 0
15:29:51.267 I [/f:2957 ] track_expiry: 2592000
15:29:51.267 I [/f:2958 ] episode_limit: 0
15:29:51.267 I [/f:2959 ] episode_expiry: 2592000
15:29:51.267 I [/f:2960 ] offline_keys: 0
15:29:51.267 I [/f:2961 ] cached_keys: 56
15:29:51.267 I [/f:2962 ] total_num_links: 0
15:29:51.267 I [/f:2969 ] ====================
15:29:51.295 D [/connect_tier1:164 ] Tier1LocalVerificationListener::startAccept: error: The I/O operation has been aborted because of either a thread exit or an application request.
15:29:51.295 E [/connect_mdns:323 ] Error 'The I/O operation has been aborted because of either a thread exit or an application request.' while receiving from fe80::cd54:97fb:5ee5:6329%4
15:29:51.295 D [/connect_dial:154 ] DIAL read unicast failure: ec = 995, msg = 'The I/O operation has been aborted because of either a thread exit or an application request.', sender = 0.0.0.0, bytes transferred = 0
15:29:51.295 D [/connect_mdns:153 ] mDNS read failure: ec = 995, msg = 'The I/O operation has been aborted because of either a thread exit or an application request.', sender = fe80::cd54:97fb:5ee5:6329%4, bytes transferred = 0
15:29:51.295 E [/connect_mdns:323 ] Error 'The I/O operation has been aborted because of either a thread exit or an application request.' while receiving from fe80::20db:38bb:5076:2434%13
15:29:51.295 D [/connect_dial:154 ] DIAL read multicast failure: ec = 995, msg = 'The I/O operation has been aborted because of either a thread exit or an application request.', sender = 192.168.1.25, bytes transferred = 0
15:29:51.295 D [/connect_mdns:153 ] mDNS read failure: ec = 995, msg = 'The I/O operation has been aborted because of either a thread exit or an application request.', sender = fe80::20db:38bb:5076:2434%13, bytes transferred = 0
15:29:51.295 W [/connect_network:412 ] Socket with outbound interface 192.168.1.199 not found and not deleted
15:29:51.295 E [/connect_mdns:323 ] Error 'The I/O operation has been aborted because of either a thread exit or an application request.' while receiving from fe80::20db:38bb:5076:2434%13
15:29:51.295 W [/connect_network:412 ] Socket with outbound interface 192.168.1.199 not found and not deleted
15:29:51.295 D [/connect_mdns:153 ] mDNS read failure: ec = 995, msg = 'The I/O operation has been aborted because of either a thread exit or an application request.', sender = fe80::20db:38bb:5076:2434%13, bytes transferred = 0
15:29:51.295 E [/connect_mdns:323 ] Error 'The I/O operation has been aborted because of either a thread exit or an application request.' while receiving from 192.168.1.25
15:29:51.295 D [/connect_mdns:153 ] mDNS read failure: ec = 995, msg = 'The I/O operation has been aborted because of either a thread exit or an application request.', sender = 192.168.1.25, bytes transferred = 0
15:29:51.295 W [/connect_network:412 ] Socket with outbound interface fe80::e41:c64a:bc89:1914%4 not found and not deleted
15:29:51.295 W [/connect_network:412 ] Socket with outbound interface 2001:e68:5419:8466:9c5d:74d8:55db:b2ed not found and not deleted
15:29:51.295 W [/connect_network:412 ] Socket with outbound interface 2001:e68:5419:8466:1a67:5b7a:922c:5a48 not found and not deleted
15:29:51.295 W [/connect_network:412 ] Socket with outbound interface 192.168.1.199 not found and not deleted
15:29:51.296 D [/connect_network:208 ] WoL: Mark timer as being shutdown
15:29:51.298 I [/f:123 ] Logger currently has no transport!
15:29:51.298 I [/f:123 ] Logger currently has no transport!
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment