-- Logs begin at Thu 2019-02-14 12:11:58 EET, end at Tue 2026-02-24 16:25:48 EET. -- Feb 24 16:25:41 volumio-rpi4-olkkari systemd-timedated[1076]: Changed local time to Tue Feb 24 16:25:41 2026 Feb 24 16:25:41 volumio-rpi4-olkkari sudo[1057]: pam_unix(sudo:session): session closed for user root Feb 24 16:25:41 volumio-rpi4-olkkari volumio-time-update[594]: volumio-time-update-util: System time updated successfully. Feb 24 16:25:41 volumio-rpi4-olkkari systemd[1]: Started Volumio Time Update Utility. Feb 24 16:25:41 volumio-rpi4-olkkari sudo[1086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 24 16:25:41 volumio-rpi4-olkkari sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:25:41 volumio-rpi4-olkkari systemd[1]: Starting Daily apt download activities... Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: No need to fix Spotify hosts Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Discovery: A device disappeared from network Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Starting Shairport Sync Feb 24 16:25:41 volumio-rpi4-olkkari ntpd[819]: Listen normally on 3 eth0 10.8.8.64:123 Feb 24 16:25:41 volumio-rpi4-olkkari ntpd[819]: new interface(s) found: waking up resolver Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Starting Shairport Sync Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Starting Shairport Sync Feb 24 16:25:41 volumio-rpi4-olkkari systemd[1]: Started go-librespot Daemon. Feb 24 16:25:41 volumio-rpi4-olkkari sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 16:25:41 volumio-rpi4-olkkari sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:25:41 volumio-rpi4-olkkari go-librespot[1101]: go-librespot daemon starting... Feb 24 16:25:41 volumio-rpi4-olkkari sudo[1086]: pam_unix(sudo:session): session closed for user root Feb 24 16:25:41 volumio-rpi4-olkkari systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 24 16:25:41 volumio-rpi4-olkkari sudo[1102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 16:25:41 volumio-rpi4-olkkari sudo[1102]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:25:41 volumio-rpi4-olkkari sudo[1106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 16:25:41 volumio-rpi4-olkkari sudo[1106]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:25:41 volumio-rpi4-olkkari systemd[1]: shairport-sync.service: Succeeded. Feb 24 16:25:41 volumio-rpi4-olkkari systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 24 16:25:41 volumio-rpi4-olkkari systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 24 16:25:41 volumio-rpi4-olkkari sudo[1099]: pam_unix(sudo:session): session closed for user root Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Shairport-Sync Started Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: Error adding Membership: Error: addMembership EINVAL Feb 24 16:25:41 volumio-rpi4-olkkari systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 24 16:25:41 volumio-rpi4-olkkari systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 24 16:25:41 volumio-rpi4-olkkari systemd[1]: shairport-sync.service: Succeeded. Feb 24 16:25:41 volumio-rpi4-olkkari systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 24 16:25:41 volumio-rpi4-olkkari systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 24 16:25:41 volumio-rpi4-olkkari sudo[1102]: pam_unix(sudo:session): session closed for user root Feb 24 16:25:41 volumio-rpi4-olkkari sudo[1106]: pam_unix(sudo:session): session closed for user root Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Shairport-Sync Started Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Shairport-Sync Started Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Discovery: adding b09539cc-f5d7-416f-b3f5-a28ddcb06167 Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Discovery: Found device volumio-rpi4-olkkari Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::volumioGetState Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CorePlayQueue::getTrack 0 Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Discovery: this is already registered, b09539cc-f5d7-416f-b3f5-a28ddcb06167 Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Discovery: Found device volumio-rpi4-olkkari Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::volumioGetState Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CorePlayQueue::getTrack 0 Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::volumioGetState Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CorePlayQueue::getTrack 0 Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: verbose: New Socket.io Connection to 10.8.8.64 from 10.8.8.88 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 OPR/127.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Volumio called home Feb 24 16:25:41 volumio-rpi4-olkkari volumio5-onboarding[1052]: time=2026-02-24T16:25:41.390+02:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Received Get System Info Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Discovery: Getting this device information Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::volumioGetState Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CorePlayQueue::getTrack 0 Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::volumioGetState Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CorePlayQueue::getTrack 0 Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::volumioGetState Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CorePlayQueue::getTrack 0 Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Received Get System Info Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Discovery: Getting this device information Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::volumioGetState Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CorePlayQueue::getTrack 0 Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::volumioGetState Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CorePlayQueue::getTrack 0 Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Listing playlists Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: 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: 6 Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: 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: 6 Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Received Get System Info Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Discovery: Getting this device information Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::volumioGetState Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CorePlayQueue::getTrack 0 Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 16:25:41 volumio-rpi4-olkkari volumio5-onboarding[1052]: time=2026-02-24T16:25:41.469+02:00 level=INFO msg="system info for cdc07ba13966bc70bdc5273795e36c3c" deviceName=volumio-rpi4-olkkari deviceVariant=volumio deviceModel= softwareVersion=3.908 Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Received Get System Info Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Discovery: Getting this device information Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::volumioGetState Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CorePlayQueue::getTrack 0 Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 24 16:25:41 volumio-rpi4-olkkari volumio5-onboarding[1052]: time=2026-02-24T16:25:41.497+02:00 level=INFO msg="enabling local network discovery" Feb 24 16:25:41 volumio-rpi4-olkkari volumio5-onboarding[1052]: time=2026-02-24T16:25:41.531+02:00 level=INFO msg="bootstrapping state" hasInternet=true Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Received Get System Info Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Discovery: Getting this device information Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::volumioGetState Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CorePlayQueue::getTrack 0 Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 16:25:41 volumio-rpi4-olkkari volumio-remote-updater[583]: No test mode Feb 24 16:25:41 volumio-rpi4-olkkari volumio-remote-updater[583]: No alpha test mode Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 16:25:41 volumio-rpi4-olkkari volumio5-onboarding[1052]: time=2026-02-24T16:25:41.654+02:00 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 24 16:25:41 volumio-rpi4-olkkari volumio5-onboarding[1052]: time=2026-02-24T16:25:41.655+02:00 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 24 16:25:41 volumio-rpi4-olkkari volumio5-onboarding[1052]: time=2026-02-24T16:25:41.655+02:00 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 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 16:25:41 volumio-rpi4-olkkari nmbd[797]: [2026/02/24 16:25:41.691967, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 24 16:25:41 volumio-rpi4-olkkari systemd[1]: Started Samba NMB Daemon. Feb 24 16:25:41 volumio-rpi4-olkkari nmbd[797]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: New Spotify access tokenBQCev9wjqp... Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 24 16:25:41 volumio-rpi4-olkkari systemd[1]: Starting Samba Winbind Daemon... Feb 24 16:25:41 volumio-rpi4-olkkari nmbd[797]: [2026/02/24 16:25:41.747747, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 24 16:25:41 volumio-rpi4-olkkari nmbd[797]: query_name_response: Multiple (2) responses received for a query on subnet 10.8.8.64 for name WORKGROUP<1d>. Feb 24 16:25:41 volumio-rpi4-olkkari nmbd[797]: This response was from IP 10.8.8.8, reporting an IP address of 10.8.8.8. Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: SPOTIFY: User informations: {"country":"FI","display_name":"hanmias","email":"kml13+spotify@iki.fi","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/hanmias"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/hanmias","id":"hanmias","images":[],"product":"premium","type":"user","uri":"spotify:user:hanmias"} Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: Spotify Successfully logged in Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: [1771943141921] CoreMusicLibrary::Adding element Spotify Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: Cannot find translation for source LastFM Feb 24 16:25:41 volumio-rpi4-olkkari volumio[843]: Cannot find translation for source Spotify Feb 24 16:25:41 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:41+02:00" level=info msg="running go-librespot 0.6.2" Feb 24 16:25:41 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:41+02:00" level=debug msg="app state loaded" Feb 24 16:25:41 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:41+02:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 24 16:25:42 volumio-rpi4-olkkari volumio[843]: info: New access token = BQDAIuVKqACIBqg-uZNNNjYe7xj9bwOSSg1SVr6iAhn0MU5ZkBxGJxVcoX_zncgc4QJRHnFfiCXC6Kv-7xm76AheFN3FjexG_pvnupXm0J7mWN6GfaujkVoAX0vbIjNGzk262wzSvdlk-Nb6GVr4WSY2kI45k5wRT253zZ5aj-2zHgyUGoJKZ3xzNuydnENyCuagFXA7oZnQc3fdyO_B1M6TF2fn6Jgj5T7B_UwLMBq3oeSyq9v32n8O4HWMSLToC3veZSGfvuoiWt5XlNwchVQRyOqGBreL Feb 24 16:25:42 volumio-rpi4-olkkari volumio[843]: info: New access token = BQA1uVp47-qaBzkcDcAonHSDmmABJGMtMw7jV6nByZLwJWWwta5OuUaiuLnm8Y5hhJzo_61_YuD_-TnFBDbuRiK23nxrgPSiCePK3qCtsTFIedFvXMAtWS8dzopc4TO1JBioI_mnOtrzpCm64zCr1fozEl5JwmfeSyvSuOGiEKkeAJCvjiqAGtRaNtLOp3Lvwju8WviNh2GGcIms2ZsYEySlYSWBZg_CdfxJg-NPGAoUPyskEZefVDURgryulIUQSPVRL_WaSeD0lrHg50Y7nFH6IPNTMo2U Feb 24 16:25:42 volumio-rpi4-olkkari winbindd[1133]: [2026/02/24 16:25:42.223948, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 24 16:25:42 volumio-rpi4-olkkari winbindd[1133]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 24 16:25:42 volumio-rpi4-olkkari volumio[843]: info: New access token = BQBvlImdY7eWqXnoDPdjbGvSPzCCezjlH1fQGgyBbu0me1mxI2GNCf5BMhoMio1KkHhWaJc3wa4cfP22KYTkeUjQgnLPoDRk6WMDBD6N8Tc7CGhch0KErEPSVO3UtQGBXsUsUzy9oXtwyQFjZPvN57AbVH7vkrRpPWF68C6STphNeKkGao4ZoUTy9abKoPRolHIZgVYwRT6tizidIgzPQ0nPTx1KDpcKJodAQp4sDJMHyfZBzCATUf2DjXF5vunfr_aCoXo2UUQzXHw8p1Yvou_queI7oeJw Feb 24 16:25:42 volumio-rpi4-olkkari winbindd[1133]: [2026/02/24 16:25:42.272475, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 24 16:25:42 volumio-rpi4-olkkari winbindd[1133]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 24 16:25:42 volumio-rpi4-olkkari systemd[1]: Started Samba Winbind Daemon. Feb 24 16:25:42 volumio-rpi4-olkkari systemd[1]: Starting Samba SMB Daemon... Feb 24 16:25:42 volumio-rpi4-olkkari systemd[1]: apt-daily.service: Succeeded. Feb 24 16:25:42 volumio-rpi4-olkkari systemd[1]: Started Daily apt download activities. Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=info msg="zeroconf server listening on port 38349" Feb 24 16:25:42 volumio-rpi4-olkkari systemd[1]: Starting Daily apt upgrade and clean activities... Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=debug msg="obtained new client token: AAAT/MFnwFiCEbAfFvbWbVOHFpaEs6F5mp5Hac6EMvfuQNrcUVnUZ0gXqU8oqVoS+queThw5qS58XP9+IOCVgfHZug4M+ZHA2G9AywP2jHOZ4cROmZY/C7qyUKU1rcAcX6iOkBzfxq5b95w3hNAmo2hoNAntLZQOzLYECWEjEvWZ/5A8io8Vn3VT61kQAWZtf1MZkNBwYKOxtG7eyQx0RiKSH49MafdHLC0DKOPVxr2RKx6Jb+FxAGmY" Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=debug msg="completed keyexchange" Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=debug msg="completed challenge" Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=info msg="authenticated AP" username="ha***as" Feb 24 16:25:42 volumio-rpi4-olkkari systemd[1]: Started Samba SMB Daemon. Feb 24 16:25:42 volumio-rpi4-olkkari systemd[1]: Reached target Multi-User System. Feb 24 16:25:42 volumio-rpi4-olkkari smbd[1172]: [2026/02/24 16:25:42.870122, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 24 16:25:42 volumio-rpi4-olkkari systemd[1]: Reached target Graphical Interface. Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=info msg="authenticated Login5" username="ha***as" Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=debug msg="initializing zeroconf session" username="ha***as" Feb 24 16:25:42 volumio-rpi4-olkkari smbd[1172]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 24 16:25:42 volumio-rpi4-olkkari systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 24 16:25:42 volumio-rpi4-olkkari systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 24 16:25:42 volumio-rpi4-olkkari systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=debug msg="dealer connection opened" Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=trace msg="starting accesspoint recv loop" Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=trace msg="starting dealer recv loop" Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=trace msg="received accesspoint ping" Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 24 16:25:42 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:42+02:00" level=debug msg="received connection id: N2FiNWQzZDAtYzBm...RkZDQTg3NUU1OA==" Feb 24 16:25:43 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:43+02:00" level=trace msg="received accesspoint pong ack" Feb 24 16:25:43 volumio-rpi4-olkkari systemd[1]: apt-daily-upgrade.service: Succeeded. Feb 24 16:25:43 volumio-rpi4-olkkari systemd[1]: Started Daily apt upgrade and clean activities. Feb 24 16:25:43 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:43+02:00" level=debug msg="put connect state because NEW_DEVICE" Feb 24 16:25:43 volumio-rpi4-olkkari mpd[1065]: Feb 24 16:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 24 16:25:43 volumio-rpi4-olkkari systemd[1]: Started Music Player Daemon. Feb 24 16:25:43 volumio-rpi4-olkkari systemd[1]: Startup finished in 12.179s (kernel) + 17.741s (userspace) = 29.920s. Feb 24 16:25:43 volumio-rpi4-olkkari sudo[1050]: pam_unix(sudo:session): session closed for user root Feb 24 16:25:43 volumio-rpi4-olkkari sudo[1032]: pam_unix(sudo:session): session closed for user root Feb 24 16:25:43 volumio-rpi4-olkkari volumio[843]: info: Completed starting Core Plugins Feb 24 16:25:43 volumio-rpi4-olkkari volumio[843]: info: ------------------------------------------- Feb 24 16:25:43 volumio-rpi4-olkkari volumio[843]: info: ----- MyVolumio plugins startup ---- Feb 24 16:25:43 volumio-rpi4-olkkari volumio[843]: info: ------------------------------------------- Feb 24 16:25:43 volumio-rpi4-olkkari volumio[843]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 24 16:25:43 volumio-rpi4-olkkari volumio[843]: error: MPD error: The expression evaluated to a falsy value: Feb 24 16:25:43 volumio-rpi4-olkkari volumio[843]: assert.ok(self.idling) Feb 24 16:25:43 volumio-rpi4-olkkari volumio[843]: error: The expression evaluated to a falsy value: Feb 24 16:25:43 volumio-rpi4-olkkari volumio[843]: assert.ok(self.idling) Feb 24 16:25:43 volumio-rpi4-olkkari volumio[843]: error: updateQueue error: null Feb 24 16:25:43 volumio-rpi4-olkkari volumio[843]: info: MPD running with PID1065 Feb 24 16:25:43 volumio-rpi4-olkkari volumio[843]: ,establishing connection Feb 24 16:25:43 volumio-rpi4-olkkari volumio[843]: error: updateQueue error: null Feb 24 16:25:44 volumio-rpi4-olkkari volumio[843]: info: go-librespot daemon successfully initialized Feb 24 16:25:44 volumio-rpi4-olkkari volumio[843]: error: [LastFM] Error: Invalid method signature supplied Feb 24 16:25:44 volumio-rpi4-olkkari volumio[843]: info: [LastFM] finished init with error: Error: Invalid method signature supplied Feb 24 16:25:45 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:45+02:00" level=debug msg="handling transfer player command from bafaf783f73f4b9e493584d73f1b263330fab9b3" Feb 24 16:25:45 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:45+02:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1E4Bn03wOKzTWm" Feb 24 16:25:45 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:45+02:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:37i9dQZF1E4Bn03wOKzTWm" Feb 24 16:25:45 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:45+02:00" level=warning msg="failed seeking to track in context spotify:playlist:37i9dQZF1E4Bn03wOKzTWm" error="could not find track" uri="spotify:playlist:37i9dQZF1E4Bn03wOKzTWm" Feb 24 16:25:45 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:45+02:00" level=debug msg="loading track (paused: true, position: 2293ms)" uri="spotify:track:2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:45 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:45+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 16:25:45 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:45+02:00" level=trace msg="emitting websocket event: will_play" Feb 24 16:25:45 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:45+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Feb 24 16:25:45 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:45+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1341" Feb 24 16:25:45 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:45+02:00" level=debug msg="selected format OGG_VORBIS_320 (db076aa2cf5b73fd7d5e7aa99ff160f9dd6ce817)" uri="spotify:track:2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:45 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:45+02:00" level=debug msg="requested aes key for file db076aa2cf5b73fd7d5e7aa99ff160f9dd6ce817, gid: 2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:45 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:45+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:45 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:45+02:00" level=debug msg="fetched first chunk of 23, total size is 11879524 bytes" uri="spotify:track:2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:45 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:45+02:00" level=trace msg="seek to 2293ms (diff: 112ms, samples: 101121, bytes: 69087)" uri="spotify:track:2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:45 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:45+02:00" level=debug msg="created new output device" Feb 24 16:25:45 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:45+02:00" level=info msg="loaded track \"Faija (feat. Nopsajalka)\" (paused: true, position: 2293ms, duration: 254360ms, prefetched: false)" uri="spotify:track:2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:45 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:45+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1121" Feb 24 16:25:45 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:45+02:00" level=debug msg="fetched chunk 3/22, size: 524288" uri="spotify:track:2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:46 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:46+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 16:25:46 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:46+02:00" level=trace msg="emitting websocket event: metadata" Feb 24 16:25:46 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:46+02:00" level=trace msg="emitting websocket event: active" Feb 24 16:25:46 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:46+02:00" level=debug msg="sending successful reply for dealer request" Feb 24 16:25:46 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:46+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Feb 24 16:25:46 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:46+02:00" level=debug msg="fetched chunk 1/22, size: 524288" uri="spotify:track:2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:46 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:46+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 24 16:25:46 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:46+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 24 16:25:46 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:46+02:00" level=debug msg="fetched chunk 2/22, size: 524288" uri="spotify:track:2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:46 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:46+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 16:25:46 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:46+02:00" level=trace msg="emitting websocket event: paused" Feb 24 16:25:46 volumio-rpi4-olkkari volumio[843]: info: Volumio Network Manager: Network status updated: 1 Feb 24 16:25:46 volumio-rpi4-olkkari sudo[1260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 16:25:46 volumio-rpi4-olkkari sudo[1260]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:25:46 volumio-rpi4-olkkari sudo[1260]: pam_unix(sudo:session): session closed for user root Feb 24 16:25:46 volumio-rpi4-olkkari sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 16:25:46 volumio-rpi4-olkkari sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:25:46 volumio-rpi4-olkkari sudo[1262]: pam_unix(sudo:session): session closed for user root Feb 24 16:25:46 volumio-rpi4-olkkari sudo[1266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 24 16:25:46 volumio-rpi4-olkkari sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:25:46 volumio-rpi4-olkkari systemd[1]: Started UPnP Renderer front-end to MPD. Feb 24 16:25:46 volumio-rpi4-olkkari sudo[1266]: pam_unix(sudo:session): session closed for user root Feb 24 16:25:46 volumio-rpi4-olkkari volumio[843]: info: Upmpdcli Daemon Started Feb 24 16:25:46 volumio-rpi4-olkkari volumio[1268]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: info: Initializing connection to go-librespot Websocket Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=debug msg="new websocket client" Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: info: Connection to go-librespot Websocket established Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=debug msg="handling skip_prev player command from bafaf783f73f4b9e493584d73f1b263330fab9b3" Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=debug msg="skip previous track" Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=debug msg="loading track (paused: true, position: 0ms)" uri="spotify:track:2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=trace msg="emitting websocket event: will_play" Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZF1E4Bn03wOKzTWm","uri":"spotify:track:2JL88tR6weJBHXSQnsp1oo","play_origin":"playlist"}} Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=debug msg="selected format OGG_VORBIS_320 (db076aa2cf5b73fd7d5e7aa99ff160f9dd6ce817)" uri="spotify:track:2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=debug msg="requested aes key for file db076aa2cf5b73fd7d5e7aa99ff160f9dd6ce817, gid: 2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=debug msg="fetched first chunk of 23, total size is 11879524 bytes" uri="spotify:track:2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=info msg="loaded track \"Faija (feat. Nopsajalka)\" (paused: true, position: 0ms, duration: 254360ms, prefetched: false)" uri="spotify:track:2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=debug msg="fetched chunk 3/22, size: 524288" uri="spotify:track:2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=trace msg="emitting websocket event: metadata" Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=debug msg="sending successful reply for dealer request" Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2JL88tR6weJBHXSQnsp1oo","name":"Faija (feat. Nopsajalka)","artist_names":["JVG","Nopsajalka"],"album_name":"Mustaa kultaa","album_cover_url":"https://i.scdn.co/image/ab67616d00001e022ac2f1a9e516a7ceaf3bf440","position":0,"duration":254360,"release_date":"year:2011 month:5 day:2","track_number":5,"disc_number":1}} Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=debug msg="fetched chunk 1/22, size: 524288" uri="spotify:track:2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=debug msg="fetched chunk 2/22, size: 524288" uri="spotify:track:2JL88tR6weJBHXSQnsp1oo" Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 16:25:47 volumio-rpi4-olkkari go-librespot[1101]: time="2026-02-24T16:25:47+02:00" level=trace msg="emitting websocket event: paused" Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1E4Bn03wOKzTWm","uri":"spotify:track:2JL88tR6weJBHXSQnsp1oo","play_origin":"playlist"}} Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: TypeError: Cannot read property 'service' of undefined Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:280:18) Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: at WebSocket.emit (events.js:315:20) Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: at Receiver.emit (events.js:315:20) Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: at writeOrBuffer (internal/streams/writable.js:358:12) Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: at Socket.emit (events.js:315:20) Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: at addChunk (internal/streams/readable.js:309:12) Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: at readableAddChunk (internal/streams/readable.js:284:9) Feb 24 16:25:47 volumio-rpi4-olkkari volumio[843]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 16:25:48 volumio-rpi4-olkkari sudo[1293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-24 16:24 Feb 24 16:25:48 volumio-rpi4-olkkari sudo[1293]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" 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 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"