Jul 19 16:46:24 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 16:46:24 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 16:46:24 volumio1920x480 volumio[1440]: info: Discovery: Getting this device information Jul 19 16:46:24 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetState Jul 19 16:46:24 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:46:24 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 16:46:24 volumio1920x480 volumio[1440]: verbose: New Socket.io Connection to 192.168.1.97:3000 from 192.168.1.24 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Jul 19 16:46:24 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetState Jul 19 16:46:24 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:46:24 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 19 16:46:24 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 19 16:46:25 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 16:46:25 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 16:46:25 volumio1920x480 volumio[1440]: info: Discovery: Getting this device information Jul 19 16:46:25 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetState Jul 19 16:46:25 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:46:25 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 16:46:25 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 16:46:25 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 16:46:25 volumio1920x480 volumio[1440]: info: Discovery: Getting this device information Jul 19 16:46:25 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetState Jul 19 16:46:25 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:46:25 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 16:46:26 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:46:26+02:00" level=trace msg="sent dealer ping" Jul 19 16:46:26 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetState Jul 19 16:46:26 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:46:26 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:46:26+02:00" level=trace msg="received dealer pong" Jul 19 16:46:26 volumio1920x480 systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 19 16:46:27 volumio1920x480 sudo[26754]: volumio : unable to resolve host volumio1920x480: System error Jul 19 16:46:27 volumio1920x480 sudo[26756]: volumio : unable to resolve host volumio1920x480: System error Jul 19 16:46:27 volumio1920x480 sudo[26756]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 19 16:46:27 volumio1920x480 sudo[26756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 16:46:27 volumio1920x480 sudo[26754]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 19 16:46:27 volumio1920x480 sudo[26754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 16:46:27 volumio1920x480 sudo[26756]: pam_unix(sudo:session): session closed for user root Jul 19 16:46:27 volumio1920x480 sudo[26754]: pam_unix(sudo:session): session closed for user root Jul 19 16:46:27 volumio1920x480 volumio[1440]: verbose: New Socket.io Connection to 192.168.1.97 from 192.168.1.24 UA: Mozilla/5.0 (Linux; Android 15; CPH2581 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.68 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jul 19 16:46:27 volumio1920x480 sudo[26760]: volumio : unable to resolve host volumio1920x480: System error Jul 19 16:46:27 volumio1920x480 sudo[26760]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 19 16:46:27 volumio1920x480 sudo[26760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 16:46:27 volumio1920x480 sudo[26762]: volumio : unable to resolve host volumio1920x480: System error Jul 19 16:46:27 volumio1920x480 sudo[26760]: pam_unix(sudo:session): session closed for user root Jul 19 16:46:27 volumio1920x480 sudo[26762]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 19 16:46:27 volumio1920x480 sudo[26762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 16:46:27 volumio1920x480 sudo[26762]: pam_unix(sudo:session): session closed for user root Jul 19 16:46:27 volumio1920x480 volumio[1440]: verbose: New Socket.io Connection to 192.168.1.95 from 192.168.1.24 UA: Mozilla/5.0 (Linux; Android 15; CPH2581 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.68 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetVisibleSources Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetState Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: Received Get System Info Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: Discovery: Getting this device information Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetState Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetState Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: Listing playlists Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 19 16:46:27 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 19 16:46:28 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 19 16:46:29 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 19 16:46:29 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 19 16:46:29 volumio1920x480 volumio[1440]: info: Received Get System Info Jul 19 16:46:29 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 16:46:29 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 16:46:29 volumio1920x480 volumio[1440]: info: Discovery: Getting this device information Jul 19 16:46:29 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetState Jul 19 16:46:29 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:46:29 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 16:46:30 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 19 16:46:30 volumio1920x480 volumio[1440]: info: Received Get System Info Jul 19 16:46:30 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 16:46:30 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 16:46:30 volumio1920x480 volumio[1440]: info: Discovery: Getting this device information Jul 19 16:46:30 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetState Jul 19 16:46:30 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:46:30 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 16:46:33 volumio1920x480 volumio[1440]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 19 16:46:33 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 19 16:46:33 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 19 16:46:33 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 19 16:46:33 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jul 19 16:46:33 volumio1920x480 volumio[1440]: info: Received Get System Version Jul 19 16:46:33 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 16:46:37 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 19 16:46:37 volumio1920x480 volumio-remote-updater[887]: Test mode enabled Jul 19 16:46:37 volumio1920x480 volumio-remote-updater[887]: Alpha mode disabled Jul 19 16:46:37 volumio1920x480 volumio-remote-updater[887]: Alpha legacy test mode disabled Jul 19 16:46:38 volumio1920x480 volumio[1440]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 19 16:46:38 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 19 16:46:40 volumio1920x480 volumio[1440]: info: CoreCommandRouter::Close All Modals sent Jul 19 16:46:41 volumio1920x480 volumio-remote-updater[887]: Test mode enabled Jul 19 16:46:41 volumio1920x480 volumio-remote-updater[887]: Alpha mode disabled Jul 19 16:46:41 volumio1920x480 volumio-remote-updater[887]: Alpha legacy test mode disabled Jul 19 16:46:41 volumio1920x480 volumio[1440]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 19 16:46:41 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 19 16:46:43 volumio1920x480 volumio[1440]: info: CoreCommandRouter::Close All Modals sent Jul 19 16:46:46 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 19 16:46:46 volumio1920x480 volumio[1440]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 19 16:46:46 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 19 16:46:46 volumio1920x480 volumio[1440]: info: Received Get System Version Jul 19 16:46:46 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 16:46:46 volumio1920x480 volumio[1440]: info: Received Get System Info Jul 19 16:46:46 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 16:46:46 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 16:46:46 volumio1920x480 volumio[1440]: info: Discovery: Getting this device information Jul 19 16:46:46 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetState Jul 19 16:46:46 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:46:46 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 16:46:48 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetQueue Jul 19 16:46:48 volumio1920x480 volumio[1440]: info: CoreStateMachine::getQueue Jul 19 16:46:48 volumio1920x480 volumio[1440]: info: CorePlayQueue::getQueue Jul 19 16:46:54 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 16:46:54 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 16:46:54 volumio1920x480 volumio[1440]: info: Discovery: Getting this device information Jul 19 16:46:54 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetState Jul 19 16:46:54 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:46:54 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 16:46:56 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:46:56+02:00" level=trace msg="received accesspoint ping" Jul 19 16:46:56 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:46:56+02:00" level=trace msg="received accesspoint pong ack" Jul 19 16:46:56 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:46:56+02:00" level=trace msg="sent dealer ping" Jul 19 16:46:56 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:46:56+02:00" level=trace msg="received dealer pong" Jul 19 16:46:59 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 19 16:46:59 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 19 16:47:10 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jul 19 16:47:10 volumio1920x480 volumio[1440]: info: In handleBrowseUri, curUri=spotify Jul 19 16:47:10 volumio1920x480 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 19 16:47:10 volumio1920x480 dbus-daemon[858]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.360' (uid=0 pid=26869 comm="timedatectl show --property=NTPSynchronized --valu") Jul 19 16:47:10 volumio1920x480 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 19 16:47:10 volumio1920x480 dbus-daemon[858]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 19 16:47:10 volumio1920x480 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 19 16:47:10 volumio1920x480 setdatetime-helper.sh[26868]: Time is already synchronized. Jul 19 16:47:10 volumio1920x480 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 19 16:47:10 volumio1920x480 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 19 16:47:11 volumio1920x480 volumio[1440]: info: New access token = BQDORYXLEKRnof2IuPPzjZBgJtmEKaY7H7a1lk4ceJCxSZ7LVpSqlmBrKJVxGjFjLnAlAAcENjpRQQnUq4Hs2D32JJk2nTuz_3zcYHRDG8hPsrSyFdJueYSadB27oBdgBa87CHcmqbvfebNxeKgPfsLTbFhoBphr-Bzl39z4FZuT804ek_kGl6nI-MZriC-tuAsEf1fbwbZwLjQ1bNttfrQ-C5S06ZGF58fX-iX9tqANS51JC8vL6Tu3O9XW-Waz19apQdaacv4cmUi_BuPpuHxoxX2nXRAb10XGTd-2vFH--OidyE4 Jul 19 16:47:11 volumio1920x480 volumio[1440]: info: New access token = BQBAb93VW6AQ-xhAV5FD7e-L_DAh2dncbhI94MxIepnTUcUbEr_gx3qwgVHKlUcnypo3R48_vaK_hxlIn1kN6LkZLgl255DnPKs1QKl0kETXM5c5IduduTZCt7tQo2ZFZnbiwmdeBiKJh22Opo27k0XJqUcDo0lvfLhGcs_EcMjs1ppTRSSaPJfkqUm6wDLoXF_0srKLpi4Y0eiLcxMq0sGtaLbF1No1M6rmrgTjZvx4fotjFIR7_O4brqiL2B_Fbnc25vtjlaG-8pQV26vdbAx1SZ0Ew1kLKJXkCfPhQFnh3_a_8cw Jul 19 16:47:11 volumio1920x480 volumio[1440]: info: New access token = BQCsdCrcWXdA4FBQVvSduETaoLPu08qzJGsVNqdpoSM4KPnMXJNSgTAmQk8xQVGKEiM3L1uSyi3lfOnwDThAkGs7dp1ZbzrxCtS8DxrOI5XNeXL_6EahqZSB0Y8W50SGQZGgzkIA39fBAcTHt-HGf_gNFZyS8PktJlpZl89j49bdYyMzigCzrbQfRyPC5RnppjG-R8PAHn26J7VWh-4vtvlG7pu-AScul_kpSCEPys76iQsg0yDp6_kOYFvao88OGTs2A38NHg2RIM4r7xZMEoz79VfsnOyloa349m2ZqVlwrx5-l60 Jul 19 16:47:11 volumio1920x480 volumio[1440]: info: Preload queue cleared Jul 19 16:47:11 volumio1920x480 volumio[1440]: info: Preload queue cleared Jul 19 16:47:11 volumio1920x480 volumio[1440]: info: Preload queue cleared Jul 19 16:47:11 volumio1920x480 volumio[1440]: info: Preload queue cleared Jul 19 16:47:13 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jul 19 16:47:13 volumio1920x480 volumio[1440]: info: In handleBrowseUri, curUri=spotify/mytracks Jul 19 16:47:13 volumio1920x480 volumio[1440]: info: Preload queue cleared Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: Preload queue cleared Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CoreStateMachine::ClearQueue Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CoreStateMachine::stop Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CorePlayQueue::clearPlayQueue Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CorePlayQueue::saveQueue Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioPushQueue Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CoreStateMachine::addQueueItems Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CorePlayQueue::addQueueItems Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: Preload queue cleared Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: Adding Item to queue: spotify:playlist:37i9dQZF1DXbTop77dnX35 Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: Exploding uri spotify:playlist:37i9dQZF1DXbTop77dnX35 in service spop Jul 19 16:47:20 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:playlist:37i9dQZF1DXbTop77dnX35 Jul 19 16:47:20 volumio1920x480 volumio[1440]: error: An error occurred while exploding listing Spotify playlist tracks TypeError: Cannot read properties of null (reading 'name') Jul 19 16:47:20 volumio1920x480 volumio[1440]: error: Commandrouter: Cannot explode uri spotify:playlist:37i9dQZF1DXbTop77dnX35 from service spop: TypeError: Cannot read properties of null (reading 'name') Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioPushQueue Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CorePlayQueue::saveQueue Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CoreStateMachine::updateTrackBlock Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrackBlock Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetState Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioPlay Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CoreStateMachine::play index 0 Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CoreStateMachine::stop Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CoreStateMachine::play index undefined Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 16:47:20 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:47:23 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jul 19 16:47:23 volumio1920x480 volumio[1440]: info: In handleBrowseUri, curUri=spotify:playlist:37i9dQZF1DXbTop77dnX35 Jul 19 16:47:23 volumio1920x480 volumio[1440]: error: An error occurred while exploding listing Spotify playlist tracks TypeError: Cannot read properties of null (reading 'name') Jul 19 16:47:24 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 16:47:24 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 16:47:24 volumio1920x480 volumio[1440]: info: Discovery: Getting this device information Jul 19 16:47:24 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetState Jul 19 16:47:24 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:47:24 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 16:47:26 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:26+02:00" level=trace msg="sent dealer ping" Jul 19 16:47:26 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:26+02:00" level=trace msg="received dealer pong" Jul 19 16:47:28 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jul 19 16:47:28 volumio1920x480 volumio[1440]: info: In handleBrowseUri, curUri=spotify:playlist:37i9dQZF1DX1GHMwBE32Ob Jul 19 16:47:28 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jul 19 16:47:28 volumio1920x480 volumio[1440]: info: In handleBrowseUri, curUri=spotify:playlist:37i9dQZF1DX1GHMwBE32Ob Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preload queue cleared Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:3s4WNh0WnfhwvIlrp6RTBj Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:6fXDXIPwX97mqCrqLFAt2p Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:6x1R2hxbuFu8SfwnfuEddZ Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:0lhLSwvRKGHfIcVEz3uQK6 Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:3w0eFAME3IxlonearUIaXJ Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:1rTtAqU8YnDyG86nuwuXyx Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:75NaRkat24l3uKOyfymyzJ Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:5G5R93fhTnLMXVcoIO6QNa Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:52ZxqHSe1HesJPwuuBiW4U Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:07eJSSPcNlkR34wVlkrCcY Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:3ZR7IlqBPK8TrJyZYfFX9L Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:4XHrQHvHWlE0RldeG8Nslq Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:1xFkc8KXwVk1FoG5xhcU3H Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:5hphSVebVxTpDfrk09W0hS Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:10y7iW3rc8bzGbhAC3mNPA Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:1Ss5QjczHsdYkvNsiBmoqS Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:3H2bLX6U4kNuv04iTkCrSp Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:7ySC0IjVS1PMEdsZOvsUK2 Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:2hODYSmDyWMu2C2XN4bgOV Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:2phrffYY0zzvRTnMiy48tb Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:4xVj25uTjTZCaHbSFbYwAE Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:4FMfmvlC2DpXIQmpYpcdlC Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:06ANwmMgiOcgBtaxFiPuKk Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:1oFabnkoJ01ksmSmyjELDl Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:1N8G6PUj14v0VG8evbRSxp Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:0WheF6yGnxE4gzGg8ScAji Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:6XBIOtdSL47qn201KKNCA0 Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:2qdwZPTNCHb8DXfQin53WK Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:6XEv0xIrDhwolBuzju8v5y Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:0CbmiJiGy6sN4RJ75mJthR Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:0syyMKF0pQ8psk6aPNTh7N Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:1YsGGtMNVuyvlq2enrwL35 Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:6EtiudQ17HCJ0gLgrCo9Xi Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:09ISd5Xw3QMvxKhvNj5tIC Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:2v7r3nItoWRbCQBS1qFQnD Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:1FZuvcRdERs7U3M4W2UX5Q Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:6LTnqcYe8dq4QC5Lh7vneM Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:2WHM3orrvvqziSkG0xp8sy Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:4otA4JHWkokv4qAj1Pp2U5 Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:1HBWK4lSLU6b4MjeK5OA2B Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:2DRHfIWD9Mg70QgTxAKn4m Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:3eWpfsYgd5OL2QdwcVcF6Q Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:2JtrWZrpNkbSEzG5m9phAV Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:595jJM2sqdHwv1kvcZUHTl Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:7fJofn7Y6Uo7g8S4JRNr3m Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:4rPaeHBlWY8MIruSNe5yZl Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:0WeU5EfNFu1wmlmCdMNDEJ Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:5rFZHAhav5kGsSAKtQHRHq Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:1uBZovogMw6DSL2DkNOwnX Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:0btl8LkqkQNo4NxmpBoP8a Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:3yLREZjJFnYLAN4aOUqqP6 Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:19FyHGQSvrg8H88eaKvUQI Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:6bBQmflDHD8a35PeZjw44J Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:6al03F4hh8LUPRMcnrM3H5 Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:6mV6zxfQetBvsAwz6AWxtH Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:5GX1wsJfvSXUobjDJgrVBD Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:780BUxpCmW9vOVYZsqdLLE Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:7vdGiPrjK0gJBoZE8UhDMJ Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:3mARtPEP8hi1LzMctEwVN4 Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:6FZlSfQrD9aoB4SSnoAzWk Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:3s4WNh0WnfhwvIlrp6RTBj in service spop Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:3s4WNh0WnfhwvIlrp6RTBj Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:6fXDXIPwX97mqCrqLFAt2p in service spop Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:6fXDXIPwX97mqCrqLFAt2p Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:6x1R2hxbuFu8SfwnfuEddZ in service spop Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:6x1R2hxbuFu8SfwnfuEddZ Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:0lhLSwvRKGHfIcVEz3uQK6 in service spop Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:0lhLSwvRKGHfIcVEz3uQK6 Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3s4WNh0WnfhwvIlrp6RTBj","service":"spop","name":"ZAAZAA","artist":"Frenna","album":"ZAAZAA","type":"song","duration":156,"albumart":"https://i.scdn.co/image/ab67616d0000b273b42e403365ae43b13e89b215","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6fXDXIPwX97mqCrqLFAt2p","service":"spop","name":"Love","artist":"Burna Boy","album":"No Sign Of Weakness","type":"song","duration":176,"albumart":"https://i.scdn.co/image/ab67616d0000b273b65dc01df8113649f354244e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:3w0eFAME3IxlonearUIaXJ in service spop Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:3w0eFAME3IxlonearUIaXJ Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6x1R2hxbuFu8SfwnfuEddZ","service":"spop","name":"Happy Day","artist":"Zlatan","album":"Happy Day","type":"song","duration":165,"albumart":"https://i.scdn.co/image/ab67616d0000b2732ddae02dfa21b48a5b5e813d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:1rTtAqU8YnDyG86nuwuXyx in service spop Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:1rTtAqU8YnDyG86nuwuXyx Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0lhLSwvRKGHfIcVEz3uQK6","service":"spop","name":"Laho","artist":"Shallipopi","album":"Laho","type":"song","duration":144,"albumart":"https://i.scdn.co/image/ab67616d0000b273c3a6a0f6939149124d524072","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:75NaRkat24l3uKOyfymyzJ in service spop Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:75NaRkat24l3uKOyfymyzJ Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3w0eFAME3IxlonearUIaXJ","service":"spop","name":"Ewo","artist":"Famous Pluto","album":"Uzama The 3rd","type":"song","duration":180,"albumart":"https://i.scdn.co/image/ab67616d0000b273853547dbbc7c850f4ba5726c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:5G5R93fhTnLMXVcoIO6QNa in service spop Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:5G5R93fhTnLMXVcoIO6QNa Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1rTtAqU8YnDyG86nuwuXyx","service":"spop","name":"E DON STILL ENTER","artist":"Rexxie","album":"E DON STILL ENTER","type":"song","duration":165,"albumart":"https://i.scdn.co/image/ab67616d0000b27349ee9a6172eb88c2b18b95b9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:52ZxqHSe1HesJPwuuBiW4U in service spop Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:52ZxqHSe1HesJPwuuBiW4U Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:75NaRkat24l3uKOyfymyzJ","service":"spop","name":"Free","artist":"Olamide","album":"Olamidé","type":"song","duration":211,"albumart":"https://i.scdn.co/image/ab67616d0000b2734de99a77f4f4446a4bd0f155","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:07eJSSPcNlkR34wVlkrCcY in service spop Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:07eJSSPcNlkR34wVlkrCcY Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:3ZR7IlqBPK8TrJyZYfFX9L in service spop Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:3ZR7IlqBPK8TrJyZYfFX9L Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5G5R93fhTnLMXVcoIO6QNa","service":"spop","name":"Mortal Kombat","artist":"Muyeez","album":"Mortal Kombat","type":"song","duration":121,"albumart":"https://i.scdn.co/image/ab67616d0000b273aac7c44e22ac24e41227cc0a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:4XHrQHvHWlE0RldeG8Nslq in service spop Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:4XHrQHvHWlE0RldeG8Nslq Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:52ZxqHSe1HesJPwuuBiW4U","service":"spop","name":"MARIO KART","artist":"Seyi Vibez","album":"Children of Africa","type":"song","duration":171,"albumart":"https://i.scdn.co/image/ab67616d0000b27397ce44b0f04256f8206eab4d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:1xFkc8KXwVk1FoG5xhcU3H in service spop Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:1xFkc8KXwVk1FoG5xhcU3H Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preload queue cleared Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:5hphSVebVxTpDfrk09W0hS Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:10y7iW3rc8bzGbhAC3mNPA Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:1Ss5QjczHsdYkvNsiBmoqS Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:3H2bLX6U4kNuv04iTkCrSp Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:7ySC0IjVS1PMEdsZOvsUK2 Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:2hODYSmDyWMu2C2XN4bgOV Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:2phrffYY0zzvRTnMiy48tb Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:4xVj25uTjTZCaHbSFbYwAE Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:4FMfmvlC2DpXIQmpYpcdlC Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:06ANwmMgiOcgBtaxFiPuKk Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:1oFabnkoJ01ksmSmyjELDl Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:1N8G6PUj14v0VG8evbRSxp Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:0WheF6yGnxE4gzGg8ScAji Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:6XBIOtdSL47qn201KKNCA0 Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:2qdwZPTNCHb8DXfQin53WK Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:6XEv0xIrDhwolBuzju8v5y Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:0CbmiJiGy6sN4RJ75mJthR Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:0syyMKF0pQ8psk6aPNTh7N Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:1YsGGtMNVuyvlq2enrwL35 Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:6EtiudQ17HCJ0gLgrCo9Xi Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:09ISd5Xw3QMvxKhvNj5tIC Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:2v7r3nItoWRbCQBS1qFQnD Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:1FZuvcRdERs7U3M4W2UX5Q Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:6LTnqcYe8dq4QC5Lh7vneM Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:2WHM3orrvvqziSkG0xp8sy Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:4otA4JHWkokv4qAj1Pp2U5 Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:1HBWK4lSLU6b4MjeK5OA2B Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:2DRHfIWD9Mg70QgTxAKn4m Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:3eWpfsYgd5OL2QdwcVcF6Q Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:2JtrWZrpNkbSEzG5m9phAV Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:595jJM2sqdHwv1kvcZUHTl Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:7fJofn7Y6Uo7g8S4JRNr3m Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:4rPaeHBlWY8MIruSNe5yZl Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:0WeU5EfNFu1wmlmCdMNDEJ Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:5rFZHAhav5kGsSAKtQHRHq Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:1uBZovogMw6DSL2DkNOwnX Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:0btl8LkqkQNo4NxmpBoP8a Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:3yLREZjJFnYLAN4aOUqqP6 Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:19FyHGQSvrg8H88eaKvUQI Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:6bBQmflDHD8a35PeZjw44J Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:6al03F4hh8LUPRMcnrM3H5 Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:6mV6zxfQetBvsAwz6AWxtH Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:5GX1wsJfvSXUobjDJgrVBD Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:780BUxpCmW9vOVYZsqdLLE Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:7vdGiPrjK0gJBoZE8UhDMJ Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:3mARtPEP8hi1LzMctEwVN4 Jul 19 16:47:29 volumio1920x480 volumio[1440]: info: Preloading song: spotify:track:6FZlSfQrD9aoB4SSnoAzWk Jul 19 16:47:29 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:07eJSSPcNlkR34wVlkrCcY","service":"spop","name":"Dangbana Riddim","artist":"Bella Shmurda","album":"Dangbana Riddim","type":"song","duration":160,"albumart":"https://i.scdn.co/image/ab67616d0000b273b71bce354fa7597ecb0ae895","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3ZR7IlqBPK8TrJyZYfFX9L","service":"spop","name":"who does that? (feat. Bella Shmurda)","artist":"FOLA","album":"what a feeling","type":"song","duration":159,"albumart":"https://i.scdn.co/image/ab67616d0000b273b256f5717dc8316248adb981","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:5hphSVebVxTpDfrk09W0hS in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:5hphSVebVxTpDfrk09W0hS Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4XHrQHvHWlE0RldeG8Nslq","service":"spop","name":"Na Scra","artist":"Famous Pluto","album":"Na Scra","type":"song","duration":150,"albumart":"https://i.scdn.co/image/ab67616d0000b273475fa235046ea8ebdb66bb4c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:10y7iW3rc8bzGbhAC3mNPA in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:10y7iW3rc8bzGbhAC3mNPA Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1xFkc8KXwVk1FoG5xhcU3H","service":"spop","name":"See Me See God (SMSG)","artist":"Barry Jhay","album":"Barrystar Vol. 1","type":"song","duration":192,"albumart":"https://i.scdn.co/image/ab67616d0000b2733cecee001fb32dac9a308c3f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:1Ss5QjczHsdYkvNsiBmoqS in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:1Ss5QjczHsdYkvNsiBmoqS Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5hphSVebVxTpDfrk09W0hS","service":"spop","name":"Doha","artist":"Seyi Vibez","album":"Loseyi Professor","type":"song","duration":164,"albumart":"https://i.scdn.co/image/ab67616d0000b273a5db6e3fd57954a028540e44","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:3H2bLX6U4kNuv04iTkCrSp in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:3H2bLX6U4kNuv04iTkCrSp Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:7ySC0IjVS1PMEdsZOvsUK2 in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:7ySC0IjVS1PMEdsZOvsUK2 Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:10y7iW3rc8bzGbhAC3mNPA","service":"spop","name":"Are you there?","artist":"Ayo Maff","album":"MAFFIAN","type":"song","duration":156,"albumart":"https://i.scdn.co/image/ab67616d0000b273ff1af2a5858b99c7eb30c638","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1Ss5QjczHsdYkvNsiBmoqS","service":"spop","name":"Instagram","artist":"Muyeez","album":"Instagram","type":"song","duration":140,"albumart":"https://i.scdn.co/image/ab67616d0000b27301392cd2b087237236cccc28","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:2hODYSmDyWMu2C2XN4bgOV in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:2hODYSmDyWMu2C2XN4bgOV Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3H2bLX6U4kNuv04iTkCrSp","service":"spop","name":"HAUSAPIANO - Remix","artist":"Kvng Vinci","album":"HAUSAPIANO (Remix)","type":"song","duration":137,"albumart":"https://i.scdn.co/image/ab67616d0000b2732e666a1b00d7ee77ea75a2af","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:2phrffYY0zzvRTnMiy48tb in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:2phrffYY0zzvRTnMiy48tb Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:4xVj25uTjTZCaHbSFbYwAE in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:4xVj25uTjTZCaHbSFbYwAE Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7ySC0IjVS1PMEdsZOvsUK2","service":"spop","name":"OZEBA","artist":"Rema","album":"HEIS","type":"song","duration":137,"albumart":"https://i.scdn.co/image/ab67616d0000b27319df46a8fa3d972ff4c84275","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2hODYSmDyWMu2C2XN4bgOV","service":"spop","name":"SHAOLIN","artist":"Seyi Vibez","album":"Children of Africa","type":"song","duration":140,"albumart":"https://i.scdn.co/image/ab67616d0000b27397ce44b0f04256f8206eab4d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:4FMfmvlC2DpXIQmpYpcdlC in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:4FMfmvlC2DpXIQmpYpcdlC Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2phrffYY0zzvRTnMiy48tb","service":"spop","name":"AKPOR - feat. ODUMODUBLVCK, Magnito & Portable","artist":"Reeplay","album":"AKPOR (feat. ODUMODUBLVCK, Magnito & Portable)","type":"song","duration":158,"albumart":"https://i.scdn.co/image/ab67616d0000b27346494fb0b4b9195447f0438d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:06ANwmMgiOcgBtaxFiPuKk in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:06ANwmMgiOcgBtaxFiPuKk Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4xVj25uTjTZCaHbSFbYwAE","service":"spop","name":"Hey Jago","artist":"Poco Lee","album":"Hey Jago","type":"song","duration":125,"albumart":"https://i.scdn.co/image/ab67616d0000b273d7cd63fb7b193edef4689308","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:1oFabnkoJ01ksmSmyjELDl in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:1oFabnkoJ01ksmSmyjELDl Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4FMfmvlC2DpXIQmpYpcdlC","service":"spop","name":"Calculate","artist":"Kidd Carder","album":"Calculate","type":"song","duration":180,"albumart":"https://i.scdn.co/image/ab67616d0000b2738750bbc21a5f081b1d43447a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:1N8G6PUj14v0VG8evbRSxp in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:1N8G6PUj14v0VG8evbRSxp Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:06ANwmMgiOcgBtaxFiPuKk","service":"spop","name":"My Amigo","artist":"Zerrydl","album":"My Amigo","type":"song","duration":160,"albumart":"https://i.scdn.co/image/ab67616d0000b273fa7a4e79e36bd7699b7f153d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:0WheF6yGnxE4gzGg8ScAji in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:0WheF6yGnxE4gzGg8ScAji Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1oFabnkoJ01ksmSmyjELDl","service":"spop","name":"Where I’m from","artist":"Tml Vibez","album":"Timileyin 2.0","type":"song","duration":159,"albumart":"https://i.scdn.co/image/ab67616d0000b2732458df61b97319ad1edc026f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:6XBIOtdSL47qn201KKNCA0 in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:6XBIOtdSL47qn201KKNCA0 Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1N8G6PUj14v0VG8evbRSxp","service":"spop","name":"Higher","artist":"Burna Boy","album":"Higher","type":"song","duration":137,"albumart":"https://i.scdn.co/image/ab67616d0000b273c4d7ed9e7abbee796aa7682f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:2qdwZPTNCHb8DXfQin53WK in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:2qdwZPTNCHb8DXfQin53WK Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0WheF6yGnxE4gzGg8ScAji","service":"spop","name":"Superfuji(GOBE)","artist":"oSHAMO","album":"Superfuji(GOBE)","type":"song","duration":232,"albumart":"https://i.scdn.co/image/ab67616d0000b273036341ba6c46228e81107a98","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:6XEv0xIrDhwolBuzju8v5y in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:6XEv0xIrDhwolBuzju8v5y Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6XBIOtdSL47qn201KKNCA0","service":"spop","name":"JUJU (feat. Shallipopi)","artist":"Smur Lee","album":"JUJU (feat. Shallipopi)","type":"song","duration":157,"albumart":"https://i.scdn.co/image/ab67616d0000b273b54e16ded272e0223b1427e0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:0CbmiJiGy6sN4RJ75mJthR in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:0CbmiJiGy6sN4RJ75mJthR Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2qdwZPTNCHb8DXfQin53WK","service":"spop","name":"Back to Back","artist":"Zerrydl","album":"Back to Back","type":"song","duration":146,"albumart":"https://i.scdn.co/image/ab67616d0000b273a1fc3feb8e0a59e1be8427ad","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:0syyMKF0pQ8psk6aPNTh7N in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:0syyMKF0pQ8psk6aPNTh7N Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6XEv0xIrDhwolBuzju8v5y","service":"spop","name":"Giveaway","artist":"Ruger","album":"BlownBoy RU","type":"song","duration":180,"albumart":"https://i.scdn.co/image/ab67616d0000b2734ca57a45f53b5fb6bdb465a2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:1YsGGtMNVuyvlq2enrwL35 in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:1YsGGtMNVuyvlq2enrwL35 Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0CbmiJiGy6sN4RJ75mJthR","service":"spop","name":"Free Service","artist":"Shallipopi","album":"Free Service","type":"song","duration":156,"albumart":"https://i.scdn.co/image/ab67616d0000b273da6f73c0ff798fdaa5fa0d15","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:30 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:6EtiudQ17HCJ0gLgrCo9Xi in service spop Jul 19 16:47:30 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:6EtiudQ17HCJ0gLgrCo9Xi Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:09ISd5Xw3QMvxKhvNj5tIC in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:09ISd5Xw3QMvxKhvNj5tIC Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1YsGGtMNVuyvlq2enrwL35","service":"spop","name":"Comforter","artist":"Llona","album":"Homeless","type":"song","duration":138,"albumart":"https://i.scdn.co/image/ab67616d0000b273498cb583fae655d485aba244","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0syyMKF0pQ8psk6aPNTh7N","service":"spop","name":"Panic","artist":"Ayo Maff","album":"Panic","type":"song","duration":152,"albumart":"https://i.scdn.co/image/ab67616d0000b273ee1b0de787635385d58c73b8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:2v7r3nItoWRbCQBS1qFQnD in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:2v7r3nItoWRbCQBS1qFQnD Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6EtiudQ17HCJ0gLgrCo9Xi","service":"spop","name":"Imagine","artist":"Niphkeys","album":"Intergalactic Dreams","type":"song","duration":124,"albumart":"https://i.scdn.co/image/ab67616d0000b273f4d45d38e8a9e97495a553ab","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:1FZuvcRdERs7U3M4W2UX5Q in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:1FZuvcRdERs7U3M4W2UX5Q Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:09ISd5Xw3QMvxKhvNj5tIC","service":"spop","name":"Pxy Drip","artist":"Naira Marley","album":"Pxy Drip","type":"song","duration":149,"albumart":"https://i.scdn.co/image/ab67616d0000b273186fe1233ee13b0d2576d90e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:6LTnqcYe8dq4QC5Lh7vneM in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:6LTnqcYe8dq4QC5Lh7vneM Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2v7r3nItoWRbCQBS1qFQnD","service":"spop","name":"Trenches Luv","artist":"T.I BLAZE","album":"Trenches Luv","type":"song","duration":205,"albumart":"https://i.scdn.co/image/ab67616d0000b273038a36ef20c22470cf98e3a2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:2WHM3orrvvqziSkG0xp8sy in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:2WHM3orrvvqziSkG0xp8sy Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1FZuvcRdERs7U3M4W2UX5Q","service":"spop","name":"Abanikanda","artist":"Zinoleesky","album":"Gen Z","type":"song","duration":207,"albumart":"https://i.scdn.co/image/ab67616d0000b273031b368082f16c71881b2b20","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:4otA4JHWkokv4qAj1Pp2U5 in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:4otA4JHWkokv4qAj1Pp2U5 Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:1HBWK4lSLU6b4MjeK5OA2B in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:1HBWK4lSLU6b4MjeK5OA2B Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6LTnqcYe8dq4QC5Lh7vneM","service":"spop","name":"Stubborn","artist":"Victony","album":"Stubborn","type":"song","duration":176,"albumart":"https://i.scdn.co/image/ab67616d0000b273b2e1c4b21d7f965149762998","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:2DRHfIWD9Mg70QgTxAKn4m in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:2DRHfIWD9Mg70QgTxAKn4m Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4otA4JHWkokv4qAj1Pp2U5","service":"spop","name":"Money Moves","artist":"Fido","album":"Money Moves","type":"song","duration":163,"albumart":"https://i.scdn.co/image/ab67616d0000b27326e7adf08abf99f498dd7b34","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2WHM3orrvvqziSkG0xp8sy","service":"spop","name":"Fuji Vibe","artist":"Asake","album":"Lungu Boy","type":"song","duration":322,"albumart":"https://i.scdn.co/image/ab67616d0000b2737c45c1c29c18a5e0b46fc117","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:3eWpfsYgd5OL2QdwcVcF6Q in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:3eWpfsYgd5OL2QdwcVcF6Q Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1HBWK4lSLU6b4MjeK5OA2B","service":"spop","name":"Hope","artist":"T.I BLAZE","album":"Shakur","type":"song","duration":164,"albumart":"https://i.scdn.co/image/ab67616d0000b273ff3b21f27cdc0e1e482ef019","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:2JtrWZrpNkbSEzG5m9phAV in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:2JtrWZrpNkbSEzG5m9phAV Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2DRHfIWD9Mg70QgTxAKn4m","service":"spop","name":"WhatsApp","artist":"Seyi Vibez","album":"WhatsApp","type":"song","duration":134,"albumart":"https://i.scdn.co/image/ab67616d0000b27323562ea7166b519afbe77134","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:595jJM2sqdHwv1kvcZUHTl in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:595jJM2sqdHwv1kvcZUHTl Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3eWpfsYgd5OL2QdwcVcF6Q","service":"spop","name":"Bust Down","artist":"Zlatan","album":"Bust Down","type":"song","duration":196,"albumart":"https://i.scdn.co/image/ab67616d0000b273773ebeedf6c29d97cb313717","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:7fJofn7Y6Uo7g8S4JRNr3m in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:7fJofn7Y6Uo7g8S4JRNr3m Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2JtrWZrpNkbSEzG5m9phAV","service":"spop","name":"Sunny Ade","artist":"Zinoleesky","album":"Sunny Ade","type":"song","duration":136,"albumart":"https://i.scdn.co/image/ab67616d0000b273975afba0a067435ea875b1cc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:4rPaeHBlWY8MIruSNe5yZl in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:4rPaeHBlWY8MIruSNe5yZl Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:595jJM2sqdHwv1kvcZUHTl","service":"spop","name":"Good Vibes","artist":"Didi B","album":"Good Vibes","type":"song","duration":176,"albumart":"https://i.scdn.co/image/ab67616d0000b273e328a027dde2396234364bf2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:0WeU5EfNFu1wmlmCdMNDEJ in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:0WeU5EfNFu1wmlmCdMNDEJ Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7fJofn7Y6Uo7g8S4JRNr3m","service":"spop","name":"W For Wetego (feat. Young Jonn, Phyno & DJ 808)","artist":"Blaqbonez","album":"W For Wetego (feat. Young Jonn, Phyno & DJ 808)","type":"song","duration":217,"albumart":"https://i.scdn.co/image/ab67616d0000b273b579ba9af18b040e8edbe659","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:5rFZHAhav5kGsSAKtQHRHq in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:5rFZHAhav5kGsSAKtQHRHq Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4rPaeHBlWY8MIruSNe5yZl","service":"spop","name":"Id.Me","artist":"Rybeena","album":"Id.Me","type":"song","duration":169,"albumart":"https://i.scdn.co/image/ab67616d0000b27304221e976c670785cbe79883","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:1uBZovogMw6DSL2DkNOwnX in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:1uBZovogMw6DSL2DkNOwnX Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0WeU5EfNFu1wmlmCdMNDEJ","service":"spop","name":"Metaverse","artist":"Olamide","album":"Ikigai / 生き甲斐, Vol. 1","type":"song","duration":179,"albumart":"https://i.scdn.co/image/ab67616d0000b273612e202866040c2447203e36","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:0btl8LkqkQNo4NxmpBoP8a in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:0btl8LkqkQNo4NxmpBoP8a Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5rFZHAhav5kGsSAKtQHRHq","service":"spop","name":"Most Wanted","artist":"Zinoleesky","album":"Most Wanted","type":"song","duration":142,"albumart":"https://i.scdn.co/image/ab67616d0000b2732fe9adda1eec563e62ccc990","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:3yLREZjJFnYLAN4aOUqqP6 in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:3yLREZjJFnYLAN4aOUqqP6 Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1uBZovogMw6DSL2DkNOwnX","service":"spop","name":"For Where?","artist":"Bayanni","album":"For Where?","type":"song","duration":113,"albumart":"https://i.scdn.co/image/ab67616d0000b27352771b456d6a3296652a411a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:19FyHGQSvrg8H88eaKvUQI in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:19FyHGQSvrg8H88eaKvUQI Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0btl8LkqkQNo4NxmpBoP8a","service":"spop","name":"Anytime (feat. Ayo Maff)","artist":"Id Cabasa","album":"Anytime (feat. Ayo Maff)","type":"song","duration":192,"albumart":"https://i.scdn.co/image/ab67616d0000b2733379cc0e95475b9282756bc8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:31 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:6bBQmflDHD8a35PeZjw44J in service spop Jul 19 16:47:31 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:6bBQmflDHD8a35PeZjw44J Jul 19 16:47:32 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:6al03F4hh8LUPRMcnrM3H5 in service spop Jul 19 16:47:32 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:6al03F4hh8LUPRMcnrM3H5 Jul 19 16:47:32 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:19FyHGQSvrg8H88eaKvUQI","service":"spop","name":"FIND MONEY (feat. Zerrydl)","artist":"Ayo Maff","album":"FIND MONEY (feat. Zerrydl)","type":"song","duration":149,"albumart":"https://i.scdn.co/image/ab67616d0000b27353f37a0232552a4f8b4f540a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:32 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3yLREZjJFnYLAN4aOUqqP6","service":"spop","name":"My Way","artist":"Hotkeed","album":"My Way","type":"song","duration":154,"albumart":"https://i.scdn.co/image/ab67616d0000b273c43204d8f8eed8507ee72564","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:32 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:6mV6zxfQetBvsAwz6AWxtH in service spop Jul 19 16:47:32 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:6mV6zxfQetBvsAwz6AWxtH Jul 19 16:47:32 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6bBQmflDHD8a35PeZjw44J","service":"spop","name":"Popo","artist":"Zerrydl","album":"Danger Zee","type":"song","duration":153,"albumart":"https://i.scdn.co/image/ab67616d0000b2738af5c26c9382b13f4dd0a55b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:32 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:5GX1wsJfvSXUobjDJgrVBD in service spop Jul 19 16:47:32 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:5GX1wsJfvSXUobjDJgrVBD Jul 19 16:47:32 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6al03F4hh8LUPRMcnrM3H5","service":"spop","name":"Ave Maria","artist":"Ajebo Hustlers","album":"Ave Maria","type":"song","duration":182,"albumart":"https://i.scdn.co/image/ab67616d0000b27385c731efdfd2364a738bff87","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:32 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:780BUxpCmW9vOVYZsqdLLE in service spop Jul 19 16:47:32 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:780BUxpCmW9vOVYZsqdLLE Jul 19 16:47:32 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6mV6zxfQetBvsAwz6AWxtH","service":"spop","name":"Port-harcourt","artist":"Vicoka","album":"Port-harcourt","type":"song","duration":170,"albumart":"https://i.scdn.co/image/ab67616d0000b273979ef32f201bcd1226ea339a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:32 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:7vdGiPrjK0gJBoZE8UhDMJ in service spop Jul 19 16:47:32 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:7vdGiPrjK0gJBoZE8UhDMJ Jul 19 16:47:32 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5GX1wsJfvSXUobjDJgrVBD","service":"spop","name":"BENIN BOYS","artist":"Rema","album":"BENIN BOYS","type":"song","duration":169,"albumart":"https://i.scdn.co/image/ab67616d0000b2733314f1abcf1a048160e01a88","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:32 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:3mARtPEP8hi1LzMctEwVN4 in service spop Jul 19 16:47:32 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:3mARtPEP8hi1LzMctEwVN4 Jul 19 16:47:32 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:780BUxpCmW9vOVYZsqdLLE","service":"spop","name":"Wave","artist":"Asake","album":"Wave","type":"song","duration":205,"albumart":"https://i.scdn.co/image/ab67616d0000b27352d1f444de0b3a2466980a97","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:32 volumio1920x480 volumio[1440]: info: Exploding uri spotify:track:6FZlSfQrD9aoB4SSnoAzWk in service spop Jul 19 16:47:32 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:track:6FZlSfQrD9aoB4SSnoAzWk Jul 19 16:47:32 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7vdGiPrjK0gJBoZE8UhDMJ","service":"spop","name":"Uptown Disco","artist":"Olamide","album":"Ikigai / 生き甲斐, Vol. 1","type":"song","duration":239,"albumart":"https://i.scdn.co/image/ab67616d0000b273612e202866040c2447203e36","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:32 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3mARtPEP8hi1LzMctEwVN4","service":"spop","name":"Tony Montana","artist":"Skepta","album":"Tony Montana","type":"song","duration":207,"albumart":"https://i.scdn.co/image/ab67616d0000b27365747db3a4c0abe5ab281563","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:32 volumio1920x480 volumio[1440]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6FZlSfQrD9aoB4SSnoAzWk","service":"spop","name":"ASAP","artist":"Shallipopi","album":"Shakespopi","type":"song","duration":160,"albumart":"https://i.scdn.co/image/ab67616d0000b27304282533fd67551b87d5a113","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 19 16:47:33 volumio1920x480 volumio[1440]: info: Preload queue cleared Jul 19 16:47:33 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 19 16:47:33 volumio1920x480 volumio[1440]: info: CoreStateMachine::ClearQueue Jul 19 16:47:33 volumio1920x480 volumio[1440]: info: CoreStateMachine::stop Jul 19 16:47:33 volumio1920x480 volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 16:47:33 volumio1920x480 volumio[1440]: info: CorePlayQueue::clearPlayQueue Jul 19 16:47:33 volumio1920x480 volumio[1440]: info: CorePlayQueue::saveQueue Jul 19 16:47:33 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioPushQueue Jul 19 16:47:33 volumio1920x480 volumio[1440]: info: CoreStateMachine::addQueueItems Jul 19 16:47:33 volumio1920x480 volumio[1440]: info: CorePlayQueue::addQueueItems Jul 19 16:47:33 volumio1920x480 volumio[1440]: info: Preload queue cleared Jul 19 16:47:33 volumio1920x480 volumio[1440]: info: Adding Item to queue: spotify:user:spotify:playlist:37i9dQZF1DX1GHMwBE32Ob Jul 19 16:47:33 volumio1920x480 volumio[1440]: info: Exploding uri spotify:user:spotify:playlist:37i9dQZF1DX1GHMwBE32Ob in service spop Jul 19 16:47:33 volumio1920x480 volumio[1440]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:37i9dQZF1DX1GHMwBE32Ob Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioPushQueue Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: CorePlayQueue::saveQueue Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: CoreStateMachine::updateTrackBlock Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrackBlock Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetState Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioPlay Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: CoreStateMachine::play index 0 Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: CoreStateMachine::stop Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: CoreStateMachine::play index undefined Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: CoreStateMachine::startPlaybackTimer Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: [1752936454102] ControllerSpotify::clearAddPlayTrack Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: Sending Spotify command with payload to local API: /player/play Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=debug msg="renewing login5 access token" Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=info msg="authenticated Login5 as 31rpeznnia6zthzdlzxkalygovbe" Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=debug msg="resolved context of track" uri="spotify:track:3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=trace msg="emitting websocket event: will_play" Jul 19 16:47:34 volumio1920x480 volumio[1440]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3s4WNh0WnfhwvIlrp6RTBj","play_origin":"go-librespot"}} Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=debug msg="selected format OGG_VORBIS_320 (ee776c13dedad2e21a3c617dd59d968c436e7cd7)" uri="spotify:track:3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=debug msg="requested aes key for file ee776c13dedad2e21a3c617dd59d968c436e7cd7, gid: 3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1395" Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1148" Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=debug msg="fetched first chunk of 14, total size is 6986724 bytes" uri="spotify:track:3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=debug msg="created new output device" Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: time="2025-07-19T16:47:34+02:00" level=info msg="loaded track \"ZAAZAA\" (paused: false, position: 0ms, duration: 156080ms, prefetched: false)" uri="spotify:track:3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:34 volumio1920x480 go-librespot[1782]: go-librespot: pcm_meter.c:1220: snd_pcm_scope_s16_get_channel_buffer: Assertion `s16->buf_areas' failed. Jul 19 16:47:34 volumio1920x480 go-librespot[1781]: Aborted Jul 19 16:47:34 volumio1920x480 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jul 19 16:47:34 volumio1920x480 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 19 16:47:34 volumio1920x480 systemd[1]: go-librespot-daemon.service: Consumed 3.406s CPU time. Jul 19 16:47:34 volumio1920x480 volumio[1440]: info: Connection to go-librespot Websocket closed Jul 19 16:47:34 volumio1920x480 volumio[1440]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Jul 19 16:47:37 volumio1920x480 volumio[1440]: info: Initializing connection to go-librespot Websocket Jul 19 16:47:37 volumio1920x480 volumio[1440]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 19 16:47:38 volumio1920x480 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jul 19 16:47:38 volumio1920x480 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 19 16:47:38 volumio1920x480 systemd[1]: go-librespot-daemon.service: Consumed 3.406s CPU time. Jul 19 16:47:38 volumio1920x480 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 19 16:47:38 volumio1920x480 go-librespot[26908]: go-librespot daemon starting... Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=info msg="running go-librespot 0.2.0" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=debug msg="app state loaded" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=info msg="zeroconf server listening on port 36883" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=debug msg="obtained new client token: AABFOjnN3ts/pavPPNWK63dWxgYgiM3PmheQqdldpHAVwQhaGIHN8qIVLfUaD0j5QfaQBOOAPyvLDhLEnmLVX/IdWeuceDT4Uzm3HDoa6IJkVsnv6RG1IJqUYpbuZdy0/T+w0G9FCntyTYOEQV5TnSEYmZ7LaMZe1I3yB5dcDLBYXRZ33JC2LuI9B+HtHRGwSuTQTKE8GHiAjyRuqthIR5zFFr1cx1ZlJQaGHAn368IoiQMw7ga9+c7xlg==" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=debug msg="completed keyexchange" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=debug msg="completed challenge" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=info msg="authenticated AP as 31rpeznnia6zthzdlzxkalygovbe" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=info msg="authenticated Login5 as 31rpeznnia6zthzdlzxkalygovbe" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=debug msg="initializing zeroconf session, username: 31rpeznnia6zthzdlzxkalygovbe" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=debug msg="dealer connection opened" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=trace msg="starting accesspoint recv loop" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=trace msg="starting dealer recv loop" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=trace msg="received accesspoint ping" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=debug msg="received connection id: YTZlMDk1YTUtMjc3Mi00M2QxLWJjMjctZjU5M2M2OTA2MGQwK2RlYWxlcit0Y3A6Ly8wYWIxNTExYy5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArRjlFQkQ4QzI4NDA5N0FGN0FBRkE5MDk3ODNBQUU1QzA1RjFDNzVCNkI2QzlBOUY0OUZFQkUxRjJGN0YxNTREQg==" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=trace msg="received accesspoint pong ack" Jul 19 16:47:38 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:38+02:00" level=debug msg="put connect state because NEW_DEVICE" Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: Preload queue cleared Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CoreStateMachine::ClearQueue Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CoreStateMachine::stop Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CorePlayQueue::clearPlayQueue Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CorePlayQueue::saveQueue Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioPushQueue Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CoreStateMachine::addQueueItems Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CorePlayQueue::addQueueItems Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: Preload queue cleared Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: Adding Item to queue: spotify:user:spotify:playlist:37i9dQZF1DX1GHMwBE32Ob Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: Using cached record of: spotify:user:spotify:playlist:37i9dQZF1DX1GHMwBE32Ob Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioPushQueue Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CorePlayQueue::saveQueue Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CoreStateMachine::updateTrackBlock Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrackBlock Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetState Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioPlay Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CoreStateMachine::play index 0 Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CoreStateMachine::stop Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CoreStateMachine::play index undefined Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CoreStateMachine::startPlaybackTimer Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: [1752936459152] ControllerSpotify::clearAddPlayTrack Jul 19 16:47:39 volumio1920x480 volumio[1440]: info: Sending Spotify command with payload to local API: /player/play Jul 19 16:47:39 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:39+02:00" level=debug msg="resolved context of track" uri="spotify:track:3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:39 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:39+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:39 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:39+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:39 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:39+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 19 16:47:39 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:39+02:00" level=trace msg="emitting websocket event: will_play" Jul 19 16:47:39 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:39+02:00" level=debug msg="selected format OGG_VORBIS_320 (ee776c13dedad2e21a3c617dd59d968c436e7cd7)" uri="spotify:track:3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:39 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:39+02:00" level=debug msg="requested aes key for file ee776c13dedad2e21a3c617dd59d968c436e7cd7, gid: 3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:39 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:39+02:00" level=debug msg="fetched first chunk of 14, total size is 6986724 bytes" uri="spotify:track:3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:39 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:39+02:00" level=debug msg="created new output device" Jul 19 16:47:39 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:39+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Jul 19 16:47:39 volumio1920x480 go-librespot[26909]: time="2025-07-19T16:47:39+02:00" level=info msg="loaded track \"ZAAZAA\" (paused: false, position: 0ms, duration: 156080ms, prefetched: false)" uri="spotify:track:3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:39 volumio1920x480 go-librespot[26909]: go-librespot: pcm_meter.c:1220: snd_pcm_scope_s16_get_channel_buffer: Assertion `s16->buf_areas' failed. Jul 19 16:47:39 volumio1920x480 go-librespot[26908]: Aborted Jul 19 16:47:39 volumio1920x480 volumio[1440]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Jul 19 16:47:39 volumio1920x480 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jul 19 16:47:39 volumio1920x480 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 19 16:47:40 volumio1920x480 systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 19 16:47:40 volumio1920x480 volumio[1440]: info: Initializing connection to go-librespot Websocket Jul 19 16:47:40 volumio1920x480 volumio[1440]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 19 16:47:42 volumio1920x480 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jul 19 16:47:42 volumio1920x480 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 19 16:47:42 volumio1920x480 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 19 16:47:42 volumio1920x480 go-librespot[26938]: go-librespot daemon starting... Jul 19 16:47:42 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:42+02:00" level=info msg="running go-librespot 0.2.0" Jul 19 16:47:42 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:42+02:00" level=debug msg="app state loaded" Jul 19 16:47:42 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:42+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 19 16:47:42 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:42+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 19 16:47:42 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:42+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jul 19 16:47:42 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:42+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jul 19 16:47:42 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:42+02:00" level=info msg="zeroconf server listening on port 41243" Jul 19 16:47:42 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:42+02:00" level=debug msg="obtained new client token: AAB+1Gbh5byEJDoFTMj+H25WnjVxGGnx/fLJNJAoSr7wr4uZZp4C2coTrProjbPXeKgQDMYDJz3H6VnaVtxxR+Z6rF7G1qMtbK6Fz9mC+omu9j0fQLYIlFvCjUDBzhf9DywUEnoY4xY/nLOynmXPdrFu2ivs6gy+YD8UBtSG3hhcAfllanv7Z9NGbkYbij1AR34anlxB18oyEXTfjkDGq2mTqZkZHf1WNT1AKzZOB/OqeXN7/utIwvy6lA==" Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=debug msg="completed keyexchange" Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=debug msg="completed challenge" Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=info msg="authenticated AP as 31rpeznnia6zthzdlzxkalygovbe" Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=info msg="authenticated Login5 as 31rpeznnia6zthzdlzxkalygovbe" Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=debug msg="initializing zeroconf session, username: 31rpeznnia6zthzdlzxkalygovbe" Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=debug msg="dealer connection opened" Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=trace msg="starting accesspoint recv loop" Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=trace msg="starting dealer recv loop" Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=trace msg="received accesspoint ping" Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=debug msg="received connection id: MzAxNzA2NzgtMWQ5NC00ODdiLTljNWUtNTcyZTRkNjdhMjFjK2RlYWxlcit0Y3A6Ly8wYWIxNTA3Zi5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArRTcwMkUwNUM2MEVEM0I5Nzc1RERFNkEzQTZCNTMxNDcyNkIyMEYzQzJGRjlCRjM4NzMwMUQ2QTA1MzI5NEU0RA==" Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=trace msg="received accesspoint pong ack" Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=debug msg="put connect state because NEW_DEVICE" Jul 19 16:47:43 volumio1920x480 volumio[1440]: info: Initializing connection to go-librespot Websocket Jul 19 16:47:43 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:43+02:00" level=debug msg="new websocket client" Jul 19 16:47:43 volumio1920x480 volumio[1440]: info: Connection to go-librespot Websocket established Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: Preload queue cleared Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CoreStateMachine::ClearQueue Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CoreStateMachine::stop Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CorePlayQueue::clearPlayQueue Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CorePlayQueue::saveQueue Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioPushQueue Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CoreStateMachine::addQueueItems Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CorePlayQueue::addQueueItems Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: Preload queue cleared Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: Adding Item to queue: spotify:user:spotify:playlist:37i9dQZF1DX1GHMwBE32Ob Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: Using cached record of: spotify:user:spotify:playlist:37i9dQZF1DX1GHMwBE32Ob Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioPushQueue Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CorePlayQueue::saveQueue Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CoreStateMachine::updateTrackBlock Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrackBlock Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioGetState Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CoreCommandRouter::volumioPlay Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CoreStateMachine::play index 0 Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CoreStateMachine::stop Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CoreStateMachine::play index undefined Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CoreStateMachine::startPlaybackTimer Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: CorePlayQueue::getTrack 0 Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: [1752936464262] ControllerSpotify::clearAddPlayTrack Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: Sending Spotify command with payload to local API: /player/play Jul 19 16:47:44 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:44+02:00" level=debug msg="resolved context of track" uri="spotify:track:3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:44 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:44+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:44 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:44+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:44 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:44+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 19 16:47:44 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:44+02:00" level=trace msg="emitting websocket event: will_play" Jul 19 16:47:44 volumio1920x480 volumio[1440]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3s4WNh0WnfhwvIlrp6RTBj","play_origin":"go-librespot"}} Jul 19 16:47:44 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:44+02:00" level=debug msg="selected format OGG_VORBIS_320 (ee776c13dedad2e21a3c617dd59d968c436e7cd7)" uri="spotify:track:3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:44 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:44+02:00" level=debug msg="requested aes key for file ee776c13dedad2e21a3c617dd59d968c436e7cd7, gid: 3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:44 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:44+02:00" level=debug msg="fetched first chunk of 14, total size is 6986724 bytes" uri="spotify:track:3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:44 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:44+02:00" level=debug msg="created new output device" Jul 19 16:47:44 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:44+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Jul 19 16:47:44 volumio1920x480 go-librespot[26939]: time="2025-07-19T16:47:44+02:00" level=info msg="loaded track \"ZAAZAA\" (paused: false, position: 0ms, duration: 156080ms, prefetched: false)" uri="spotify:track:3s4WNh0WnfhwvIlrp6RTBj" Jul 19 16:47:44 volumio1920x480 go-librespot[26939]: go-librespot: pcm_meter.c:1220: snd_pcm_scope_s16_get_channel_buffer: Assertion `s16->buf_areas' failed. Jul 19 16:47:44 volumio1920x480 go-librespot[26938]: Aborted Jul 19 16:47:44 volumio1920x480 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jul 19 16:47:44 volumio1920x480 volumio[1440]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Jul 19 16:47:44 volumio1920x480 volumio[1440]: info: Connection to go-librespot Websocket closed Jul 19 16:47:44 volumio1920x480 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 19 16:47:46 volumio1920x480 volumio[1440]: info: Getting Spotify volume Jul 19 16:47:46 volumio1920x480 volumio[1440]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 19 16:47:46 volumio1920x480 volumio[1440]: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 19 16:47:46 volumio1920x480 volumio[1440]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jul 19 16:47:46 volumio1920x480 volumio[1440]: errno: -111, Jul 19 16:47:46 volumio1920x480 volumio[1440]: code: 'ECONNREFUSED', Jul 19 16:47:46 volumio1920x480 volumio[1440]: syscall: 'connect', Jul 19 16:47:46 volumio1920x480 volumio[1440]: address: '127.0.0.1', Jul 19 16:47:46 volumio1920x480 volumio[1440]: port: 9879, Jul 19 16:47:46 volumio1920x480 volumio[1440]: response: undefined Jul 19 16:47:46 volumio1920x480 volumio[1440]: } Jul 19 16:47:46 volumio1920x480 volumio[1440]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 19 16:47:47 volumio1920x480 sudo[26963]: volumio : unable to resolve host volumio1920x480: System error Jul 19 16:47:47 volumio1920x480 sudo[26963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-19 16:46' Jul 19 16:47:47 volumio1920x480 sudo[26963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b6b8ef5f8c33005d951d1c00bbb3b0cc2de6a7bc" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jul 19 09:09:46 UTC 2025" VOLUMIO_VERSION="4.017" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5a9037e7432a4740a4d09b1a6d039cf3"