Feb 25 23:04:01 volumio volumio[1222]: info: Initializing connection to go-librespot Websocket Feb 25 23:04:01 volumio go-librespot[1600]: time="2026-02-25T23:04:01+05:30" level=debug msg="new websocket client" Feb 25 23:04:02 volumio volumio[1222]: info: Connection to go-librespot Websocket established Feb 25 23:04:03 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 25 23:04:04 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Feb 25 23:04:04 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 25 23:04:04 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 25 23:04:04 volumio sudo[1645]: pam_unix(sudo:session): session closed for user root Feb 25 23:04:05 volumio volumio[1222]: info: Upmpdcli Daemon Started Feb 25 23:04:05 volumio volumio[1222]: info: Getting Spotify volume Feb 25 23:04:05 volumio volumio[1222]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 25 23:04:05 volumio volumio[1222]: info: Spotify volume: 100 Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 25 23:04:05 volumio volumio[1222]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 25 23:04:05 volumio volumio[1222]: info: Adding plugin bluetooth to MyMusic Plugins Feb 25 23:04:05 volumio volumio[1222]: info: Adding plugin multiroom to MyMusic Plugins Feb 25 23:04:05 volumio volumio[1222]: info: Adding plugin metavolumio to MyMusic Plugins Feb 25 23:04:06 volumio volumio[1222]: info: Adding plugin cd_controller to MyMusic Plugins Feb 25 23:04:06 volumio volumio[1222]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 25 23:04:06 volumio volumio[1222]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 25 23:04:06 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 25 23:04:06 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 25 23:04:06 volumio volumio[1222]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 25 23:04:06 volumio volumio[1222]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 25 23:04:11 volumio volumio[1222]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 25 23:04:11 volumio volumio[1222]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 25 23:04:11 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:04:11 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:04:11 volumio volumio[1222]: info: Starting MyVolumio Remote Streaming Endpoints Feb 25 23:04:11 volumio volumio[1222]: info: MyVolumio login type: Token Feb 25 23:04:11 volumio volumio[1222]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 25 23:04:11 volumio volumio[1222]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 25 23:04:16 volumio volumio[1222]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 25 23:04:16 volumio volumio[1222]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 25 23:04:16 volumio volumio[1222]: info: Streaming services startup Feb 25 23:04:16 volumio volumio[1222]: info: Starting Streaming Daemon Feb 25 23:04:16 volumio volumio[1222]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 25 23:04:16 volumio sudo[1752]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 23:04:16 volumio sudo[1752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 23:04:16 volumio sudo[1752]: pam_unix(sudo:session): session closed for user root Feb 25 23:04:16 volumio volumio[1222]: error: Cannot start Volumio Streaming Daemon Feb 25 23:04:16 volumio volumio[1222]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 25 23:04:16 volumio volumio[1222]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 25 23:04:17 volumio volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 25 23:04:17 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:04:17 volumio volumio[1222]: error: MyVolumio Custom Token format not valid, refreshing it Feb 25 23:04:18 volumio volumio[1222]: info: MyVolumio login type: Token Feb 25 23:04:19 volumio upmpdcli[1759]: writing RSA key Feb 25 23:04:19 volumio volumio[1222]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 25 23:04:20 volumio volumio[1222]: info: MyVolumio token set successfully Feb 25 23:04:20 volumio volumio[1222]: info: MYVOLUMIO: Adding device Feb 25 23:04:20 volumio volumio[1222]: info: MYVOLUMIO: Evaluating Server Feb 25 23:04:21 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 23:04:21 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:04:21 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 23:04:21 volumio volumio[1222]: info: MyVolumio status changed Feb 25 23:04:21 volumio volumio[1222]: info: Streaming services startup Feb 25 23:04:21 volumio volumio[1222]: info: Starting Streaming Daemon Feb 25 23:04:21 volumio volumio[1222]: info: Removing browser output: myVolumio user plan is not superstar Feb 25 23:04:21 volumio volumio[1222]: info: Removing audio output: Feb 25 23:04:21 volumio volumio[1222]: info: Stoppping Tunnel 1 Feb 25 23:04:21 volumio sudo[1805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 23:04:21 volumio sudo[1805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 23:04:21 volumio sudo[1807]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 25 23:04:21 volumio sudo[1807]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 23:04:21 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 23:04:21 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 23:04:21 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 23:04:21 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 23:04:21 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 23:04:21 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 23:04:21 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 23:04:21 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 23:04:21 volumio sudo[1805]: pam_unix(sudo:session): session closed for user root Feb 25 23:04:21 volumio volumio-remote-updater[823]: Test mode disabled Feb 25 23:04:21 volumio volumio-remote-updater[823]: Alpha mode disabled Feb 25 23:04:21 volumio volumio-remote-updater[823]: Alpha legacy test mode disabled Feb 25 23:04:21 volumio sudo[1807]: pam_unix(sudo:session): session closed for user root Feb 25 23:04:21 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 25 23:04:21 volumio volumio[1222]: info: Remote SSH Stopped Feb 25 23:04:21 volumio volumio[1222]: error: Cannot start Volumio Streaming Daemon Feb 25 23:04:21 volumio volumio[1222]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 25 23:04:21 volumio volumio[1222]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 25 23:04:22 volumio volumio[1222]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 25 23:04:22 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 25 23:04:22 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 23:04:22 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 23:04:22 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 25 23:04:22 volumio volumio[1222]: info: Setting Geolocation for MyVolumio to as2 Feb 25 23:04:22 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:04:22 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:04:22 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:04:22 volumio volumio[1222]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 25 23:04:23 volumio volumio[1222]: info: Updating MyVolumio device info Feb 25 23:04:23 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:04:23 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:04:23 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:04:23 volumio volumio[1222]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 25 23:04:24 volumio volumio5-onboarding[1531]: time=2026-02-25T23:04:24.415+05:30 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Feb 25 23:04:24 volumio volumio5-onboarding[1531]: time=2026-02-25T23:04:24.416+05:30 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Feb 25 23:04:24 volumio volumio5-onboarding[1531]: time=2026-02-25T23:04:24.416+05:30 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Feb 25 23:04:24 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 23:04:24 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 23:04:24 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 25 23:04:25 volumio go-librespot[1600]: time="2026-02-25T23:04:25+05:30" level=trace msg="sent dealer ping" Feb 25 23:04:25 volumio go-librespot[1600]: time="2026-02-25T23:04:25+05:30" level=trace msg="received dealer pong" Feb 25 23:04:25 volumio volumio5-onboarding[1531]: failed to bootstrap state: failed to get available plugins: could not get available plugins: context deadline exceeded Feb 25 23:04:25 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Feb 25 23:04:25 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Feb 25 23:04:25 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Feb 25 23:04:25 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 25 23:04:25 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 25 23:04:25 volumio volumio5-onboarding[1812]: time=2026-02-25T23:04:25.854+05:30 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 25 23:04:25 volumio volumio[1222]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 25 23:04:25 volumio volumio[1222]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 25 23:04:25 volumio volumio[1222]: info: Received Get System Info Feb 25 23:04:25 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 23:04:25 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 23:04:25 volumio volumio[1222]: info: Discovery: Getting this device information Feb 25 23:04:25 volumio volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 25 23:04:25 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:04:25 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 23:04:25 volumio volumio5-onboarding[1812]: time=2026-02-25T23:04:25.905+05:30 level=INFO msg="system info for 17ecd2316988e9556fb1a7dc6ac80a86" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.096 Feb 25 23:04:25 volumio volumio[1222]: info: Received Get System Info Feb 25 23:04:25 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 23:04:25 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 23:04:25 volumio volumio[1222]: info: Discovery: Getting this device information Feb 25 23:04:25 volumio volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 25 23:04:25 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:04:25 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 23:04:26 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 25 23:04:26 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 25 23:04:26 volumio volumio5-onboarding[1812]: time=2026-02-25T23:04:26.015+05:30 level=INFO msg="enabling local network discovery" Feb 25 23:04:26 volumio volumio5-onboarding[1812]: time=2026-02-25T23:04:26.039+05:30 level=INFO msg="bootstrapping state" hasInternet=true Feb 25 23:04:26 volumio volumio[1222]: info: Received Get System Info Feb 25 23:04:26 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 23:04:26 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 23:04:26 volumio volumio[1222]: info: Discovery: Getting this device information Feb 25 23:04:26 volumio volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 25 23:04:26 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:04:26 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 23:04:26 volumio volumio-remote-updater[823]: Test mode disabled Feb 25 23:04:26 volumio volumio-remote-updater[823]: Alpha mode disabled Feb 25 23:04:26 volumio volumio-remote-updater[823]: Alpha legacy test mode disabled Feb 25 23:04:26 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 23:04:26 volumio volumio[1222]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 25 23:04:26 volumio volumio[1222]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 25 23:04:26 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 25 23:04:26 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 23:04:26 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 23:04:26 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 25 23:04:26 volumio volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 25 23:04:26 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:04:26 volumio volumio[1222]: info: MYVOLUMIO: Adding device Feb 25 23:04:26 volumio volumio[1222]: info: MYVOLUMIO: Evaluating Server Feb 25 23:04:27 volumio volumio[1222]: info: Setting Geolocation for MyVolumio to as2 Feb 25 23:04:27 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:04:27 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:04:27 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:04:28 volumio volumio5-onboarding[1812]: time=2026-02-25T23:04:28.067+05:30 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Feb 25 23:04:28 volumio volumio5-onboarding[1812]: time=2026-02-25T23:04:28.068+05:30 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Feb 25 23:04:28 volumio volumio5-onboarding[1812]: time=2026-02-25T23:04:28.068+05:30 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Feb 25 23:04:28 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 23:04:28 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 23:04:28 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 25 23:04:28 volumio volumio[1222]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 25 23:04:28 volumio volumio[1222]: info: Updating MyVolumio device info Feb 25 23:04:28 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:04:28 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:04:28 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:04:29 volumio volumio[1222]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 25 23:04:29 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 23:04:29 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 25 23:04:30 volumio volumio5-onboarding[1812]: time=2026-02-25T23:04:30.937+05:30 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=moeHuuxEROSn0BSjL1DAFhHNvhD2 tokenExpiry=2026-02-26T00:04:30.937+05:30 Feb 25 23:04:31 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 23:04:31 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 23:04:31 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 25 23:04:31 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 25 23:04:31 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 25 23:04:31 volumio volumio[1222]: info: CoreCommandRouter::volumioGetBrowseSources Feb 25 23:04:31 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 23:04:33 volumio volumio5-onboarding[1812]: time=2026-02-25T23:04:33.687+05:30 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.101:56906 Feb 25 23:04:36 volumio volumio[1222]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 25 23:04:36 volumio volumio[1222]: info: Completed starting MyVolumio Plugin Feb 25 23:04:36 volumio volumio[1222]: [Metrics] CommandRouter: 60s 805.12ms Feb 25 23:04:36 volumio volumio[1222]: info: CoreCommandRouter::volumiosetStartupVolume Feb 25 23:04:36 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 23:04:36 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:04:36 volumio volumio[1222]: info: CoreCommandRouter::Close All Modals sent Feb 25 23:04:36 volumio volumio[1222]: info: CoreCommandRouter::Close All Modals sent Feb 25 23:04:36 volumio volumio[1222]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Feb 25 23:04:36 volumio volumio[1222]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for AUDIO Feb 25 23:04:36 volumio volumio[1222]: aplay: main:831: audio open error: No such device Feb 25 23:04:37 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 25 23:04:37 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 23:04:37 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Feb 25 23:04:37 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 25 23:04:43 volumio sudo[1883]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 23:04:43 volumio sudo[1883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 23:04:43 volumio sudo[1881]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 23:04:43 volumio sudo[1881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 23:04:43 volumio sudo[1883]: pam_unix(sudo:session): session closed for user root Feb 25 23:04:43 volumio sudo[1881]: pam_unix(sudo:session): session closed for user root Feb 25 23:04:43 volumio volumio[1222]: info: BOOT COMPLETED Feb 25 23:04:43 volumio volumio[1222]: verbose: New Socket.io Connection to 192.168.0.105 from 192.168.0.101 UA: Mozilla/5.0 (Linux; Android 16; 23127PN0CG Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 25 23:04:43 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 23:04:44 volumio volumio[1222]: info: CoreCommandRouter::volumioGetVisibleSources Feb 25 23:04:44 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 23:04:44 volumio volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 25 23:04:44 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:04:44 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 25 23:04:44 volumio volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 23:04:44 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 25 23:04:44 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 25 23:04:44 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 25 23:04:44 volumio volumio[1222]: info: Received Get System Info Feb 25 23:04:44 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 23:04:44 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 23:04:44 volumio volumio[1222]: info: Discovery: Getting this device information Feb 25 23:04:44 volumio volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 25 23:04:44 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:04:44 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 23:04:44 volumio volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 25 23:04:44 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:04:44 volumio volumio[1222]: info: Listing playlists Feb 25 23:04:44 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 25 23:04:44 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 23:04:45 volumio volumio[1222]: Disk list : [] Feb 25 23:04:45 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 23:04:45 volumio volumio[1222]: info: Received Get System Info Feb 25 23:04:45 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 23:04:45 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 23:04:45 volumio volumio[1222]: info: Discovery: Getting this device information Feb 25 23:04:45 volumio volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 25 23:04:45 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:04:45 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 23:04:45 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 25 23:04:45 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 25 23:04:45 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 25 23:04:45 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 25 23:04:46 volumio volumio[1222]: info: Received Get System Version Feb 25 23:04:46 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 23:04:46 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 25 23:04:47 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 23:04:47 volumio volumio[1222]: info: Received Get System Info Feb 25 23:04:47 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 23:04:47 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 23:04:47 volumio volumio[1222]: info: Discovery: Getting this device information Feb 25 23:04:47 volumio volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 25 23:04:47 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:04:47 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 23:04:49 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 23:04:49 volumio volumio[1222]: info: Received Get System Info Feb 25 23:04:49 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 23:04:49 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 23:04:49 volumio volumio[1222]: info: Discovery: Getting this device information Feb 25 23:04:49 volumio volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 25 23:04:49 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:04:49 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 23:04:49 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 23:04:49 volumio volumio[1222]: info: Received Get System Info Feb 25 23:04:49 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 23:04:49 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 23:04:49 volumio volumio[1222]: info: Discovery: Getting this device information Feb 25 23:04:49 volumio volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 25 23:04:49 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:04:49 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 23:04:53 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 23:04:55 volumio go-librespot[1600]: time="2026-02-25T23:04:55+05:30" level=trace msg="sent dealer ping" Feb 25 23:04:55 volumio go-librespot[1600]: time="2026-02-25T23:04:55+05:30" level=trace msg="received dealer pong" Feb 25 23:05:00 volumio kernel: usb 1-1.1: new high-speed USB device number 3 using xhci_hcd Feb 25 23:05:00 volumio kernel: usb 1-1.1: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Feb 25 23:05:00 volumio kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Feb 25 23:05:00 volumio kernel: usb 1-1.1: Product: SMSL USB AUDIO Feb 25 23:05:00 volumio kernel: usb 1-1.1: Manufacturer: SMSL Feb 25 23:05:00 volumio kernel: hid-generic 0003:152A:85DD.0001: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-0000:01:00.0-1.1/input3 Feb 25 23:05:01 volumio kernel: usb 1-1.1: parse_audio_format_rates_v2v3(): unable to retrieve number of sample rates (clock 41) Feb 25 23:05:01 volumio kernel: usb 1-1.1: parse_audio_format_rates_v2v3(): unable to retrieve number of sample rates (clock 41) Feb 25 23:05:01 volumio kernel: usb 1-1.1: parse_audio_format_rates_v2v3(): unable to retrieve number of sample rates (clock 41) Feb 25 23:05:01 volumio kernel: usbcore: registered new interface driver snd-usb-audio Feb 25 23:05:01 volumio (udev-worker)[1936]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Feb 25 23:05:01 volumio kernel: usb 1-1.1: USB disconnect, device number 3 Feb 25 23:05:02 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 23:05:02 volumio volumio[1222]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 25 23:05:02 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 25 23:05:02 volumio volumio[1222]: info: Received Get System Version Feb 25 23:05:02 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 23:05:02 volumio volumio[1222]: info: Received Get System Info Feb 25 23:05:02 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 23:05:02 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 23:05:02 volumio volumio[1222]: info: Discovery: Getting this device information Feb 25 23:05:02 volumio volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 25 23:05:02 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:05:02 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 23:05:02 volumio kernel: usb 1-1.1: new high-speed USB device number 4 using xhci_hcd Feb 25 23:05:02 volumio kernel: usb 1-1.1: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Feb 25 23:05:02 volumio kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Feb 25 23:05:02 volumio kernel: usb 1-1.1: Product: SMSL USB AUDIO Feb 25 23:05:02 volumio kernel: usb 1-1.1: Manufacturer: SMSL Feb 25 23:05:03 volumio kernel: hid-generic 0003:152A:85DD.0002: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-0000:01:00.0-1.1/input3 Feb 25 23:05:03 volumio volumio[1222]: info: Feb 25 23:05:03 volumio volumio[1222]: ---------------------------- USB Audio Device Attached Feb 25 23:05:03 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Feb 25 23:05:03 volumio volumio[1222]: info: CoreCommandRouter::Close All Modals sent Feb 25 23:05:03 volumio volumio[1222]: info: Preparing to save Alsa Options, stopping services first Feb 25 23:05:03 volumio volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 25 23:05:03 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:05:03 volumio volumio[1222]: info: CoreCommandRouter::volumioPause Feb 25 23:05:03 volumio volumio[1222]: info: CoreStateMachine::pause Feb 25 23:05:03 volumio volumio[1222]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Feb 25 23:05:03 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 23:05:03 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 23:05:03 volumio volumio[1222]: info: Device 5 does not have any Mixer Control Available Feb 25 23:05:03 volumio volumio[1222]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 25 23:05:03 volumio volumio[1222]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 25 23:05:03 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 25 23:05:03 volumio volumio[1222]: info: Disabling external Volume Control Feb 25 23:05:03 volumio volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 23:05:03 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 25 23:05:03 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:05:03 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 25 23:05:03 volumio volumio[1222]: info: Preparing to generate the ALSA configuration file Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 25 23:05:04 volumio volumio[1222]: info: Asound.conf file unchanged, so no further update is needed Feb 25 23:05:04 volumio volumio[1222]: info: Output device has changed, restarting MPD Feb 25 23:05:04 volumio volumio[1222]: info: Output device has changed, restarting Shairport Sync Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:05:04 volumio sudo[1980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 23:05:04 volumio sudo[1982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 23:05:04 volumio sudo[1982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 23:05:04 volumio sudo[1980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 23:05:04 volumio volumio[1222]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 23:05:04 volumio sudo[1980]: pam_unix(sudo:session): session closed for user root Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Feb 25 23:05:04 volumio volumio[1222]: info: MPD Permissions set Feb 25 23:05:04 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 23:05:04 volumio volumio[1222]: info: Starting Shairport Sync Feb 25 23:05:04 volumio sudo[1995]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 23:05:04 volumio sudo[1995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 23:05:04 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 25 23:05:04 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 25 23:05:04 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 23:05:04 volumio systemd[1]: shairport-sync.service: Consumed 1.639s CPU time. Feb 25 23:05:04 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 23:05:04 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 25 23:05:04 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 25 23:05:04 volumio systemd[1]: mpd.service: Consumed 3.321s CPU time. Feb 25 23:05:04 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 25 23:05:04 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 25 23:05:04 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 25 23:05:04 volumio sudo[1995]: pam_unix(sudo:session): session closed for user root Feb 25 23:05:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 25 23:05:04 volumio volumio[1222]: info: Shairport-Sync Started Feb 25 23:05:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 25 23:05:04 volumio sudo[1999]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 23:05:04 volumio sudo[1999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 23:05:04 volumio sudo[1999]: pam_unix(sudo:session): session closed for user root Feb 25 23:05:06 volumio mpd[2015]: 2026-02-25T23:05:06 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 23:05:06 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 25 23:05:06 volumio sudo[1982]: pam_unix(sudo:session): session closed for user root Feb 25 23:05:06 volumio volumio[1222]: error: updateQueue error: null Feb 25 23:05:07 volumio volumio[1222]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 25 23:05:07 volumio volumio[1222]: info: CoreStateMachine::getcurrentVolume Feb 25 23:05:07 volumio volumio[1222]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 23:05:07 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 25 23:05:07 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:05:07 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 25 23:05:10 volumio volumio[1222]: info: CoreCommandRouter::volumioGetQueue Feb 25 23:05:10 volumio volumio[1222]: info: CoreStateMachine::getQueue Feb 25 23:05:10 volumio volumio[1222]: info: CorePlayQueue::getQueue Feb 25 23:05:12 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 25 23:05:12 volumio volumio[1222]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:37i9dQZF1EIW4wtKFr52hv Feb 25 23:05:13 volumio go-librespot[1600]: time="2026-02-25T23:05:13+05:30" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Feb 25 23:05:13 volumio go-librespot[1600]: time="2026-02-25T23:05:13+05:30" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EIW4wtKFr52hv" Feb 25 23:05:14 volumio go-librespot[1600]: time="2026-02-25T23:05:14+05:30" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EIW4wtKFr52hv" Feb 25 23:05:14 volumio go-librespot[1600]: time="2026-02-25T23:05:14+05:30" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Feb 25 23:05:15 volumio volumio[1222]: info: Preload queue cleared Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:59xrC6NUUNt24Lft63CjiD Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:3ROGyhvn69FP9XFWAaNS1x Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:6hjv9Rxj6SOwwaoHjuJXzI Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:6DCALWbYX0BCOAGy0hMmRs Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:5neMc6ey0hPVKhN8OnyF8l Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:6lEd3iSiH4s38XWf1C4NlO Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:0ZHNcLdzLhH01fdqtNllkP Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:4u9IvdoXfFo4sgLOUbltRu Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:30bDpLQeAcMH10IAjsCQCS Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:2JAECj9kBYQHb8AqJ2rsxt Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:2aRm1A4mXghkPQt7ksU8fC Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:1fOrdq5lDitklPno27DeHV Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:3gUFcAz4TdQ2vA5CaNNw5e Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:72twZ9MYaKADeH7hl4wCAd Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:1Gx8roQoHEPHTh9WNWgIaE Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:1N0UflnZhn7xOkrN2l8zxx Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:3MVQrEDUTk4dwvCThIv1oC Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:5WmDRnuGYo31xrWNDcYaps Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:6Mnjz2VYYLpf7ArmowVmWD Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:3w6USSa8JTYJ8gXgqL5rys Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:7rxeQyD3lriVzWNVoF285U Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:1cip9sLyXtKxDoUonU3uAP Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:7ds7cEdJ0nT0ioYemdnezQ Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:4X4D3YION3iy6A9U7rj3D6 Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:1UOJReluWsmaQKoEQXOcnf Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:0ZcPFX9YzSw0t9wHgIbEXv Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:0xHaDtehlGFW9aAuwnLg1V Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:25ZjGFRegtNCsK6RDiFOTg Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:3BriNUPwRBWMoo8DTm2l1N Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:0bGb5V7IzkqAcM3Ken9kYq Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:49RmBppHATHjbdCGzqpGxA Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:3PVoXtBjLMJza441VATbr9 Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:1GcFzch0H2ISYaIouviZlU Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:3xRzUblYGZhVs52fMjii7R Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:18LZE90QFlt1QKi1EvDORa Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:3CpgQJIW2FPfHMKkxhwyz7 Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:5Beqms0vrxPAniBv4xMaR4 Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:2tJlASUdstRCCecAN9rThS Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:6j23STH3Eiul1qrdWpRAwv Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:3CTxcImER2VN4xGVF4mJCV Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:6laP19z6DZlkIpXnvua0U5 Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:0ye53FFhP3oRFuaz5Ubcop Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:4ueEJ6xZ8MfC0jKXVZFwsz Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:7GRq9NUgqhxPGUUksX02fk Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:51TGkDPBOxT3cj1z4RgOXK Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:6nylEPiO5P3Vut7qiUtpqG Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:0cvCArAiYZcWg1v0DRCxFC Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:4jhAySwh8YhXVS9togVMDc Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:24DGDovZOGiLrkL3wnq8id Feb 25 23:05:15 volumio volumio[1222]: info: Preloading song: spotify:track:6ctbCIikHbC6tSOL1rewQX Feb 25 23:05:15 volumio volumio[1222]: info: Exploding uri spotify:track:59xrC6NUUNt24Lft63CjiD in service spop Feb 25 23:05:15 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:59xrC6NUUNt24Lft63CjiD Feb 25 23:05:15 volumio volumio[1222]: info: Exploding uri spotify:track:3ROGyhvn69FP9XFWAaNS1x in service spop Feb 25 23:05:15 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:3ROGyhvn69FP9XFWAaNS1x Feb 25 23:05:15 volumio volumio[1222]: info: Exploding uri spotify:track:6hjv9Rxj6SOwwaoHjuJXzI in service spop Feb 25 23:05:15 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:6hjv9Rxj6SOwwaoHjuJXzI Feb 25 23:05:15 volumio volumio[1222]: info: Exploding uri spotify:track:6DCALWbYX0BCOAGy0hMmRs in service spop Feb 25 23:05:15 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:6DCALWbYX0BCOAGy0hMmRs Feb 25 23:05:15 volumio volumio[1222]: info: Exploding uri spotify:track:5neMc6ey0hPVKhN8OnyF8l in service spop Feb 25 23:05:15 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:5neMc6ey0hPVKhN8OnyF8l Feb 25 23:05:15 volumio volumio[1222]: info: Exploding uri spotify:track:6lEd3iSiH4s38XWf1C4NlO in service spop Feb 25 23:05:15 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:6lEd3iSiH4s38XWf1C4NlO Feb 25 23:05:15 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 25 23:05:15 volumio volumio[1222]: info: CURURI: artists:// Feb 25 23:05:15 volumio volumio[1222]: info: Exploding uri spotify:track:0ZHNcLdzLhH01fdqtNllkP in service spop Feb 25 23:05:15 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:0ZHNcLdzLhH01fdqtNllkP Feb 25 23:05:15 volumio volumio[1222]: info: Exploding uri spotify:track:4u9IvdoXfFo4sgLOUbltRu in service spop Feb 25 23:05:15 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:4u9IvdoXfFo4sgLOUbltRu Feb 25 23:05:15 volumio volumio[1222]: info: Exploding uri spotify:track:30bDpLQeAcMH10IAjsCQCS in service spop Feb 25 23:05:15 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:30bDpLQeAcMH10IAjsCQCS Feb 25 23:05:15 volumio volumio[1222]: info: Exploding uri spotify:track:2JAECj9kBYQHb8AqJ2rsxt in service spop Feb 25 23:05:15 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:2JAECj9kBYQHb8AqJ2rsxt Feb 25 23:05:15 volumio volumio[1222]: info: Exploding uri spotify:track:2aRm1A4mXghkPQt7ksU8fC in service spop Feb 25 23:05:15 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:2aRm1A4mXghkPQt7ksU8fC Feb 25 23:05:15 volumio volumio[1222]: info: Preload queue cleared Feb 25 23:05:16 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3ROGyhvn69FP9XFWAaNS1x","service":"spop","name":"In a Daydream","artist":"Freddy Jones Band","album":"Waiting For The Night","type":"song","duration":328,"albumart":"https://i.scdn.co/image/ab67616d0000b273907c5349a80398b7d1885a14","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:17 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:59xrC6NUUNt24Lft63CjiD","service":"spop","name":"Bittersweet","artist":"Big Head Todd and The Monsters","album":"Sister Sweetly","type":"song","duration":376,"albumart":"https://i.scdn.co/image/ab67616d0000b273d8efe5696ac0ca2778856d99","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:17 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6hjv9Rxj6SOwwaoHjuJXzI","service":"spop","name":"Please Don't Tell Her","artist":"Big Head Todd and The Monsters","album":"Beautiful World","type":"song","duration":247,"albumart":"https://i.scdn.co/image/ab67616d0000b273b962bc58305ae876f4452a82","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:17 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6lEd3iSiH4s38XWf1C4NlO","service":"spop","name":"Things We Do","artist":"Indigenous","album":"The Acoustic Sessions","type":"song","duration":273,"albumart":"https://i.scdn.co/image/ab67616d0000b27344f226c7af47bbc296437b7e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:17 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4u9IvdoXfFo4sgLOUbltRu","service":"spop","name":"Stand Up","artist":"The Revivalists","album":"Men Amongst Mountains","type":"song","duration":231,"albumart":"https://i.scdn.co/image/ab67616d0000b273c5214ee5d4300598a8a95264","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:17 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6DCALWbYX0BCOAGy0hMmRs","service":"spop","name":"Lie To Me","artist":"Jonny Lang","album":"Lie To Me","type":"song","duration":251,"albumart":"https://i.scdn.co/image/ab67616d0000b27305fdcf33799ab844f54541e8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:17 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2JAECj9kBYQHb8AqJ2rsxt","service":"spop","name":"Walk On the Ocean","artist":"Toad The Wet Sprocket","album":"fear","type":"song","duration":178,"albumart":"https://i.scdn.co/image/ab67616d0000b273769b148d277c435a367317e4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:17 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:30bDpLQeAcMH10IAjsCQCS","service":"spop","name":"Broken Hearted Savior","artist":"Big Head Todd and The Monsters","album":"Sister Sweetly","type":"song","duration":261,"albumart":"https://i.scdn.co/image/ab67616d0000b273d8efe5696ac0ca2778856d99","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:17 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0ZHNcLdzLhH01fdqtNllkP","service":"spop","name":"Midnight Radio","artist":"Big Head Todd and The Monsters","album":"Midnight Radio","type":"song","duration":361,"albumart":"https://i.scdn.co/image/ab67616d0000b2734ae9fe2fbb6c6fb8e466d69c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:17 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5neMc6ey0hPVKhN8OnyF8l","service":"spop","name":"It's Alright","artist":"Big Head Todd and The Monsters","album":"Sister Sweetly","type":"song","duration":240,"albumart":"https://i.scdn.co/image/ab67616d0000b273d8efe5696ac0ca2778856d99","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:17 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2aRm1A4mXghkPQt7ksU8fC","service":"spop","name":"Beast Of Burden","artist":"Big Head Todd and The Monsters","album":"Rocksteady","type":"song","duration":222,"albumart":"https://i.scdn.co/image/ab67616d0000b273ff4eb3cb2b8d7b9143b50957","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:18 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 25 23:05:18 volumio volumio[1222]: info: In handleBrowseUri, curUri=spotify Feb 25 23:05:18 volumio volumio[1222]: info: Preload queue cleared Feb 25 23:05:18 volumio volumio[1222]: info: Preload queue cleared Feb 25 23:05:18 volumio volumio[1222]: info: Preload queue cleared Feb 25 23:05:18 volumio volumio[1222]: info: Preload queue cleared Feb 25 23:05:19 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 25 23:05:19 volumio volumio[1222]: info: In handleBrowseUri, curUri=spotify/playlists Feb 25 23:05:20 volumio volumio[1222]: info: Preload queue cleared Feb 25 23:05:22 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 25 23:05:22 volumio volumio[1222]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:37i9dQZF1EVJSvZp5AOML2 Feb 25 23:05:23 volumio go-librespot[1600]: time="2026-02-25T23:05:23+05:30" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Feb 25 23:05:23 volumio go-librespot[1600]: time="2026-02-25T23:05:23+05:30" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EVJSvZp5AOML2" Feb 25 23:05:25 volumio go-librespot[1600]: time="2026-02-25T23:05:25+05:30" level=trace msg="sent dealer ping" Feb 25 23:05:25 volumio go-librespot[1600]: time="2026-02-25T23:05:25+05:30" level=trace msg="received dealer pong" Feb 25 23:05:26 volumio volumio[1222]: info: Preload queue cleared Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:2EWpa5XnAuSn0sIkSSIhYk Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:44aTAUBF0g6sMkMNE8I5kd Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:7pG7odMa9PQ7ME0ZfnyR8d Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:6tXnRSvuNgOq4QcxpIN54r Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:2BY7ALEWdloFHgQZG6VMLA Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:4d6eqRtpDX7tydHJGDZUBQ Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:3DZ11M7LSpVQzYHnFx3i95 Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:2QlPByrmEO9XciNBXCrawR Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:0DJnqFhVWoTDs58JPem5Zh Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:1OFKUn2VLafrHj7ybnap0Q Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:254bXAqt3zP6P50BdQvEsq Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:0otlwsD3mSogk7VJCTp6Kg Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:1LOZMYF5s8qhW7Rv4w2gun Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:0aJrYfARfCKzv6gXjhs4SZ Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:4OJFkrRQqol4FsPesF8eu4 Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:6VnpKLtfNH4Dk09YSGPSyR Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:5BckPAYcKEJuYs1eV1BHHe Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:5KgbyD2lQQlIupAaPjgiXg Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:6xdLJrVj4vIXwhuG8TMopk Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:7Ar4G7Ci11gpt6sfH9Cgz5 Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:3kXoKlD84c6OmIcOLfrfEs Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:4N0TP4Rmj6QQezWV88ARNJ Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:0bRXwKfigvpKZUurwqAlEh Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:0q21FNwES2bbtcduB6kjEU Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:4ZExvJvQXPEeYzGU0N3THi Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:4LxDdYU3x9tyDxy3CghqdC Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:6lrQo6KAYvb92MGk6ZuZlt Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:4mxutS3UOEqZD6UOaIJUCB Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:30qVCFYKBtAENjTIBA8FPZ Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:3Vby4nGmtbDo7HDJamOWkT Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:5IKLwqBQG6KU6MP2zP80Nu Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:0Jl5bIEve3A4axcjY3EgDZ Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:4T6FWA703h6H7zk1FoSARw Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:6y4FVJwf09ssxuRnlEgXkp Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:6xE6ZWzK1YDDSYzqOCoQlz Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:2HDc1De9ZdPO1DDLao0iIw Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:2olVm1lHicpveMAo4AUDRB Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:4vhVDkSx9RSb2k6mWFMYNI Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:4UDmDIqJIbrW0hMBQMFOsM Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:6QgjcU0zLnzq5OrUoSZ3OK Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:1A4WjuUXeFEVXjwgc7eVZ6 Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:3ycnLH0vm7D9K2QHA4cAnq Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:05ajrG3EAcIe3j7NuqRV9B Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:5fztgDIt1Nq32VHJrAHq0Y Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:5MXXbGYNmRHR7ULMvZYo5R Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:1OBAPPxgujYUVYerorsg90 Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:3ZpQiJ78LKINrW9SQTgbXd Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:6fw12s3vAJQAt7lbICodwC Feb 25 23:05:26 volumio volumio[1222]: info: Preloading song: spotify:track:6mGTiazKYI1BHT2uc9wTUL Feb 25 23:05:26 volumio volumio[1222]: info: Exploding uri spotify:track:2EWpa5XnAuSn0sIkSSIhYk in service spop Feb 25 23:05:26 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:2EWpa5XnAuSn0sIkSSIhYk Feb 25 23:05:26 volumio volumio[1222]: info: Exploding uri spotify:track:44aTAUBF0g6sMkMNE8I5kd in service spop Feb 25 23:05:26 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:44aTAUBF0g6sMkMNE8I5kd Feb 25 23:05:26 volumio volumio[1222]: info: Exploding uri spotify:track:7pG7odMa9PQ7ME0ZfnyR8d in service spop Feb 25 23:05:26 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:7pG7odMa9PQ7ME0ZfnyR8d Feb 25 23:05:26 volumio volumio[1222]: info: Exploding uri spotify:track:6tXnRSvuNgOq4QcxpIN54r in service spop Feb 25 23:05:26 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:6tXnRSvuNgOq4QcxpIN54r Feb 25 23:05:26 volumio volumio[1222]: info: Exploding uri spotify:track:2BY7ALEWdloFHgQZG6VMLA in service spop Feb 25 23:05:26 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:2BY7ALEWdloFHgQZG6VMLA Feb 25 23:05:26 volumio volumio[1222]: info: Exploding uri spotify:track:4d6eqRtpDX7tydHJGDZUBQ in service spop Feb 25 23:05:26 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:4d6eqRtpDX7tydHJGDZUBQ Feb 25 23:05:26 volumio volumio[1222]: info: Exploding uri spotify:track:3DZ11M7LSpVQzYHnFx3i95 in service spop Feb 25 23:05:26 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:3DZ11M7LSpVQzYHnFx3i95 Feb 25 23:05:26 volumio volumio[1222]: info: Exploding uri spotify:track:2QlPByrmEO9XciNBXCrawR in service spop Feb 25 23:05:26 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:2QlPByrmEO9XciNBXCrawR Feb 25 23:05:26 volumio volumio[1222]: info: Exploding uri spotify:track:0DJnqFhVWoTDs58JPem5Zh in service spop Feb 25 23:05:26 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:0DJnqFhVWoTDs58JPem5Zh Feb 25 23:05:26 volumio volumio[1222]: info: Exploding uri spotify:track:1OFKUn2VLafrHj7ybnap0Q in service spop Feb 25 23:05:26 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:1OFKUn2VLafrHj7ybnap0Q Feb 25 23:05:26 volumio volumio[1222]: info: Exploding uri spotify:track:254bXAqt3zP6P50BdQvEsq in service spop Feb 25 23:05:26 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:254bXAqt3zP6P50BdQvEsq Feb 25 23:05:27 volumio volumio[1222]: info: Exploding uri spotify:track:0otlwsD3mSogk7VJCTp6Kg in service spop Feb 25 23:05:27 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:0otlwsD3mSogk7VJCTp6Kg Feb 25 23:05:27 volumio volumio[1222]: info: Exploding uri spotify:track:1LOZMYF5s8qhW7Rv4w2gun in service spop Feb 25 23:05:27 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:1LOZMYF5s8qhW7Rv4w2gun Feb 25 23:05:27 volumio volumio[1222]: info: Exploding uri spotify:track:0aJrYfARfCKzv6gXjhs4SZ in service spop Feb 25 23:05:27 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:0aJrYfARfCKzv6gXjhs4SZ Feb 25 23:05:27 volumio volumio[1222]: info: Exploding uri spotify:track:4OJFkrRQqol4FsPesF8eu4 in service spop Feb 25 23:05:27 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:4OJFkrRQqol4FsPesF8eu4 Feb 25 23:05:27 volumio volumio[1222]: info: Exploding uri spotify:track:6VnpKLtfNH4Dk09YSGPSyR in service spop Feb 25 23:05:27 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:6VnpKLtfNH4Dk09YSGPSyR Feb 25 23:05:27 volumio volumio[1222]: info: Exploding uri spotify:track:5BckPAYcKEJuYs1eV1BHHe in service spop Feb 25 23:05:27 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:5BckPAYcKEJuYs1eV1BHHe Feb 25 23:05:27 volumio volumio[1222]: info: Exploding uri spotify:track:5KgbyD2lQQlIupAaPjgiXg in service spop Feb 25 23:05:27 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:5KgbyD2lQQlIupAaPjgiXg Feb 25 23:05:27 volumio volumio[1222]: info: Exploding uri spotify:track:6xdLJrVj4vIXwhuG8TMopk in service spop Feb 25 23:05:27 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:6xdLJrVj4vIXwhuG8TMopk Feb 25 23:05:27 volumio volumio[1222]: info: Exploding uri spotify:track:7Ar4G7Ci11gpt6sfH9Cgz5 in service spop Feb 25 23:05:27 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:7Ar4G7Ci11gpt6sfH9Cgz5 Feb 25 23:05:27 volumio volumio[1222]: info: Exploding uri spotify:track:3kXoKlD84c6OmIcOLfrfEs in service spop Feb 25 23:05:27 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:3kXoKlD84c6OmIcOLfrfEs Feb 25 23:05:27 volumio volumio[1222]: info: Exploding uri spotify:track:4N0TP4Rmj6QQezWV88ARNJ in service spop Feb 25 23:05:27 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:4N0TP4Rmj6QQezWV88ARNJ Feb 25 23:05:27 volumio volumio[1222]: info: Exploding uri spotify:track:0bRXwKfigvpKZUurwqAlEh in service spop Feb 25 23:05:27 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:0bRXwKfigvpKZUurwqAlEh Feb 25 23:05:27 volumio volumio[1222]: info: Exploding uri spotify:track:0q21FNwES2bbtcduB6kjEU in service spop Feb 25 23:05:27 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:0q21FNwES2bbtcduB6kjEU Feb 25 23:05:27 volumio volumio[1222]: info: Exploding uri spotify:track:4ZExvJvQXPEeYzGU0N3THi in service spop Feb 25 23:05:27 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:4ZExvJvQXPEeYzGU0N3THi Feb 25 23:05:27 volumio volumio[1222]: info: Exploding uri spotify:track:4LxDdYU3x9tyDxy3CghqdC in service spop Feb 25 23:05:27 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:4LxDdYU3x9tyDxy3CghqdC Feb 25 23:05:28 volumio volumio[1222]: info: Exploding uri spotify:track:6lrQo6KAYvb92MGk6ZuZlt in service spop Feb 25 23:05:28 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:6lrQo6KAYvb92MGk6ZuZlt Feb 25 23:05:28 volumio volumio[1222]: info: Exploding uri spotify:track:4mxutS3UOEqZD6UOaIJUCB in service spop Feb 25 23:05:28 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:4mxutS3UOEqZD6UOaIJUCB Feb 25 23:05:28 volumio volumio[1222]: info: Exploding uri spotify:track:30qVCFYKBtAENjTIBA8FPZ in service spop Feb 25 23:05:28 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:30qVCFYKBtAENjTIBA8FPZ Feb 25 23:05:28 volumio volumio[1222]: info: Exploding uri spotify:track:3Vby4nGmtbDo7HDJamOWkT in service spop Feb 25 23:05:28 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:3Vby4nGmtbDo7HDJamOWkT Feb 25 23:05:28 volumio volumio[1222]: info: Exploding uri spotify:track:5IKLwqBQG6KU6MP2zP80Nu in service spop Feb 25 23:05:28 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:5IKLwqBQG6KU6MP2zP80Nu Feb 25 23:05:28 volumio volumio[1222]: info: Exploding uri spotify:track:0Jl5bIEve3A4axcjY3EgDZ in service spop Feb 25 23:05:28 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:0Jl5bIEve3A4axcjY3EgDZ Feb 25 23:05:28 volumio volumio[1222]: info: Exploding uri spotify:track:4T6FWA703h6H7zk1FoSARw in service spop Feb 25 23:05:28 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:4T6FWA703h6H7zk1FoSARw Feb 25 23:05:28 volumio volumio[1222]: info: Exploding uri spotify:track:6y4FVJwf09ssxuRnlEgXkp in service spop Feb 25 23:05:28 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:6y4FVJwf09ssxuRnlEgXkp Feb 25 23:05:28 volumio volumio[1222]: info: Exploding uri spotify:track:6xE6ZWzK1YDDSYzqOCoQlz in service spop Feb 25 23:05:28 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:6xE6ZWzK1YDDSYzqOCoQlz Feb 25 23:05:28 volumio volumio[1222]: info: Exploding uri spotify:track:2HDc1De9ZdPO1DDLao0iIw in service spop Feb 25 23:05:28 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:2HDc1De9ZdPO1DDLao0iIw Feb 25 23:05:28 volumio volumio[1222]: info: Exploding uri spotify:track:2olVm1lHicpveMAo4AUDRB in service spop Feb 25 23:05:28 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:2olVm1lHicpveMAo4AUDRB Feb 25 23:05:28 volumio volumio[1222]: info: Exploding uri spotify:track:4vhVDkSx9RSb2k6mWFMYNI in service spop Feb 25 23:05:28 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:4vhVDkSx9RSb2k6mWFMYNI Feb 25 23:05:28 volumio volumio[1222]: info: Exploding uri spotify:track:4UDmDIqJIbrW0hMBQMFOsM in service spop Feb 25 23:05:28 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:4UDmDIqJIbrW0hMBQMFOsM Feb 25 23:05:28 volumio volumio[1222]: info: Exploding uri spotify:track:6QgjcU0zLnzq5OrUoSZ3OK in service spop Feb 25 23:05:28 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:track:6QgjcU0zLnzq5OrUoSZ3OK Feb 25 23:05:29 volumio volumio[1222]: info: Preload queue cleared Feb 25 23:05:29 volumio volumio[1222]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 25 23:05:29 volumio volumio[1222]: info: CoreStateMachine::ClearQueue Feb 25 23:05:29 volumio volumio[1222]: info: CoreStateMachine::stop Feb 25 23:05:29 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 23:05:29 volumio volumio[1222]: info: CorePlayQueue::clearPlayQueue Feb 25 23:05:29 volumio volumio[1222]: info: CorePlayQueue::saveQueue Feb 25 23:05:29 volumio volumio[1222]: info: CoreCommandRouter::volumioPushQueue Feb 25 23:05:29 volumio volumio[1222]: info: CoreStateMachine::addQueueItems Feb 25 23:05:29 volumio volumio[1222]: info: CorePlayQueue::addQueueItems Feb 25 23:05:29 volumio volumio[1222]: info: Preload queue cleared Feb 25 23:05:29 volumio volumio[1222]: info: Adding Item to queue: spotify:user:spotify:playlist:37i9dQZF1EVJSvZp5AOML2 Feb 25 23:05:29 volumio volumio[1222]: info: Exploding uri spotify:user:spotify:playlist:37i9dQZF1EVJSvZp5AOML2 in service spop Feb 25 23:05:29 volumio volumio[1222]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:37i9dQZF1EVJSvZp5AOML2 Feb 25 23:05:31 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2EWpa5XnAuSn0sIkSSIhYk","service":"spop","name":"Wish I Knew You","artist":"The Revivalists","album":"Men Amongst Mountains","type":"song","duration":274,"albumart":"https://i.scdn.co/image/ab67616d0000b273c5214ee5d4300598a8a95264","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:31 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7pG7odMa9PQ7ME0ZfnyR8d","service":"spop","name":"Lido Shuffle (2023 Remaster)","artist":"Boz Scaggs","album":"Silk Degrees (2023 Remaster)","type":"song","duration":223,"albumart":"https://i.scdn.co/image/ab67616d0000b273f5395c7406ecb6a3e93255d4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:31 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0q21FNwES2bbtcduB6kjEU","service":"spop","name":"Dancing in the Moonlight","artist":"King Harvest","album":"Dancing in the Moonlight","type":"song","duration":181,"albumart":"https://i.scdn.co/image/ab67616d0000b273dcd829318f889df294d2bb7a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:31 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6tXnRSvuNgOq4QcxpIN54r","service":"spop","name":"Weather With You","artist":"Crowded House","album":"Woodface","type":"song","duration":224,"albumart":"https://i.scdn.co/image/ab67616d0000b2735f9e9291ab85c1e8fa88143f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:31 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:30qVCFYKBtAENjTIBA8FPZ","service":"spop","name":"The River of Dreams","artist":"Billy Joel","album":"River Of Dreams","type":"song","duration":245,"albumart":"https://i.scdn.co/image/ab67616d0000b2732f1069a5ca2d9b5f3eb56054","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:31 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6y4FVJwf09ssxuRnlEgXkp","service":"spop","name":"Let's Stick Together","artist":"Bryan Ferry","album":"Let's Stick Together","type":"song","duration":179,"albumart":"https://i.scdn.co/image/ab67616d0000b2738924d0252e61e6f1f68808a5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:44aTAUBF0g6sMkMNE8I5kd","service":"spop","name":"Every Little Thing She Does Is Magic","artist":"The Police","album":"Ghost In The Machine (Remastered 2003)","type":"song","duration":260,"albumart":"https://i.scdn.co/image/ab67616d0000b273b7879980ef2ea7ac1cc29316","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6QgjcU0zLnzq5OrUoSZ3OK","service":"spop","name":"Feel It Still","artist":"Portugal. The Man","album":"Woodstock","type":"song","duration":163,"albumart":"https://i.scdn.co/image/ab67616d0000b273af52c228c9619ff6298b08cd","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6lrQo6KAYvb92MGk6ZuZlt","service":"spop","name":"Say You Love Me","artist":"Fleetwood Mac","album":"Fleetwood Mac","type":"song","duration":249,"albumart":"https://i.scdn.co/image/ab67616d0000b2734fb043195e8d07e72edc7226","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0aJrYfARfCKzv6gXjhs4SZ","service":"spop","name":"Upside Down","artist":"Jack Johnson","album":"Upside Down","type":"song","duration":209,"albumart":"https://i.scdn.co/image/ab67616d0000b273f401bb58442523100aee4869","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4mxutS3UOEqZD6UOaIJUCB","service":"spop","name":"I Can See Clearly Now","artist":"Hothouse Flowers","album":"Home","type":"song","duration":295,"albumart":"https://i.scdn.co/image/ab67616d0000b2730378b0cfe766499edc10446a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2HDc1De9ZdPO1DDLao0iIw","service":"spop","name":"Let's Dance","artist":"Chris Rea","album":"Dancing with Strangers","type":"song","duration":247,"albumart":"https://i.scdn.co/image/ab67616d0000b2738388728d295e16cbfc01d779","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4ZExvJvQXPEeYzGU0N3THi","service":"spop","name":"Higher Love","artist":"Steve Winwood","album":"Back In The High Life","type":"song","duration":351,"albumart":"https://i.scdn.co/image/ab67616d0000b2738362a7a26eaf279f336f1494","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0DJnqFhVWoTDs58JPem5Zh","service":"spop","name":"Something Got Me Started - 2008 Remaster","artist":"Simply Red","album":"Stars (Collector's Edition)","type":"song","duration":240,"albumart":"https://i.scdn.co/image/ab67616d0000b2730e767887e304020cdbbe25e8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4T6FWA703h6H7zk1FoSARw","service":"spop","name":"New Light","artist":"John Mayer","album":"Sob Rock","type":"song","duration":217,"albumart":"https://i.scdn.co/image/ab67616d0000b273779063301154e835a91a35e0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3DZ11M7LSpVQzYHnFx3i95","service":"spop","name":"You Make Me Feel Like Dancing","artist":"Leo Sayer","album":"Endless Flight","type":"song","duration":221,"albumart":"https://i.scdn.co/image/ab67616d0000b273fc260b9f45d36ce9ebde7bc5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0bRXwKfigvpKZUurwqAlEh","service":"spop","name":"Lovely Day","artist":"Bill Withers","album":"Menagerie","type":"song","duration":257,"albumart":"https://i.scdn.co/image/ab67616d0000b27367c1113f55ab816ef61d3993","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6xE6ZWzK1YDDSYzqOCoQlz","service":"spop","name":"Last Train to London","artist":"Electric Light Orchestra","album":"Discovery","type":"song","duration":272,"albumart":"https://i.scdn.co/image/ab67616d0000b27337e78b6638f7737adee73d6f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2QlPByrmEO9XciNBXCrawR","service":"spop","name":"Roll With It","artist":"Steve Winwood","album":"Roll With It","type":"song","duration":320,"albumart":"https://i.scdn.co/image/ab67616d0000b273c833aad3e1bd21ddcdf0cab0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0otlwsD3mSogk7VJCTp6Kg","service":"spop","name":"Let My Love Open The Door","artist":"Pete Townshend","album":"Empty Glass","type":"song","duration":164,"albumart":"https://i.scdn.co/image/ab67616d0000b2733e7111cc866efb341a2988f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2BY7ALEWdloFHgQZG6VMLA","service":"spop","name":"Brandy (You're a Fine Girl)","artist":"Looking Glass","album":"Looking Glass","type":"song","duration":189,"albumart":"https://i.scdn.co/image/ab67616d0000b2737d12d6d8dcffd1594123b3bd","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5BckPAYcKEJuYs1eV1BHHe","service":"spop","name":"Take A Chance On Me","artist":"ABBA","album":"The Album","type":"song","duration":243,"albumart":"https://i.scdn.co/image/ab67616d0000b2736f705bef76c1d861c4d51d8c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7Ar4G7Ci11gpt6sfH9Cgz5","service":"spop","name":"Listen to the Music","artist":"The Doobie Brothers","album":"Toulouse Street","type":"song","duration":227,"albumart":"https://i.scdn.co/image/ab67616d0000b27391b51fa9b5967fb34c044498","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2olVm1lHicpveMAo4AUDRB","service":"spop","name":"The Power Of Love","artist":"Huey Lewis & The News","album":"Greatest Hits: Huey Lewis And The News","type":"song","duration":234,"albumart":"https://i.scdn.co/image/ab67616d0000b2735306ed42ae78f317258c51bb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5KgbyD2lQQlIupAaPjgiXg","service":"spop","name":"Night Fever - From \"Saturday Night Fever\" Soundtrack","artist":"Bee Gees","album":"Greatest","type":"song","duration":212,"albumart":"https://i.scdn.co/image/ab67616d0000b27352038992fc6d7868f31d23b7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1OFKUn2VLafrHj7ybnap0Q","service":"spop","name":"Only Wanna Be With You","artist":"Hootie & The Blowfish","album":"Cracked Rear View","type":"song","duration":229,"albumart":"https://i.scdn.co/image/ab67616d0000b273820d2376b2fb84aa99823903","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:254bXAqt3zP6P50BdQvEsq","service":"spop","name":"Everywhere - 2017 Remaster","artist":"Fleetwood Mac","album":"Tango In the Night (Deluxe Edition)","type":"song","duration":226,"albumart":"https://i.scdn.co/image/ab67616d0000b273aaba065944cd82a6f15c86b6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4LxDdYU3x9tyDxy3CghqdC","service":"spop","name":"Sunrise","artist":"Simply Red","album":"Home","type":"song","duration":199,"albumart":"https://i.scdn.co/image/ab67616d0000b27317a3d8e105d4f872fc66893d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0Jl5bIEve3A4axcjY3EgDZ","service":"spop","name":"Feelin' Alright","artist":"Joe Cocker","album":"With A Little Help From My Friends","type":"song","duration":250,"albumart":"https://i.scdn.co/image/ab67616d0000b2735bb09aa81ffb17ccf902629f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4OJFkrRQqol4FsPesF8eu4","service":"spop","name":"Saturday in the Park - 2002 Remaster","artist":"Chicago","album":"Chicago V (Expanded & Remastered)","type":"song","duration":235,"albumart":"https://i.scdn.co/image/ab67616d0000b27310d80ba410370acdbb0bf6c2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6xdLJrVj4vIXwhuG8TMopk","service":"spop","name":"Crazy Little Thing Called Love - Remastered 2011","artist":"Queen","album":"The Game (2011 Remaster)","type":"song","duration":163,"albumart":"https://i.scdn.co/image/ab67616d0000b273056e90910cbaf5c5b892aeba","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3Vby4nGmtbDo7HDJamOWkT","service":"spop","name":"Stuck In The Middle With You","artist":"Stealers Wheel","album":"Stealers Wheel","type":"song","duration":208,"albumart":"https://i.scdn.co/image/ab67616d0000b273a5ce236c22035a02cf87d4de","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5IKLwqBQG6KU6MP2zP80Nu","service":"spop","name":"We Are Family - 1995 Remaster","artist":"Sister Sledge","album":"We Are Family (1995 Remaster)","type":"song","duration":216,"albumart":"https://i.scdn.co/image/ab67616d0000b273f66d92378b233aa8253e71d2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1LOZMYF5s8qhW7Rv4w2gun","service":"spop","name":"Dreadlock Holiday","artist":"10cc","album":"Bloody Tourists","type":"song","duration":267,"albumart":"https://i.scdn.co/image/ab67616d0000b27364c6ef51927c575ed9f464cf","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6VnpKLtfNH4Dk09YSGPSyR","service":"spop","name":"It Ain't Over 'Til It's Over","artist":"Lenny Kravitz","album":"Mama Said","type":"song","duration":242,"albumart":"https://i.scdn.co/image/ab67616d0000b273bfc19627a4a3a604c0a195e5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3kXoKlD84c6OmIcOLfrfEs","service":"spop","name":"September","artist":"Earth, Wind & Fire","album":"September (sped up + slowed)","type":"song","duration":216,"albumart":"https://i.scdn.co/image/ab67616d0000b2730faf2fc17446651865ce2282","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4UDmDIqJIbrW0hMBQMFOsM","service":"spop","name":"Stayin' Alive","artist":"Bee Gees","album":"Staying Alive (Original Motion Picture Soundtrack)","type":"song","duration":92,"albumart":"https://i.scdn.co/image/ab67616d0000b27355fb38fda5321a8923911ff9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4N0TP4Rmj6QQezWV88ARNJ","service":"spop","name":"Superstition","artist":"Stevie Wonder","album":"Talking Book","type":"song","duration":266,"albumart":"https://i.scdn.co/image/ab67616d0000b273a14b08b9a6616e121df5e8b0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:32 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4vhVDkSx9RSb2k6mWFMYNI","service":"spop","name":"You're My Best Friend - Remastered 2011","artist":"Queen","album":"A Night At The Opera (2011 Remaster)","type":"song","duration":170,"albumart":"https://i.scdn.co/image/ab67616d0000b273e319baafd16e84f0408af2a0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:33 volumio volumio[1222]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4d6eqRtpDX7tydHJGDZUBQ","service":"spop","name":"She Drives Me Crazy","artist":"Fine Young Cannibals","album":"The Raw & The Cooked","type":"song","duration":215,"albumart":"https://i.scdn.co/image/ab67616d0000b273c573a4a852f010523c4ba383","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 25 23:05:33 volumio volumio[1222]: info: CoreCommandRouter::volumioPushQueue Feb 25 23:05:33 volumio volumio[1222]: info: CorePlayQueue::saveQueue Feb 25 23:05:33 volumio volumio[1222]: info: CoreStateMachine::updateTrackBlock Feb 25 23:05:33 volumio volumio[1222]: info: CorePlayQueue::getTrackBlock Feb 25 23:05:33 volumio volumio[1222]: info: CoreCommandRouter::volumioPlay Feb 25 23:05:33 volumio volumio[1222]: info: CoreStateMachine::play index 0 Feb 25 23:05:33 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 23:05:33 volumio volumio[1222]: info: CoreStateMachine::stop Feb 25 23:05:33 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 23:05:33 volumio volumio[1222]: info: CoreStateMachine::play index undefined Feb 25 23:05:33 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 23:05:33 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:05:33 volumio volumio[1222]: info: CoreStateMachine::startPlaybackTimer Feb 25 23:05:33 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:05:33 volumio volumio[1222]: info: [1772040933779] ControllerSpotify::clearAddPlayTrack Feb 25 23:05:33 volumio volumio[1222]: info: Sending Spotify command with payload to local API: /player/play Feb 25 23:05:34 volumio go-librespot[1600]: time="2026-02-25T23:05:34+05:30" level=debug msg="resolved context of track" uri="spotify:track:2EWpa5XnAuSn0sIkSSIhYk" Feb 25 23:05:34 volumio go-librespot[1600]: time="2026-02-25T23:05:34+05:30" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:2EWpa5XnAuSn0sIkSSIhYk" Feb 25 23:05:34 volumio go-librespot[1600]: time="2026-02-25T23:05:34+05:30" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:2EWpa5XnAuSn0sIkSSIhYk" Feb 25 23:05:34 volumio go-librespot[1600]: time="2026-02-25T23:05:34+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 25 23:05:34 volumio go-librespot[1600]: time="2026-02-25T23:05:34+05:30" level=trace msg="emitting websocket event: will_play" Feb 25 23:05:34 volumio volumio[1222]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:2EWpa5XnAuSn0sIkSSIhYk","uri":"spotify:track:2EWpa5XnAuSn0sIkSSIhYk","play_origin":"go-librespot"}} Feb 25 23:05:34 volumio go-librespot[1600]: time="2026-02-25T23:05:34+05:30" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Feb 25 23:05:34 volumio go-librespot[1600]: time="2026-02-25T23:05:34+05:30" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Feb 25 23:05:34 volumio go-librespot[1600]: time="2026-02-25T23:05:34+05:30" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 25 23:05:34 volumio go-librespot[1600]: time="2026-02-25T23:05:34+05:30" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1364" Feb 25 23:05:34 volumio go-librespot[1600]: time="2026-02-25T23:05:34+05:30" level=debug msg="selected format OGG_VORBIS_320 (07296b847d716728b75ff67ce4dc1d4a55bbe638)" uri="spotify:track:2EWpa5XnAuSn0sIkSSIhYk" Feb 25 23:05:34 volumio go-librespot[1600]: time="2026-02-25T23:05:34+05:30" level=debug msg="requested aes key for file 07296b847d716728b75ff67ce4dc1d4a55bbe638, gid: 2EWpa5XnAuSn0sIkSSIhYk" Feb 25 23:05:34 volumio go-librespot[1600]: time="2026-02-25T23:05:34+05:30" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 25 23:05:34 volumio go-librespot[1600]: time="2026-02-25T23:05:34+05:30" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1126" Feb 25 23:05:34 volumio go-librespot[1600]: time="2026-02-25T23:05:34+05:30" level=trace msg="found 2 cdn urls" uri="spotify:track:2EWpa5XnAuSn0sIkSSIhYk" Feb 25 23:05:35 volumio go-librespot[1600]: time="2026-02-25T23:05:35+05:30" level=debug msg="fetched first chunk of 21, total size is 10841112 bytes" uri="spotify:track:2EWpa5XnAuSn0sIkSSIhYk" Feb 25 23:05:35 volumio go-librespot[1600]: time="2026-02-25T23:05:35+05:30" level=debug msg="created new output device" Feb 25 23:05:35 volumio go-librespot[1600]: time="2026-02-25T23:05:35+05:30" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Feb 25 23:05:35 volumio go-librespot[1600]: time="2026-02-25T23:05:35+05:30" level=info msg="loaded track \"Wish I Knew You\" (paused: false, position: 0ms, duration: 274093ms, prefetched: false)" uri="spotify:track:2EWpa5XnAuSn0sIkSSIhYk" Feb 25 23:05:35 volumio go-librespot[1600]: time="2026-02-25T23:05:35+05:30" level=debug msg="fetched chunk 1/20, size: 524288" uri="spotify:track:2EWpa5XnAuSn0sIkSSIhYk" Feb 25 23:05:35 volumio go-librespot[1600]: time="2026-02-25T23:05:35+05:30" level=debug msg="fetched chunk 2/20, size: 524288" uri="spotify:track:2EWpa5XnAuSn0sIkSSIhYk" Feb 25 23:05:35 volumio go-librespot[1600]: time="2026-02-25T23:05:35+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 25 23:05:35 volumio go-librespot[1600]: time="2026-02-25T23:05:35+05:30" level=trace msg="scheduling prefetch in 244s" Feb 25 23:05:35 volumio go-librespot[1600]: time="2026-02-25T23:05:35+05:30" level=trace msg="emitting websocket event: metadata" Feb 25 23:05:35 volumio volumio[1222]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2EWpa5XnAuSn0sIkSSIhYk","name":"Wish I Knew You","artist_names":["The Revivalists"],"album_name":"Men Amongst Mountains","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02c5214ee5d4300598a8a95264","position":0,"duration":274093,"release_date":"year:2015 month:7 day:15","track_number":2,"disc_number":1}} Feb 25 23:05:35 volumio go-librespot[1600]: time="2026-02-25T23:05:35+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 25 23:05:35 volumio go-librespot[1600]: time="2026-02-25T23:05:35+05:30" level=trace msg="emitting websocket event: playing" Feb 25 23:05:35 volumio volumio[1222]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:2EWpa5XnAuSn0sIkSSIhYk","uri":"spotify:track:2EWpa5XnAuSn0sIkSSIhYk","resume":false,"play_origin":"go-librespot"}} Feb 25 23:05:35 volumio volumio[1222]: SPOTIFY: PUSH STATE SPOTIFY Feb 25 23:05:35 volumio volumio[1222]: SPOTIFY: {"status":"play","service":"spop","title":"Wish I Knew You","artist":"The Revivalists","album":"Men Amongst Mountains","albumart":"https://i.scdn.co/image/ab67616d00001e02c5214ee5d4300598a8a95264","uri":"spotify:track:2EWpa5XnAuSn0sIkSSIhYk","trackType":"spotify","seek":0,"duration":274,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 25 23:05:35 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 25 23:05:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:05:35 volumio volumio[1222]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Wish I Knew You","artist":"The Revivalists","album":"Men Amongst Mountains","albumart":"https://i.scdn.co/image/ab67616d00001e02c5214ee5d4300598a8a95264","uri":"spotify:track:2EWpa5XnAuSn0sIkSSIhYk","trackType":"spotify","seek":0,"duration":274,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 25 23:05:35 volumio volumio[1222]: verbose: CURRENT POSITION 0 Feb 25 23:05:35 volumio volumio[1222]: info: CoreStateMachine::syncState stateService play Feb 25 23:05:35 volumio volumio[1222]: info: CoreStateMachine::syncState currentStatus stop Feb 25 23:05:35 volumio go-librespot[1600]: time="2026-02-25T23:05:35+05:30" level=debug msg="fetched chunk 3/20, size: 524288" uri="spotify:track:2EWpa5XnAuSn0sIkSSIhYk" Feb 25 23:05:35 volumio volumio[1222]: SPOTIFY: PUSH STATE SPOTIFY Feb 25 23:05:35 volumio volumio[1222]: SPOTIFY: {"status":"play","service":"spop","title":"Wish I Knew You","artist":"The Revivalists","album":"Men Amongst Mountains","albumart":"https://i.scdn.co/image/ab67616d00001e02c5214ee5d4300598a8a95264","uri":"spotify:track:2EWpa5XnAuSn0sIkSSIhYk","trackType":"spotify","seek":0,"duration":274,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 25 23:05:35 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 25 23:05:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:05:35 volumio volumio[1222]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Wish I Knew You","artist":"The Revivalists","album":"Men Amongst Mountains","albumart":"https://i.scdn.co/image/ab67616d00001e02c5214ee5d4300598a8a95264","uri":"spotify:track:2EWpa5XnAuSn0sIkSSIhYk","trackType":"spotify","seek":0,"duration":274,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 25 23:05:35 volumio volumio[1222]: verbose: CURRENT POSITION 0 Feb 25 23:05:35 volumio volumio[1222]: info: CoreStateMachine::syncState stateService play Feb 25 23:05:35 volumio volumio[1222]: info: CoreStateMachine::syncState currentStatus play Feb 25 23:05:35 volumio volumio[1222]: info: Received an update from plugin. extracting info from payload Feb 25 23:05:35 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 25 23:05:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:05:35 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 23:05:35 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 25 23:05:35 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 25 23:05:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 25 23:05:35 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 25 23:05:44 volumio volumio[1222]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 23:05:44 volumio volumio[1222]: TypeError: Cannot read properties of null (reading 'slice') Feb 25 23:05:44 volumio volumio[1222]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Feb 25 23:05:44 volumio volumio[1222]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Feb 25 23:05:44 volumio volumio[1222]: at Object.onceWrapper (node:events:629:26) Feb 25 23:05:44 volumio volumio[1222]: at ClientRequest.emit (node:events:514:28) Feb 25 23:05:44 volumio volumio[1222]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Feb 25 23:05:44 volumio volumio[1222]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Feb 25 23:05:44 volumio volumio[1222]: at TLSSocket.socketOnData (node:_http_client:535:22) Feb 25 23:05:44 volumio volumio[1222]: at TLSSocket.emit (node:events:514:28) Feb 25 23:05:44 volumio volumio[1222]: at addChunk (node:internal/streams/readable:343:12) Feb 25 23:05:44 volumio volumio[1222]: at readableAddChunk (node:internal/streams/readable:316:9) Feb 25 23:05:44 volumio volumio[1222]: at Readable.push (node:internal/streams/readable:253:10) Feb 25 23:05:44 volumio volumio[1222]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Feb 25 23:05:44 volumio volumio[1222]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 23:05:47 volumio sudo[2095]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 23:04' Feb 25 23:05:47 volumio sudo[2095]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"