Dec 15 05:05:00 radiozelva volumio[24735]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 15 05:05:00 radiozelva volumio[24735]: SPOTIFY: BQAg5wticMJ7YvXIO2jg5GZon1asdOi-tOGNDDmIVpQcxbqa3oZAFw4nxXOMmNYkBUBYzx98w3wGhCj26NIR49yHI_n7c6nRrinHSIojcisSHp_fKPqEHXDe8OSZAmipefocI9UhKsqOQvpUxWomXPdKCkZU5wk82aPHUHpH1WkJANoijl_Qj_y4QMr5ko5Nsrh2qKnGrdXNHIJWrA5c5Xml9tmEVhcB5_CASzGyDxBQ7BO6f8iXPTu7y5urFwWd62QiiDp2DtbNDTbHlPyvTiuhwAmI_tX-gXZjT274SP4nEMRjhrD6ExYQLR38 Dec 15 05:05:00 radiozelva volumio[24735]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 15 05:05:00 radiozelva volumio[24735]: info: New Spotify access token = BQAg5wticMJ7YvXIO2jg5GZon1asdOi-tOGNDDmIVpQcxbqa3oZAFw4nxXOMmNYkBUBYzx98w3wGhCj26NIR49yHI_n7c6nRrinHSIojcisSHp_fKPqEHXDe8OSZAmipefocI9UhKsqOQvpUxWomXPdKCkZU5wk82aPHUHpH1WkJANoijl_Qj_y4QMr5ko5Nsrh2qKnGrdXNHIJWrA5c5Xml9tmEVhcB5_CASzGyDxBQ7BO6f8iXPTu7y5urFwWd62QiiDp2DtbNDTbHlPyvTiuhwAmI_tX-gXZjT274SP4nEMRjhrD6ExYQLR38 Dec 15 05:05:00 radiozelva volumio[24735]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 15 05:05:00 radiozelva volumio[24735]: info: CoreCommandRouter::volumioGetVisibleSources Dec 15 05:05:00 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 05:05:00 radiozelva volumio[24735]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:00 radiozelva volumio[24735]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:00 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 15 05:05:00 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 15 05:05:00 radiozelva volumio[24735]: info: Received Get System Info Dec 15 05:05:00 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 05:05:00 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 05:05:00 radiozelva volumio[24735]: info: Discovery: Getting this device information Dec 15 05:05:00 radiozelva volumio[24735]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:00 radiozelva volumio[24735]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:00 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 05:05:00 radiozelva volumio[24735]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:00 radiozelva volumio[24735]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:00 radiozelva volumio[24735]: info: Listing playlists Dec 15 05:05:00 radiozelva volumio[24735]: info: CoreCommandRouter::volumioGetQueue Dec 15 05:05:00 radiozelva volumio[24735]: info: CoreStateMachine::getQueue Dec 15 05:05:00 radiozelva volumio[24735]: info: CorePlayQueue::getQueue Dec 15 05:05:00 radiozelva volumio[24735]: info: Shairport-Sync Started Dec 15 05:05:00 radiozelva volumio[24735]: Error adding Membership: Error: addMembership EINVAL Dec 15 05:05:00 radiozelva volumio[24735]: info: Shairport-Sync Started Dec 15 05:05:00 radiozelva volumio[24735]: info: Shairport-Sync Started Dec 15 05:05:00 radiozelva volumio[24735]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.104 UA: Mozilla/5.0 (Linux; Android 16; CPH2645 Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 15 05:05:00 radiozelva sudo[24988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 15 05:05:00 radiozelva sudo[24988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:00 radiozelva sudo[24988]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:00 radiozelva go-librespot[24956]: time="2025-12-15T05:05:00+01: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]" Dec 15 05:05:00 radiozelva go-librespot[24956]: time="2025-12-15T05:05:00+01: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]" Dec 15 05:05:00 radiozelva go-librespot[24956]: time="2025-12-15T05:05:00+01: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]" Dec 15 05:05:00 radiozelva go-librespot[24956]: time="2025-12-15T05:05:00+01:00" level=info msg="zeroconf server listening on port 37925" Dec 15 05:05:00 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 15 05:05:00 radiozelva volumio[24735]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:00 radiozelva go-librespot[24956]: time="2025-12-15T05:05:00+01:00" level=debug msg="obtained new client token: AADulwkdDcjjpa/0MYWiVaz5VCYI3AyNaByiUxGeY2GwQw2BOyItEBW9ItdK57NT/shz69vDRZVAnTQMF5i3Nwh5WcNF85Hz20eoR3quy88z0LsYc7yK0+oBFSxWmKAmsopUweTjpLtyzf7py2zI/wjquN8SIce/KawM5PpHDx5QH1AQ1ewcX0k2fJeX3Qn2f3nk9Hz6Fn7B69dkDDr7zbSwjVmGW7LxQESvBF9aj/aMGSBCAAoYZEyPwhE=" Dec 15 05:05:00 radiozelva volumio[24735]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:00 radiozelva volumio[24735]: info: Upmpdcli Daemon Started Dec 15 05:05:00 radiozelva go-librespot[24956]: time="2025-12-15T05:05:00+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 05:05:00 radiozelva volumio[24735]: SPOTIFY: User informations: {"country":"CZ","display_name":"JiříP","email":"plucnaraj@seznam.cz","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31l2amtke6dqqywkn7igkzamptmi"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31l2amtke6dqqywkn7igkzamptmi","id":"31l2amtke6dqqywkn7igkzamptmi","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee850f544f353eb0c7d5b667547a","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b820f544f353eb0c7d5b667547a","width":64}],"product":"premium","type":"user","uri":"spotify:user:31l2amtke6dqqywkn7igkzamptmi"} Dec 15 05:05:00 radiozelva volumio[24735]: info: Spotify Successfully logged in Dec 15 05:05:00 radiozelva volumio[24735]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 05:05:00 radiozelva volumio[24735]: info: [1765771500864] CoreMusicLibrary::Adding element Spotify Dec 15 05:05:00 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 05:05:00 radiozelva volumio[24735]: Cannot find translation for source Spotify Dec 15 05:05:00 radiozelva go-librespot[24956]: time="2025-12-15T05:05:00+01:00" level=debug msg="completed keyexchange" Dec 15 05:05:00 radiozelva go-librespot[24956]: time="2025-12-15T05:05:00+01:00" level=debug msg="completed challenge" Dec 15 05:05:00 radiozelva go-librespot[24956]: time="2025-12-15T05:05:00+01:00" level=info msg="authenticated AP" username="31************************mi" Dec 15 05:05:01 radiozelva go-librespot[24956]: time="2025-12-15T05:05:01+01:00" level=info msg="authenticated Login5" username="31************************mi" Dec 15 05:05:01 radiozelva go-librespot[24956]: time="2025-12-15T05:05:01+01:00" level=debug msg="initializing zeroconf session" username="31************************mi" Dec 15 05:05:01 radiozelva go-librespot[24956]: time="2025-12-15T05:05:01+01:00" level=debug msg="dealer connection opened" Dec 15 05:05:01 radiozelva go-librespot[24956]: time="2025-12-15T05:05:01+01:00" level=trace msg="starting accesspoint recv loop" Dec 15 05:05:01 radiozelva go-librespot[24956]: time="2025-12-15T05:05:01+01:00" level=trace msg="starting dealer recv loop" Dec 15 05:05:01 radiozelva go-librespot[24956]: time="2025-12-15T05:05:01+01:00" level=trace msg="received accesspoint ping" Dec 15 05:05:01 radiozelva go-librespot[24956]: time="2025-12-15T05:05:01+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 15 05:05:01 radiozelva go-librespot[24956]: time="2025-12-15T05:05:01+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 15 05:05:01 radiozelva go-librespot[24956]: time="2025-12-15T05:05:01+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 15 05:05:01 radiozelva go-librespot[24956]: time="2025-12-15T05:05:01+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 15 05:05:01 radiozelva go-librespot[24956]: time="2025-12-15T05:05:01+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 15 05:05:01 radiozelva go-librespot[24956]: time="2025-12-15T05:05:01+01:00" level=debug msg="received connection id: MDIxNzIxYTYtYWYz...MjdCNzU3RkVFOQ==" Dec 15 05:05:01 radiozelva go-librespot[24956]: time="2025-12-15T05:05:01+01:00" level=trace msg="received accesspoint pong ack" Dec 15 05:05:01 radiozelva go-librespot[24956]: time="2025-12-15T05:05:01+01:00" level=debug msg="put connect state because NEW_DEVICE" Dec 15 05:05:01 radiozelva sudo[25003]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 05:05:01 radiozelva sudo[25001]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 05:05:01 radiozelva sudo[25001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:01 radiozelva sudo[25003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:01 radiozelva sudo[25001]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:01 radiozelva sudo[25003]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:01 radiozelva volumio[24735]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.104 UA: Mozilla/5.0 (Linux; Android 16; CPH2645 Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 15 05:05:01 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 15 05:05:01 radiozelva volumio[24735]: info: CoreCommandRouter::volumioGetVisibleSources Dec 15 05:05:01 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 05:05:01 radiozelva volumio[24735]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:01 radiozelva volumio[24735]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:01 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 15 05:05:01 radiozelva volumio[24735]: info: CoreCommandRouter::volumioGetQueue Dec 15 05:05:01 radiozelva volumio[24735]: info: CoreStateMachine::getQueue Dec 15 05:05:01 radiozelva volumio[24735]: info: CorePlayQueue::getQueue Dec 15 05:05:01 radiozelva volumio[24735]: info: Listing playlists Dec 15 05:05:01 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 15 05:05:01 radiozelva volumio[24735]: info: Received Get System Info Dec 15 05:05:01 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 05:05:01 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 05:05:01 radiozelva volumio[24735]: info: Discovery: Getting this device information Dec 15 05:05:01 radiozelva volumio[24735]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:01 radiozelva volumio[24735]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:01 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 05:05:01 radiozelva volumio[24735]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:01 radiozelva volumio[24735]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:01 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 15 05:05:01 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 15 05:05:02 radiozelva volumio[24735]: info: minidlna: Found miniDLNA version 1.3.0 Dec 15 05:05:02 radiozelva volumio[24735]: info: minidlna: Starting minidlna.service Dec 15 05:05:02 radiozelva sudo[25013]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Dec 15 05:05:02 radiozelva sudo[25013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:02 radiozelva sudo[25013]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:02 radiozelva volumio[24735]: info: minidlna: systemctl start minidlna.service succeeded. Dec 15 05:05:02 radiozelva volumio[24735]: info: go-librespot daemon successfully initialized Dec 15 05:05:03 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 15 05:05:03 radiozelva volumio[24735]: info: Received Get System Info Dec 15 05:05:03 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 05:05:03 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 05:05:03 radiozelva volumio[24735]: info: Discovery: Getting this device information Dec 15 05:05:03 radiozelva volumio[24735]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:03 radiozelva volumio[24735]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:03 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 05:05:03 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 15 05:05:03 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 15 05:05:03 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 15 05:05:03 radiozelva volumio[24735]: info: Received Get System Info Dec 15 05:05:03 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 05:05:03 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 05:05:03 radiozelva volumio[24735]: info: Discovery: Getting this device information Dec 15 05:05:03 radiozelva volumio[24735]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:03 radiozelva volumio[24735]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:03 radiozelva volumio[24735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 05:05:03 radiozelva volumio[24735]: info: Preload queue cleared Dec 15 05:05:03 radiozelva volumio[24735]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 15 05:05:03 radiozelva volumio[24735]: info: CoreStateMachine::ClearQueue Dec 15 05:05:03 radiozelva volumio[24735]: info: CoreStateMachine::stop Dec 15 05:05:03 radiozelva volumio[24735]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 05:05:03 radiozelva volumio[24735]: info: CorePlayQueue::clearPlayQueue Dec 15 05:05:03 radiozelva volumio[24735]: info: CorePlayQueue::saveQueue Dec 15 05:05:03 radiozelva volumio[24735]: info: CoreCommandRouter::volumioPushQueue Dec 15 05:05:03 radiozelva volumio[24735]: info: CoreStateMachine::addQueueItems Dec 15 05:05:03 radiozelva volumio[24735]: info: CorePlayQueue::addQueueItems Dec 15 05:05:03 radiozelva volumio[24735]: info: Preload queue cleared Dec 15 05:05:03 radiozelva volumio[24735]: info: Adding Item to queue: music-library/USB/8726816d-c5e7-4d32-a90c-046dc3305599/tišící-meditace3hzdeltahluboká-regenerace-organismu-s-mým-zpěvem.mp3 Dec 15 05:05:03 radiozelva volumio[24735]: info: Exploding uri music-library/USB/8726816d-c5e7-4d32-a90c-046dc3305599/tišící-meditace3hzdeltahluboká-regenerace-organismu-s-mým-zpěvem.mp3 in service mpd Dec 15 05:05:03 radiozelva volumio[24735]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 05:05:03 radiozelva volumio[24735]: TypeError: Cannot read properties of undefined (reading 'then') Dec 15 05:05:03 radiozelva volumio[24735]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2546:19) Dec 15 05:05:03 radiozelva volumio[24735]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2344:21) Dec 15 05:05:03 radiozelva volumio[24735]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) Dec 15 05:05:03 radiozelva volumio[24735]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Dec 15 05:05:03 radiozelva volumio[24735]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Dec 15 05:05:03 radiozelva volumio[24735]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Dec 15 05:05:03 radiozelva volumio[24735]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:251:25) Dec 15 05:05:03 radiozelva volumio[24735]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:511:25) Dec 15 05:05:03 radiozelva volumio[24735]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Dec 15 05:05:03 radiozelva volumio[24735]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Dec 15 05:05:03 radiozelva volumio[24735]: at Socket.emit (node:events:514:28) Dec 15 05:05:03 radiozelva volumio[24735]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 15 05:05:03 radiozelva volumio[24735]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Dec 15 05:05:03 radiozelva volumio[24735]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 05:05:04 radiozelva systemd[1]: mpd.service: Deactivated successfully. Dec 15 05:05:04 radiozelva systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 15 05:05:04 radiozelva systemd[1]: mpd.service: Consumed 14min 10.208s CPU time. Dec 15 05:05:04 radiozelva systemd[1]: mpd.socket: Deactivated successfully. Dec 15 05:05:04 radiozelva mpd_monitor.sh[25020]: MPD error: Connection reset by peer Dec 15 05:05:04 radiozelva systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 15 05:05:04 radiozelva systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 15 05:05:04 radiozelva mpd_monitor.sh[1738]: MPD Monitor Service: MPD Appears to be inactive, restarting Dec 15 05:05:04 radiozelva mpd_monitor.sh[25022]: mpd: no process found Dec 15 05:05:04 radiozelva systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 15 05:05:04 radiozelva systemd[1]: Starting mpd.service - Music Player Daemon... Dec 15 05:05:04 radiozelva systemd[1]: mpd.service: Deactivated successfully. Dec 15 05:05:04 radiozelva systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 15 05:05:04 radiozelva systemd[1]: mpd.socket: Deactivated successfully. Dec 15 05:05:04 radiozelva systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 15 05:05:04 radiozelva systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 15 05:05:04 radiozelva systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 15 05:05:04 radiozelva systemd[1]: Starting mpd.service - Music Player Daemon... Dec 15 05:05:04 radiozelva sudo[25034]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 15 05:05:04 radiozelva sudo[25034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 15 05:05:04 radiozelva sudo[25034]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:05 radiozelva sudo[25041]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-15 05:04' Dec 15 05:05:05 radiozelva sudo[25041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:05 radiozelva sudo[25041]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:06 radiozelva volumio-remote-updater[608]: [2025-12-15 05:05:06] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 15 05:05:06 radiozelva volumio-remote-updater[608]: [2025-12-15 05:05:06] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 15 05:05:06 radiozelva systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 15 05:05:06 radiozelva sudo[24872]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:06 radiozelva sudo[24864]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:06 radiozelva systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 15 05:05:06 radiozelva systemd[1]: volumio.service: Consumed 33.993s CPU time. Dec 15 05:05:06 radiozelva systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 15 05:05:06 radiozelva systemd[1]: dynamicswap.service: Deactivated successfully. Dec 15 05:05:06 radiozelva systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Dec 15 05:05:06 radiozelva systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 15 05:05:06 radiozelva systemd[1]: Stopped volumio.service - Volumio Backend Module. Dec 15 05:05:06 radiozelva systemd[1]: volumio.service: Consumed 33.993s CPU time. Dec 15 05:05:06 radiozelva systemd[1]: Started volumio.service - Volumio Backend Module. Dec 15 05:05:06 radiozelva systemd[1]: dynamicswap.service: Deactivated successfully. Dec 15 05:05:09 radiozelva volumio[25063]: info: ------------------------------------------- Dec 15 05:05:09 radiozelva volumio[25063]: info: ----- Volumio3 ---- Dec 15 05:05:09 radiozelva volumio[25063]: info: ------------------------------------------- Dec 15 05:05:09 radiozelva volumio[25063]: info: ----- System startup ---- Dec 15 05:05:09 radiozelva volumio[25063]: info: ------------------------------------------- Dec 15 05:05:11 radiozelva volumio-remote-updater[608]: [2025-12-15 05:05:11] [connect] Successful connection Dec 15 05:05:11 radiozelva volumio[25063]: info: MYVOLUMIO Environment detected Dec 15 05:05:11 radiozelva mpd[25038]: 2025-12-15T05:05:11 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 15 05:05:11 radiozelva systemd[1]: Started mpd.service - Music Player Daemon. Dec 15 05:05:11 radiozelva mpd_monitor.sh[1738]: MPD Monitor Service: MPD restarted due to no mpc output. Dec 15 05:05:11 radiozelva volumio[25063]: info: Plugin folders cleanup Dec 15 05:05:11 radiozelva volumio[25063]: info: Scanning into folder /volumio/app/plugins/ Dec 15 05:05:11 radiozelva volumio[25063]: info: Scanning category audio_interface Dec 15 05:05:11 radiozelva volumio[25063]: info: Scanning category miscellanea Dec 15 05:05:11 radiozelva volumio[25063]: info: Scanning category music_service Dec 15 05:05:11 radiozelva volumio[25063]: info: Scanning category plugins.json Dec 15 05:05:11 radiozelva volumio[25063]: info: Scanning category system_controller Dec 15 05:05:11 radiozelva volumio[25063]: info: Scanning category user_interface Dec 15 05:05:11 radiozelva volumio[25063]: info: Scanning into folder /data/plugins/ Dec 15 05:05:11 radiozelva volumio[25063]: info: Scanning category audio_interface Dec 15 05:05:11 radiozelva volumio[25063]: info: Scanning category music_service Dec 15 05:05:11 radiozelva volumio[25063]: info: Plugin folders cleanup completed Dec 15 05:05:11 radiozelva volumio[25063]: info: ------------------------------------------- Dec 15 05:05:11 radiozelva volumio[25063]: info: ----- Core plugins startup ---- Dec 15 05:05:11 radiozelva volumio[25063]: info: ------------------------------------------- Dec 15 05:05:11 radiozelva volumio[25063]: info: Loading plugins from folder /volumio/app/plugins/ Dec 15 05:05:11 radiozelva volumio[25063]: info: Adding plugin upnp to MyMusic Plugins Dec 15 05:05:11 radiozelva volumio[25063]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 15 05:05:11 radiozelva volumio[25063]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 15 05:05:11 radiozelva volumio[25063]: info: Loading plugins from folder /data/plugins/ Dec 15 05:05:11 radiozelva volumio[25063]: info: Loading plugin "system"... Dec 15 05:05:11 radiozelva volumio[25063]: info: Loading plugin "appearance"... Dec 15 05:05:13 radiozelva volumio[25063]: info: Loading plugin "network"... Dec 15 05:05:13 radiozelva volumio[25063]: info: Refreshing Cached IP Addresses Dec 15 05:05:13 radiozelva sudo[25095]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 05:05:13 radiozelva sudo[25095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:13 radiozelva volumio[25063]: info: Loading plugin "services"... Dec 15 05:05:13 radiozelva sudo[25097]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 05:05:13 radiozelva sudo[25097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:13 radiozelva volumio[25063]: info: Loading plugin "alsa_controller"... Dec 15 05:05:13 radiozelva sudo[25097]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:13 radiozelva sudo[25095]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:13 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 05:05:13 radiozelva volumio[25063]: info: Loading plugin "wizard"... Dec 15 05:05:13 radiozelva volumio[25063]: info: Loading plugin "networkfs"... Dec 15 05:05:13 radiozelva volumio[25063]: info: Starting Udev Watcher for removable devices Dec 15 05:05:13 radiozelva volumio[25063]: info: Ignoring mount for partition: boot Dec 15 05:05:13 radiozelva volumio[25063]: info: Ignoring mount for partition: volumio Dec 15 05:05:13 radiozelva volumio[25063]: info: Ignoring mount for partition: volumio_data Dec 15 05:05:13 radiozelva volumio[25063]: info: Mounting Device 8726816d-c5e7-4d32-a90c-046dc3305599 Dec 15 05:05:13 radiozelva sudo[25131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/8726816d-c5e7-4d32-a90c-046dc3305599 -o noatime Dec 15 05:05:13 radiozelva sudo[25131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:13 radiozelva sudo[25131]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:13 radiozelva volumio[25063]: mount: /media/8726816d-c5e7-4d32-a90c-046dc3305599: /dev/sda1 already mounted on /media/8726816d-c5e7-4d32-a90c-046dc3305599. Dec 15 05:05:13 radiozelva volumio[25063]: dmesg(1) may have more information after failed mount system call. Dec 15 05:05:13 radiozelva volumio[25063]: error: Failed to mount 8726816d-c5e7-4d32-a90c-046dc3305599: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/8726816d-c5e7-4d32-a90c-046dc3305599" -o noatime Dec 15 05:05:13 radiozelva volumio[25063]: mount: /media/8726816d-c5e7-4d32-a90c-046dc3305599: /dev/sda1 already mounted on /media/8726816d-c5e7-4d32-a90c-046dc3305599. Dec 15 05:05:13 radiozelva volumio[25063]: dmesg(1) may have more information after failed mount system call. Dec 15 05:05:13 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 15 05:05:13 radiozelva volumio[25063]: info: Loading plugin "volumio_command_line_client"... Dec 15 05:05:13 radiozelva volumio[25063]: info: Loading plugin "upnp"... Dec 15 05:05:13 radiozelva volumio[25063]: info: [1765771513870] Starting Upmpd Daemon Dec 15 05:05:13 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 15 05:05:13 radiozelva volumio[25063]: info: Loading plugin "my_music"... Dec 15 05:05:13 radiozelva volumio[25063]: info: Loading plugin "mpd"... Dec 15 05:05:14 radiozelva volumio[25063]: info: Loading plugin "upnp_browser"... Dec 15 05:05:16 radiozelva volumio[25063]: info: Starting UPNP Browser Dec 15 05:05:16 radiozelva volumio[25063]: info: Loading plugin "alarm-clock"... Dec 15 05:05:16 radiozelva volumio[25063]: info: Loading plugin "airplay_emulation"... Dec 15 05:05:16 radiozelva volumio[25063]: info: Starting Shairport Sync Dec 15 05:05:16 radiozelva volumio[25063]: info: Loading plugin "last_100"... Dec 15 05:05:16 radiozelva volumio[25063]: info: Loading plugin "webradio"... Dec 15 05:05:17 radiozelva volumio[25063]: info: Loading plugin "i2s_dacs"... Dec 15 05:05:17 radiozelva volumio[25063]: info: I2S DAC not set, start Auto-detection Dec 15 05:05:17 radiozelva volumio[25063]: info: Loading plugin "volumiodiscovery"... Dec 15 05:05:17 radiozelva volumio[25063]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 15 05:05:17 radiozelva volumio[25063]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 05:05:17 radiozelva volumio[25063]: *** WARNING *** For more information see Dec 15 05:05:17 radiozelva volumio[25063]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 15 05:05:17 radiozelva volumio[25063]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 05:05:17 radiozelva volumio[25063]: *** WARNING *** For more information see Dec 15 05:05:17 radiozelva node[25063]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 15 05:05:17 radiozelva node[25063]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 05:05:17 radiozelva node[25063]: *** WARNING *** For more information see Dec 15 05:05:17 radiozelva node[25063]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 15 05:05:17 radiozelva node[25063]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 05:05:17 radiozelva node[25063]: *** WARNING *** For more information see Dec 15 05:05:17 radiozelva volumio[25063]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 15 05:05:17 radiozelva volumio[25063]: info: Discovery: Started advertising with name: Radiozelva Dec 15 05:05:17 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 15 05:05:17 radiozelva volumio[25063]: info: Loading plugin "spop"... Dec 15 05:05:18 radiozelva volumio[25063]: info: Loading plugin "outputs"... Dec 15 05:05:18 radiozelva volumio[25063]: info: Loading plugin "albumart"... Dec 15 05:05:18 radiozelva volumio[25063]: info: Plugin example_plugin is not enabled Dec 15 05:05:19 radiozelva volumio[25063]: info: Loading plugin "inputs"... Dec 15 05:05:19 radiozelva volumio[25063]: info: Loading plugin "updater_comm"... Dec 15 05:05:19 radiozelva volumio[25063]: info: Plugin mpdemulation is not enabled Dec 15 05:05:19 radiozelva volumio[25063]: info: Loading plugin "rest_api"... Dec 15 05:05:19 radiozelva volumio[25063]: info: Loading plugin "websocket"... Dec 15 05:05:19 radiozelva volumio[25063]: info: Starting Socket.io Server version 1.7.4 Dec 15 05:05:19 radiozelva volumio[25063]: info: Loading plugin "mpdoutput"... Dec 15 05:05:19 radiozelva volumio[25063]: info: Loading plugin "minidlna"... Dec 15 05:05:19 radiozelva volumio[25063]: info: Applying required configuration parameters for plugin minidlna Dec 15 05:05:19 radiozelva volumio[25136]: Forking 3 albumart workers Dec 15 05:05:19 radiozelva volumio[25063]: info: Loading i18n strings for locale cs Dec 15 05:05:19 radiozelva volumio[25063]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Dec 15 05:05:19 radiozelva volumio[25063]: Updating browse sources language Dec 15 05:05:19 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreCommandRouter::initPlayerControls Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 05:05:20 radiozelva volumio[25063]: Express server listening on port 3000 Dec 15 05:05:20 radiozelva volumio[25063]: [Metrics] WebUI: 11s 373.03ms Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreStateMachine::resetVolumioState Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreStateMachine::getcurrentVolume Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreCommandRouter::volumioRetrievevolume Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:20 radiozelva volumio[25063]: info: Volumio Network Manager: Network status updated: 1 Dec 15 05:05:20 radiozelva volumio[25063]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.104 UA: Mozilla/5.0 (Linux; Android 16; CPH2645 Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 15 05:05:20 radiozelva volumio[25063]: verbose: New Socket.io Connection to 192.168.1.103:3000 from 192.168.1.104 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Dec 15 05:05:20 radiozelva volumio[25063]: info: Cannot read play queue from file Dec 15 05:05:20 radiozelva volumio[25063]: info: VolumeController:: Volume=77 Mute =false Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreStateMachine::pushState Dec 15 05:05:20 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreCommandRouter::volumioPushState Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreStateMachine::updateTrackBlock Dec 15 05:05:20 radiozelva volumio[25063]: info: CorePlayQueue::getTrackBlock Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreCommandRouter::volumioRetrievevolume Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreStateMachine::setRepeat null single undefined Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreStateMachine::pushState Dec 15 05:05:20 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreCommandRouter::volumioPushState Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreStateMachine::setRandom null Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreStateMachine::pushState Dec 15 05:05:20 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:20 radiozelva volumio[25063]: info: CoreCommandRouter::volumioPushState Dec 15 05:05:21 radiozelva volumio[25063]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 15 05:05:21 radiozelva volumio[25063]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Dec 15 05:05:21 radiozelva volumio[25063]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 15 05:05:21 radiozelva volumio[25063]: info: Setting Device type: Raspberry PI Dec 15 05:05:21 radiozelva volumio[25063]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.104 UA: Mozilla/5.0 (Linux; Android 16; CPH2645 Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 15 05:05:21 radiozelva volumio[25063]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.104 UA: Mozilla/5.0 (Linux; Android 16; CPH2645 Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 15 05:05:21 radiozelva volumio[25063]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.104 UA: Mozilla/5.0 (Linux; Android 16; CPH2645 Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 15 05:05:21 radiozelva volumio[25063]: info: VolumeController:: Volume=77 Mute =false Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreStateMachine::pushState Dec 15 05:05:21 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::volumioPushState Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 05:05:21 radiozelva volumio[25063]: info: Discovery: Getting this device information Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:21 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 05:05:21 radiozelva volumio[25063]: info: Discovery: adding 69726dd2-3b92-4cfd-805e-811b61331101 Dec 15 05:05:21 radiozelva volumio[25063]: info: Discovery: Found device Radiozelva Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:21 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:21 radiozelva volumio[25063]: info: Discovery: this is already registered, 69726dd2-3b92-4cfd-805e-811b61331101 Dec 15 05:05:21 radiozelva volumio[25063]: info: Discovery: Found device Radiozelva Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:21 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:21 radiozelva volumio[25063]: info: Completed loading Core Plugins Dec 15 05:05:21 radiozelva volumio[25063]: info: Preparing to generate the ALSA configuration file Dec 15 05:05:21 radiozelva volumio[25063]: verbose: New Socket.io Connection to 192.168.1.103:3000 from 192.168.1.104 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 15 05:05:21 radiozelva volumio[25063]: info: Asound.conf file unchanged, so no further update is needed Dec 15 05:05:21 radiozelva volumio[25063]: info: Output device has changed, restarting MPD Dec 15 05:05:21 radiozelva volumio[25063]: info: Output device has changed, restarting Shairport Sync Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:21 radiozelva volumio[25146]: Starting albumart workers Dec 15 05:05:21 radiozelva volumio[25148]: Starting albumart workers Dec 15 05:05:21 radiozelva volumio[25147]: Starting albumart workers Dec 15 05:05:21 radiozelva volumio[25063]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 05:05:21 radiozelva volumio[25063]: info: ___________ START PLUGINS ___________ Dec 15 05:05:21 radiozelva volumio[25063]: info: ControllerMpd::onStart: Initializing MPD Dec 15 05:05:21 radiozelva volumio[25063]: info: Creating MPD Configuration file Dec 15 05:05:21 radiozelva sudo[25193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 15 05:05:21 radiozelva sudo[25195]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 15 05:05:21 radiozelva sudo[25193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:21 radiozelva sudo[25195]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 05:05:21 radiozelva volumio[25063]: info: [1765771521667] CoreMusicLibrary::Adding element Média servery Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 05:05:21 radiozelva sudo[25193]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:21 radiozelva sudo[25202]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 15 05:05:21 radiozelva sudo[25202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:21 radiozelva sudo[25202]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:21 radiozelva volumio[25063]: info: UPNP Browser: Client initialized successfully Dec 15 05:05:21 radiozelva sudo[25204]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 15 05:05:21 radiozelva sudo[25204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:21 radiozelva volumio[25063]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:21 radiozelva volumio[25063]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 05:05:21 radiozelva volumio[25063]: info: [1765771521880] CoreMusicLibrary::Adding element Last_100 Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 05:05:21 radiozelva volumio[25063]: info: [1765771521890] CoreMusicLibrary::Adding element Webradio Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 05:05:21 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 05:05:21 radiozelva systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 15 05:05:21 radiozelva volumio[25063]: info: Initializing BBC Radios Dec 15 05:05:22 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 05:05:22 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:22 radiozelva volumio[25063]: info: Creating Spotify config file Dec 15 05:05:22 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:22 radiozelva volumio[25063]: info: Loading i18n strings for locale cs Dec 15 05:05:23 radiozelva volumio[25063]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Dec 15 05:05:23 radiozelva volumio[25063]: info: Volumio Calling Home Dec 15 05:05:23 radiozelva systemd[1]: mpd.service: Deactivated successfully. Dec 15 05:05:23 radiozelva systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 15 05:05:23 radiozelva systemd[1]: mpd.service: Consumed 6.188s CPU time. Dec 15 05:05:23 radiozelva systemd[1]: mpd.socket: Deactivated successfully. Dec 15 05:05:23 radiozelva systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 15 05:05:23 radiozelva systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 15 05:05:23 radiozelva systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 15 05:05:23 radiozelva systemd[1]: Starting mpd.service - Music Player Daemon... Dec 15 05:05:23 radiozelva volumio[25063]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.104 UA: Mozilla/5.0 (Linux; Android 16; CPH2645 Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 15 05:05:23 radiozelva volumio[25063]: info: MPD Permissions set Dec 15 05:05:23 radiozelva volumio[25063]: info: MPD Permissions set Dec 15 05:05:23 radiozelva volumio[25063]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.104 UA: Mozilla/5.0 (Linux; Android 16; CPH2645 Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 15 05:05:23 radiozelva volumio[25063]: info: Volumio called home Dec 15 05:05:23 radiozelva volumio[25063]: info: Spotify config file written Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 15 05:05:23 radiozelva sudo[25273]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 15 05:05:23 radiozelva sudo[25273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:23 radiozelva systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 15 05:05:23 radiozelva systemd[1]: go-librespot-daemon.service: Killing process 24967 (go-librespot) with signal SIGKILL. Dec 15 05:05:23 radiozelva volumio-remote-updater[608]: [2025-12-15 05:05:23] [connect] Successful connection Dec 15 05:05:23 radiozelva systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 15 05:05:23 radiozelva systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 05:05:23 radiozelva systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 05:05:23 radiozelva volumio[25063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 15 05:05:23 radiozelva go-librespot[25275]: go-librespot daemon starting... Dec 15 05:05:23 radiozelva volumio[25063]: info: No need to fix Spotify hosts Dec 15 05:05:23 radiozelva sudo[25273]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:23 radiozelva go-librespot[25276]: time="2025-12-15T05:05:23+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 05:05:23 radiozelva go-librespot[25276]: time="2025-12-15T05:05:23+01:00" level=debug msg="app state loaded" Dec 15 05:05:23 radiozelva go-librespot[25276]: time="2025-12-15T05:05:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:23 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:23 radiozelva sudo[25261]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 15 05:05:23 radiozelva sudo[25261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 15 05:05:23 radiozelva sudo[25261]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetVisibleSources Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:24 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 15 05:05:24 radiozelva volumio[25063]: info: Received Get System Info Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 05:05:24 radiozelva volumio[25063]: info: Discovery: Getting this device information Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:24 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:24 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:24 radiozelva volumio[25063]: info: Listing playlists Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetQueue Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreStateMachine::getQueue Dec 15 05:05:24 radiozelva volumio[25063]: info: CorePlayQueue::getQueue Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01: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]" Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01: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]" Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01: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]" Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=info msg="zeroconf server listening on port 46429" Dec 15 05:05:24 radiozelva volumio[25063]: verbose: New Socket.io Connection to 192.168.1.103:3000 from 192.168.1.104 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=debug msg="obtained new client token: AAAhNLfLWQLmp2ZhytyIiK6qhvqcj5kv2eZyGGchANNZcAAV+wRmh405BFHjYT9oWKtMEhxYNgtxC8QqymIUJe2L9QvpYClxmDhOu55TCqJorMp76eVLwmXfINRdkcdse3sedjn0wcVwDEfesXBtxhDw11A+JTf7GYW3iFHBsZ3RK4ocixitmWlI6sxKEHdm3il+vLGVOQKXPnXAVp3/A3TeufD6+UZIP3+Ztu8jLHowCS4L+8xY+xXigio=" Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 05:05:24 radiozelva sudo[25296]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 05:05:24 radiozelva sudo[25296]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:24 radiozelva sudo[25298]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 05:05:24 radiozelva sudo[25298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=debug msg="completed keyexchange" Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=debug msg="completed challenge" Dec 15 05:05:24 radiozelva sudo[25296]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:24 radiozelva sudo[25298]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=info msg="authenticated AP" username="31************************mi" Dec 15 05:05:24 radiozelva volumio[25063]: info: minidlna: Found miniDLNA version 1.3.0 Dec 15 05:05:24 radiozelva volumio[25063]: info: minidlna: Starting minidlna.service Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 15 05:05:24 radiozelva volumio[25063]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 15 05:05:24 radiozelva volumio[25063]: info: Received Get System Version Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 05:05:24 radiozelva volumio[25063]: info: Received Get System Info Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 05:05:24 radiozelva volumio[25063]: info: Discovery: Getting this device information Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:24 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 15 05:05:24 radiozelva sudo[25303]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Dec 15 05:05:24 radiozelva sudo[25303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetVisibleSources Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:24 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetQueue Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreStateMachine::getQueue Dec 15 05:05:24 radiozelva volumio[25063]: info: CorePlayQueue::getQueue Dec 15 05:05:24 radiozelva volumio[25063]: info: Listing playlists Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 15 05:05:24 radiozelva volumio[25063]: info: Received Get System Info Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 05:05:24 radiozelva volumio[25063]: info: Discovery: Getting this device information Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:24 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 05:05:24 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:24 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=info msg="authenticated Login5" username="31************************mi" Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=debug msg="initializing zeroconf session" username="31************************mi" Dec 15 05:05:24 radiozelva sudo[25303]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:24 radiozelva volumio-remote-updater[608]: [2025-12-15 05:05:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765771523 101 Dec 15 05:05:24 radiozelva volumio[25063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 10 Dec 15 05:05:24 radiozelva volumio[25063]: info: Starting Shairport Sync Dec 15 05:05:24 radiozelva volumio[25063]: info: Starting Shairport Sync Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=debug msg="dealer connection opened" Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=trace msg="starting accesspoint recv loop" Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=trace msg="starting dealer recv loop" Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=trace msg="received accesspoint ping" Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 15 05:05:24 radiozelva volumio[25063]: info: Starting Shairport Sync Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=debug msg="received connection id: YzYwOTQyZTUtOGVl...ODIwMzEzOTVENg==" Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=trace msg="received accesspoint pong ack" Dec 15 05:05:24 radiozelva sudo[25311]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 15 05:05:24 radiozelva sudo[25311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:24 radiozelva sudo[25309]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 15 05:05:24 radiozelva sudo[25309]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:24 radiozelva sudo[25313]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 15 05:05:24 radiozelva sudo[25313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:24 radiozelva volumio[25063]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 15 05:05:24 radiozelva volumio[25063]: SPOTIFY: BQD2ifC3J63WmpfUG0BJ3eKOdyH1pEfsUps3n8zvFsq68B5Iw0TLqezQrZxzldbi8uonZIuC4XiPMh1QvItmnqVJYx5ruM8IFW167fh3x5wE9kPMpSvp32OF6NrzHBYWZtPkAPoOk09AkyJAo4FjrO6fpy1EerrI8rApNgPsgk1n1NevM9j_OCKNnnh-9hhK38mUQ9PewuQU1RMsV4Rt6JOCW3Eb8ZOcvWeezg_iLRdvwznW7zt0UcFxpZ1Uo3Lyx1O6piuTJKEoY6CpVY3sTIgQPhVd76PYk46Xz_9T6jJWQbL1nyQbl3odwoc3 Dec 15 05:05:24 radiozelva volumio[25063]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 15 05:05:24 radiozelva volumio[25063]: info: New Spotify access token = BQD2ifC3J63WmpfUG0BJ3eKOdyH1pEfsUps3n8zvFsq68B5Iw0TLqezQrZxzldbi8uonZIuC4XiPMh1QvItmnqVJYx5ruM8IFW167fh3x5wE9kPMpSvp32OF6NrzHBYWZtPkAPoOk09AkyJAo4FjrO6fpy1EerrI8rApNgPsgk1n1NevM9j_OCKNnnh-9hhK38mUQ9PewuQU1RMsV4Rt6JOCW3Eb8ZOcvWeezg_iLRdvwznW7zt0UcFxpZ1Uo3Lyx1O6piuTJKEoY6CpVY3sTIgQPhVd76PYk46Xz_9T6jJWQbL1nyQbl3odwoc3 Dec 15 05:05:24 radiozelva volumio[25063]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 15 05:05:24 radiozelva go-librespot[25276]: time="2025-12-15T05:05:24+01:00" level=debug msg="put connect state because NEW_DEVICE" Dec 15 05:05:24 radiozelva systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 15 05:05:24 radiozelva volumio[25063]: info: minidlna: systemctl start minidlna.service succeeded. Dec 15 05:05:25 radiozelva systemd[1]: shairport-sync.service: Deactivated successfully. Dec 15 05:05:25 radiozelva systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 15 05:05:25 radiozelva systemd[1]: shairport-sync.service: Consumed 2.131s CPU time. Dec 15 05:05:25 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 05:05:25 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 05:05:25 radiozelva volumio[25063]: info: Discovery: Getting this device information Dec 15 05:05:25 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:25 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:25 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 05:05:25 radiozelva systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 15 05:05:25 radiozelva sudo[25313]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:25 radiozelva sudo[25311]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:25 radiozelva sudo[25309]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:25 radiozelva volumio[25063]: info: Shairport-Sync Started Dec 15 05:05:25 radiozelva volumio[25063]: Error adding Membership: Error: addMembership EINVAL Dec 15 05:05:25 radiozelva volumio[25063]: info: Shairport-Sync Started Dec 15 05:05:25 radiozelva volumio[25063]: info: Shairport-Sync Started Dec 15 05:05:25 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:25 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:25 radiozelva sudo[25320]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 15 05:05:25 radiozelva sudo[25320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:25 radiozelva volumio[25063]: SPOTIFY: User informations: {"country":"CZ","display_name":"JiříP","email":"plucnaraj@seznam.cz","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31l2amtke6dqqywkn7igkzamptmi"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31l2amtke6dqqywkn7igkzamptmi","id":"31l2amtke6dqqywkn7igkzamptmi","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee850f544f353eb0c7d5b667547a","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b820f544f353eb0c7d5b667547a","width":64}],"product":"premium","type":"user","uri":"spotify:user:31l2amtke6dqqywkn7igkzamptmi"} Dec 15 05:05:25 radiozelva volumio[25063]: info: Spotify Successfully logged in Dec 15 05:05:25 radiozelva sudo[25320]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:25 radiozelva volumio[25063]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 05:05:25 radiozelva volumio[25063]: info: [1765771525422] CoreMusicLibrary::Adding element Spotify Dec 15 05:05:25 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 05:05:25 radiozelva volumio[25063]: Cannot find translation for source Spotify Dec 15 05:05:25 radiozelva volumio[25063]: info: Upmpdcli Daemon Started Dec 15 05:05:27 radiozelva volumio[25063]: info: go-librespot daemon successfully initialized Dec 15 05:05:29 radiozelva mpd[25293]: 2025-12-15T05:05:29 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 15 05:05:29 radiozelva systemd[1]: Started mpd.service - Music Player Daemon. Dec 15 05:05:29 radiozelva sudo[25204]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:29 radiozelva sudo[25195]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:29 radiozelva volumio[25063]: info: Completed starting Core Plugins Dec 15 05:05:29 radiozelva volumio[25063]: info: ------------------------------------------- Dec 15 05:05:29 radiozelva volumio[25063]: info: ----- MyVolumio plugins startup ---- Dec 15 05:05:29 radiozelva volumio[25063]: info: ------------------------------------------- Dec 15 05:05:29 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 15 05:05:29 radiozelva volumio[25063]: error: MPD error: The expression evaluated to a falsy value: Dec 15 05:05:29 radiozelva volumio[25063]: assert.ok(self.idling) Dec 15 05:05:29 radiozelva volumio[25063]: error: The expression evaluated to a falsy value: Dec 15 05:05:29 radiozelva volumio[25063]: assert.ok(self.idling) Dec 15 05:05:29 radiozelva volumio[25063]: info: MPD running with PID25293 Dec 15 05:05:29 radiozelva volumio[25063]: ,establishing connection Dec 15 05:05:29 radiozelva volumio[25063]: error: updateQueue error: null Dec 15 05:05:29 radiozelva volumio[25063]: error: updateQueue error: null Dec 15 05:05:30 radiozelva volumio[25063]: info: Initializing connection to go-librespot Websocket Dec 15 05:05:30 radiozelva go-librespot[25276]: time="2025-12-15T05:05:30+01:00" level=debug msg="new websocket client" Dec 15 05:05:30 radiozelva volumio[25063]: info: Connection to go-librespot Websocket established Dec 15 05:05:32 radiozelva sudo[25346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 15 05:05:32 radiozelva sudo[25346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:32 radiozelva sudo[25349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 15 05:05:32 radiozelva sudo[25349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:32 radiozelva systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 15 05:05:32 radiozelva systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 15 05:05:32 radiozelva systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 15 05:05:32 radiozelva systemd[1]: mpd_monitor.service: Consumed 24.325s CPU time. Dec 15 05:05:32 radiozelva systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 15 05:05:32 radiozelva sudo[25346]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:32 radiozelva volumio[25063]: info: Successfully started MPD Monitor Dec 15 05:05:32 radiozelva systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 15 05:05:32 radiozelva systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 15 05:05:32 radiozelva systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 15 05:05:32 radiozelva systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 15 05:05:32 radiozelva sudo[25349]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:32 radiozelva mpd_monitor.sh[25353]: MPD Monitor Service: Starting MPD Monitor Service Dec 15 05:05:32 radiozelva volumio[25063]: info: Successfully started MPD Monitor Dec 15 05:05:33 radiozelva volumio[25063]: info: Getting Spotify volume Dec 15 05:05:33 radiozelva volumio[25063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 15 05:05:33 radiozelva volumio[25063]: info: Spotify volume: 100 Dec 15 05:05:33 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:33 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:33 radiozelva volumio[25063]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Dec 15 05:05:33 radiozelva volumio[25063]: SPOTIFY: SPOTIFY VOLUME 100 Dec 15 05:05:33 radiozelva volumio[25063]: SPOTIFY: VOLUMIO VOLUME 77 Dec 15 05:05:33 radiozelva volumio[25063]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 15 05:05:33 radiozelva volumio[25063]: info: Setting Spotify Volume from Volumio: 77 Dec 15 05:05:35 radiozelva volumio[25063]: SPOTIFY: SETTING SPOTIFY VOLUME 77 Dec 15 05:05:35 radiozelva volumio[25063]: info: Sending Spotify command with payload to local API: /player/volume Dec 15 05:05:35 radiozelva go-librespot[25276]: time="2025-12-15T05:05:35+01:00" level=debug msg="update volume requested to 50461/65535" Dec 15 05:05:35 radiozelva go-librespot[25276]: time="2025-12-15T05:05:35+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 15 05:05:35 radiozelva go-librespot[25276]: time="2025-12-15T05:05:35+01:00" level=trace msg="emitting websocket event: volume" Dec 15 05:05:35 radiozelva volumio[25063]: SPOTIFY: received: {"type":"volume","data":{"value":77,"max":100}} Dec 15 05:05:35 radiozelva volumio[25063]: SPOTIFY: RECEIVED SPOTIFY VOLUME 77 Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 15 05:05:37 radiozelva volumio[25063]: info: Adding plugin bluetooth to MyMusic Plugins Dec 15 05:05:37 radiozelva volumio[25063]: info: Adding plugin multiroom to MyMusic Plugins Dec 15 05:05:37 radiozelva volumio[25063]: info: Adding plugin metavolumio to MyMusic Plugins Dec 15 05:05:37 radiozelva volumio[25063]: info: Adding plugin cd_controller to MyMusic Plugins Dec 15 05:05:37 radiozelva volumio[25063]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 15 05:05:37 radiozelva volumio[25063]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 15 05:05:37 radiozelva volumio[25063]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 15 05:05:37 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 15 05:05:39 radiozelva volumio[25063]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 15 05:05:39 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 15 05:05:39 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:39 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:39 radiozelva volumio[25063]: info: Starting MyVolumio Remote Streaming Endpoints Dec 15 05:05:39 radiozelva volumio[25063]: info: MyVolumio login type: Token Dec 15 05:05:39 radiozelva volumio[25063]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 15 05:05:39 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 15 05:05:41 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 15 05:05:41 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 15 05:05:41 radiozelva volumio[25063]: info: Streaming services startup Dec 15 05:05:41 radiozelva volumio[25063]: info: Starting Streaming Daemon Dec 15 05:05:41 radiozelva sudo[25373]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 15 05:05:41 radiozelva sudo[25373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:41 radiozelva volumio[25063]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 15 05:05:41 radiozelva sudo[25373]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:41 radiozelva volumio[25063]: error: Cannot start Volumio Streaming Daemon Dec 15 05:05:41 radiozelva volumio[25063]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 15 05:05:41 radiozelva volumio[25063]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 15 05:05:41 radiozelva volumio[25063]: error: MyVolumio Custom Token format not valid, refreshing it Dec 15 05:05:42 radiozelva volumio[25063]: info: MyVolumio login type: Token Dec 15 05:05:43 radiozelva volumio[25063]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 15 05:05:43 radiozelva volumio[25063]: info: MyVolumio token set successfully Dec 15 05:05:43 radiozelva volumio[25063]: info: MYVOLUMIO: Adding device Dec 15 05:05:43 radiozelva volumio[25063]: info: MYVOLUMIO: Evaluating Server Dec 15 05:05:43 radiozelva volumio[25063]: info: MyVolumio status changed Dec 15 05:05:43 radiozelva volumio[25063]: info: Streaming services startup Dec 15 05:05:44 radiozelva volumio[25063]: info: Starting Streaming Daemon Dec 15 05:05:44 radiozelva volumio[25063]: info: Removing browser output: myVolumio user plan is not superstar Dec 15 05:05:44 radiozelva volumio[25063]: info: Removing audio output: Dec 15 05:05:44 radiozelva volumio[25063]: info: Stoppping Tunnel 1 Dec 15 05:05:44 radiozelva sudo[25414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 15 05:05:44 radiozelva sudo[25416]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 15 05:05:44 radiozelva sudo[25416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:44 radiozelva sudo[25414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:44 radiozelva sudo[25414]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:44 radiozelva volumio[25063]: error: Cannot start Volumio Streaming Daemon Dec 15 05:05:44 radiozelva volumio[25063]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 15 05:05:44 radiozelva volumio[25063]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 15 05:05:44 radiozelva 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. Dec 15 05:05:44 radiozelva 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. Dec 15 05:05:44 radiozelva 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. Dec 15 05:05:44 radiozelva 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. Dec 15 05:05:44 radiozelva 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. Dec 15 05:05:44 radiozelva 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. Dec 15 05:05:44 radiozelva volumio[25063]: info: Setting Geolocation for MyVolumio to eu4 Dec 15 05:05:44 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:44 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:44 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:44 radiozelva 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. Dec 15 05:05:44 radiozelva 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. Dec 15 05:05:44 radiozelva sudo[25416]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:44 radiozelva volumio[25063]: info: Remote SSH Stopped Dec 15 05:05:44 radiozelva volumio[25063]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 15 05:05:44 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 15 05:05:44 radiozelva volumio[25063]: info: CURURI: music-library Dec 15 05:05:45 radiozelva volumio[25063]: info: Preload queue cleared Dec 15 05:05:45 radiozelva volumio[25063]: info: Updating MyVolumio device info Dec 15 05:05:45 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:45 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:45 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:45 radiozelva volumio[25063]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 15 05:05:47 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 15 05:05:47 radiozelva volumio[25063]: info: CURURI: playlists Dec 15 05:05:47 radiozelva volumio[25063]: info: Listing playlists Dec 15 05:05:47 radiozelva volumio[25063]: info: Preload queue cleared Dec 15 05:05:48 radiozelva volumio[25063]: info: MYVOLUMIO: Adding device Dec 15 05:05:48 radiozelva volumio[25063]: info: MYVOLUMIO: Evaluating Server Dec 15 05:05:49 radiozelva volumio[25063]: info: Setting Geolocation for MyVolumio to eu4 Dec 15 05:05:49 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:49 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:49 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:49 radiozelva volumio[25063]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 15 05:05:50 radiozelva volumio[25063]: info: Updating MyVolumio device info Dec 15 05:05:50 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:50 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:50 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:50 radiozelva volumio[25063]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 15 05:05:53 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 05:05:53 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:05:53 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 15 05:05:53 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 15 05:05:53 radiozelva volumio-remote-updater[608]: Test mode disabled Dec 15 05:05:53 radiozelva volumio-remote-updater[608]: Alpha mode disabled Dec 15 05:05:53 radiozelva volumio-remote-updater[608]: Alpha legacy test mode disabled Dec 15 05:05:53 radiozelva volumio[25063]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 15 05:05:53 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 15 05:05:54 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 15 05:05:54 radiozelva volumio[25063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Dec 15 05:05:54 radiozelva volumio[25063]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 05:05:54 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetState Dec 15 05:05:54 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:05:54 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 15 05:05:54 radiozelva go-librespot[25276]: time="2025-12-15T05:05:54+01:00" level=trace msg="sent dealer ping" Dec 15 05:05:54 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 15 05:05:54 radiozelva go-librespot[25276]: time="2025-12-15T05:05:54+01:00" level=trace msg="received dealer pong" Dec 15 05:05:54 radiozelva sudo[25483]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 15 05:05:54 radiozelva sudo[25472]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 15 05:05:54 radiozelva sudo[25483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:54 radiozelva sudo[25472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:54 radiozelva sudo[25468]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 15 05:05:54 radiozelva sudo[25468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:54 radiozelva sudo[25479]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 15 05:05:54 radiozelva sudo[25479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:54 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 15 05:05:54 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 15 05:05:54 radiozelva sudo[25488]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 05:05:54 radiozelva sudo[25488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:54 radiozelva sudo[25472]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:54 radiozelva sudo[25479]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:54 radiozelva sudo[25488]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:54 radiozelva sudo[25483]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:55 radiozelva sudo[25468]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:55 radiozelva sudo[25494]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 05:05:55 radiozelva sudo[25494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:05:55 radiozelva sudo[25494]: pam_unix(sudo:session): session closed for user root Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetBrowseSources Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 05:05:59 radiozelva volumio[25063]: info: Retrieving Cloud Streaming UI Dec 15 05:05:59 radiozelva volumio[25063]: info: Getting Tidal Cloud Configuration Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 05:05:59 radiozelva volumio[25063]: info: Getting Qobuz Cloud Configuration Dec 15 05:05:59 radiozelva volumio[25063]: info: Asking plugin for UI Config Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 05:05:59 radiozelva volumio[25063]: info: Getting Spotify Cloud Configuration Dec 15 05:05:59 radiozelva volumio[25063]: info: Asking plugin for UI Config Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 05:05:59 radiozelva volumio[25063]: info: Saving Spotify Acccount Dec 15 05:05:59 radiozelva volumio[25063]: info: Got Tidal Cloud Configuration Dec 15 05:05:59 radiozelva volumio[25063]: info: Got it Dec 15 05:05:59 radiozelva volumio[25063]: info: Got it Dec 15 05:05:59 radiozelva volumio[25063]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 05:05:59 radiozelva volumio[25063]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetBrowseSources Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetBrowseSources Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetBrowseSources Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 15 05:05:59 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 15 05:06:00 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 15 05:06:00 radiozelva volumio[25063]: verbose: ControllerMpd::sendMpdCommand update Dec 15 05:06:00 radiozelva volumio[25063]: verbose: ControllerMpd::sendMpdCommand status Dec 15 05:06:00 radiozelva volumio[25063]: info: Dec 15 05:06:00 radiozelva volumio[25063]: ---------------------------- MPD announces state update: update Dec 15 05:06:00 radiozelva volumio[25063]: info: sendMpdCommand update took 9 milliseconds Dec 15 05:06:00 radiozelva volumio[25063]: info: ControllerMpd::getState Dec 15 05:06:00 radiozelva volumio[25063]: verbose: ControllerMpd::sendMpdCommand status Dec 15 05:06:01 radiozelva volumio[25063]: info: sendMpdCommand status took 9 milliseconds Dec 15 05:06:01 radiozelva volumio[25063]: info: sendMpdCommand status took 4 milliseconds Dec 15 05:06:01 radiozelva volumio[25063]: verbose: ControllerMpd::parseState Dec 15 05:06:01 radiozelva volumio[25063]: info: Command Router : Notfying DB Updatetrue Dec 15 05:06:01 radiozelva volumio[25063]: verbose: ControllerMpd::parseState Dec 15 05:06:01 radiozelva volumio[25063]: info: ControllerMpd::pushState Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::servicePushState Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreStateMachine::pushState Dec 15 05:06:01 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::volumioPushState Dec 15 05:06:01 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:06:01 radiozelva volumio[25063]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 15 05:06:01 radiozelva volumio[25063]: verbose: CURRENT POSITION 0 Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreStateMachine::syncState stateService stop Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreStateMachine::syncState currentStatus stop Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreStateMachine::pushState Dec 15 05:06:01 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::volumioPushState Dec 15 05:06:01 radiozelva volumio[25063]: info: No code Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreStateMachine::pushState Dec 15 05:06:01 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::volumioPushState Dec 15 05:06:01 radiozelva volumio[25063]: info: ------------------------------ 156ms Dec 15 05:06:01 radiozelva volumio[25063]: verbose: ControllerMpd::sendMpdCommand status Dec 15 05:06:01 radiozelva volumio[25063]: info: Dec 15 05:06:01 radiozelva volumio[25063]: ---------------------------- MPD announces state update: update Dec 15 05:06:01 radiozelva volumio[25063]: info: ControllerMpd::getState Dec 15 05:06:01 radiozelva volumio[25063]: verbose: ControllerMpd::sendMpdCommand status Dec 15 05:06:01 radiozelva volumio[25063]: verbose: ControllerMpd::sendMpdCommand status Dec 15 05:06:01 radiozelva volumio[25063]: info: Dec 15 05:06:01 radiozelva volumio[25063]: ---------------------------- MPD announces state update: update Dec 15 05:06:01 radiozelva volumio[25063]: info: ControllerMpd::getState Dec 15 05:06:01 radiozelva volumio[25063]: verbose: ControllerMpd::sendMpdCommand status Dec 15 05:06:01 radiozelva volumio[25063]: info: sendMpdCommand status took 7 milliseconds Dec 15 05:06:01 radiozelva volumio[25063]: info: sendMpdCommand status took 6 milliseconds Dec 15 05:06:01 radiozelva volumio[25063]: info: sendMpdCommand status took 5 milliseconds Dec 15 05:06:01 radiozelva volumio[25063]: info: sendMpdCommand status took 4 milliseconds Dec 15 05:06:01 radiozelva volumio[25063]: verbose: ControllerMpd::parseState Dec 15 05:06:01 radiozelva volumio[25063]: info: Command Router : Notfying DB Updatetrue Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::Close All Modals sent Dec 15 05:06:01 radiozelva volumio[25063]: verbose: ControllerMpd::parseState Dec 15 05:06:01 radiozelva volumio[25063]: verbose: ControllerMpd::parseState Dec 15 05:06:01 radiozelva volumio[25063]: info: Command Router : Notfying DB Updatetrue Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::Close All Modals sent Dec 15 05:06:01 radiozelva volumio[25063]: verbose: ControllerMpd::parseState Dec 15 05:06:01 radiozelva volumio[25063]: info: ControllerMpd::pushState Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::servicePushState Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreStateMachine::pushState Dec 15 05:06:01 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::volumioPushState Dec 15 05:06:01 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:06:01 radiozelva volumio[25063]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 15 05:06:01 radiozelva volumio[25063]: verbose: CURRENT POSITION 0 Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreStateMachine::syncState stateService stop Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreStateMachine::syncState currentStatus stop Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreStateMachine::pushState Dec 15 05:06:01 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::volumioPushState Dec 15 05:06:01 radiozelva volumio[25063]: info: No code Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreStateMachine::pushState Dec 15 05:06:01 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::volumioPushState Dec 15 05:06:01 radiozelva volumio[25063]: info: ControllerMpd::pushState Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::servicePushState Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreStateMachine::pushState Dec 15 05:06:01 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::volumioPushState Dec 15 05:06:01 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:06:01 radiozelva volumio[25063]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 15 05:06:01 radiozelva volumio[25063]: verbose: CURRENT POSITION 0 Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreStateMachine::syncState stateService stop Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreStateMachine::syncState currentStatus stop Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreStateMachine::pushState Dec 15 05:06:01 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::volumioPushState Dec 15 05:06:01 radiozelva volumio[25063]: info: No code Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreStateMachine::pushState Dec 15 05:06:01 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::volumioPushState Dec 15 05:06:01 radiozelva volumio[25063]: info: ------------------------------ 225ms Dec 15 05:06:01 radiozelva volumio[25063]: info: ------------------------------ 224ms Dec 15 05:06:01 radiozelva volumio[25063]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Dec 15 05:06:01 radiozelva volumio[25063]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Dec 15 05:06:01 radiozelva volumio[25063]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Dec 15 05:06:01 radiozelva volumio[25063]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Dec 15 05:06:01 radiozelva volumio[25063]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Dec 15 05:06:01 radiozelva volumio[25063]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Dec 15 05:06:01 radiozelva volumio[25063]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Dec 15 05:06:01 radiozelva volumio[25063]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Dec 15 05:06:01 radiozelva volumio[25063]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Dec 15 05:06:01 radiozelva volumio[25063]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 15 05:06:01 radiozelva volumio[25063]: info: Completed starting MyVolumio Plugin Dec 15 05:06:01 radiozelva volumio[25063]: [Metrics] CommandRouter: 51s 578.73ms Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::volumiosetStartupVolume Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::Close All Modals sent Dec 15 05:06:01 radiozelva volumio[25063]: info: CoreCommandRouter::Close All Modals sent Dec 15 05:06:02 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 15 05:06:02 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 15 05:06:02 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 15 05:06:03 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 15 05:06:07 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 15 05:06:08 radiozelva volumio[25063]: info: BOOT COMPLETED Dec 15 05:06:08 radiozelva volumio[25063]: info: mpdhttpout ---Boot completed detected! Patching mpd now! Dec 15 05:06:08 radiozelva volumio[25063]: info: CoreCommandRouter::volumioGetState Dec 15 05:06:08 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:06:08 radiozelva volumio[25063]: info: CoreCommandRouter::volumioPause Dec 15 05:06:08 radiozelva volumio[25063]: info: CoreStateMachine::pause Dec 15 05:06:08 radiozelva volumio[25063]: info: mpdhttpout --- Volumio set on pause Dec 15 05:06:08 radiozelva volumio[25063]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Dec 15 05:06:11 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 15 05:06:11 radiozelva sudo[25533]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 15 05:06:11 radiozelva sudo[25533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:06:11 radiozelva systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 15 05:06:12 radiozelva systemd[1]: mpd.service: Deactivated successfully. Dec 15 05:06:12 radiozelva systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 15 05:06:12 radiozelva systemd[1]: mpd.service: Consumed 15.373s CPU time. Dec 15 05:06:12 radiozelva systemd[1]: mpd.socket: Deactivated successfully. Dec 15 05:06:12 radiozelva mpd_monitor.sh[25536]: MPD error: Connection reset by peer Dec 15 05:06:12 radiozelva systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 15 05:06:12 radiozelva systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 15 05:06:12 radiozelva mpd_monitor.sh[25353]: MPD Monitor Service: MPD Appears to be inactive, restarting Dec 15 05:06:13 radiozelva mpd_monitor.sh[25538]: mpd: no process found Dec 15 05:06:13 radiozelva systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 15 05:06:13 radiozelva systemd[1]: Starting mpd.service - Music Player Daemon... Dec 15 05:06:13 radiozelva systemd[1]: mpd.service: Deactivated successfully. Dec 15 05:06:13 radiozelva systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 15 05:06:13 radiozelva systemd[1]: mpd.socket: Deactivated successfully. Dec 15 05:06:13 radiozelva systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 15 05:06:13 radiozelva systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 15 05:06:13 radiozelva systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 15 05:06:13 radiozelva systemd[1]: Starting mpd.service - Music Player Daemon... Dec 15 05:06:13 radiozelva sudo[25542]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 15 05:06:13 radiozelva sudo[25542]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 15 05:06:13 radiozelva sudo[25542]: pam_unix(sudo:session): session closed for user root Dec 15 05:06:17 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 15 05:06:17 radiozelva volumio[25063]: info: CURURI: music-library Dec 15 05:06:18 radiozelva mpd[25545]: 2025-12-15T05:06:18 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 15 05:06:18 radiozelva systemd[1]: Started mpd.service - Music Player Daemon. Dec 15 05:06:18 radiozelva mpd_monitor.sh[25353]: MPD Monitor Service: MPD restarted due to no mpc output. Dec 15 05:06:18 radiozelva sudo[25533]: pam_unix(sudo:session): session closed for user root Dec 15 05:06:18 radiozelva volumio[25063]: error: updateQueue error: null Dec 15 05:06:21 radiozelva sudo[25564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 15 05:06:21 radiozelva sudo[25564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 05:06:21 radiozelva systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 15 05:06:21 radiozelva systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 15 05:06:21 radiozelva systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 15 05:06:21 radiozelva systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 15 05:06:21 radiozelva sudo[25564]: pam_unix(sudo:session): session closed for user root Dec 15 05:06:21 radiozelva mpd_monitor.sh[25566]: MPD Monitor Service: Starting MPD Monitor Service Dec 15 05:06:21 radiozelva volumio[25063]: info: Successfully started MPD Monitor Dec 15 05:06:24 radiozelva go-librespot[25276]: time="2025-12-15T05:06:24+01:00" level=trace msg="sent dealer ping" Dec 15 05:06:24 radiozelva go-librespot[25276]: time="2025-12-15T05:06:24+01:00" level=trace msg="received dealer pong" Dec 15 05:06:37 radiozelva volumio[25063]: info: Preload queue cleared Dec 15 05:06:39 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 15 05:06:39 radiozelva volumio[25063]: info: CURURI: playlists Dec 15 05:06:39 radiozelva volumio[25063]: info: Listing playlists Dec 15 05:06:39 radiozelva volumio[25063]: info: Preload queue cleared Dec 15 05:06:41 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 15 05:06:41 radiozelva volumio[25063]: info: CURURI: artists:// Dec 15 05:06:41 radiozelva volumio[25063]: info: Preload queue cleared Dec 15 05:06:43 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 15 05:06:43 radiozelva volumio[25063]: info: CURURI: music-library Dec 15 05:06:43 radiozelva volumio[25063]: info: Preload queue cleared Dec 15 05:06:44 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 15 05:06:44 radiozelva volumio[25063]: info: CURURI: music-library/USB Dec 15 05:06:44 radiozelva volumio[25063]: info: Preload queue cleared Dec 15 05:06:44 radiozelva volumio[25063]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 15 05:06:44 radiozelva volumio[25063]: info: CURURI: music-library/USB/8726816d-c5e7-4d32-a90c-046dc3305599 Dec 15 05:06:44 radiozelva volumio[25063]: info: Preload queue cleared Dec 15 05:06:44 radiozelva volumio[25063]: info: Preloading song: music-library/USB/8726816d-c5e7-4d32-a90c-046dc3305599/tišící-meditace3hzdeltahluboká-regenerace-organismu-s-mým-zpěvem.mp3 Dec 15 05:06:44 radiozelva volumio[25063]: info: Exploding uri music-library/USB/8726816d-c5e7-4d32-a90c-046dc3305599/tišící-meditace3hzdeltahluboká-regenerace-organismu-s-mým-zpěvem.mp3 in service mpd Dec 15 05:06:44 radiozelva volumio[25063]: info: ALBUMART /albumart?cacheid=506&web=//extralarge&path=%2Fmnt%2FUSB%2F8726816d-c5e7-4d32-a90c-046dc3305599%2Fti%C5%A1%C3%ADc%C3%AD-meditace3hzdeltahlubok%C3%A1-regenerace-organismu-s-m%C3%BDm-zp%C4%9Bvem.mp3&metadata=false Dec 15 05:06:44 radiozelva volumio[25063]: info: URI /mnt/USB/8726816d-c5e7-4d32-a90c-046dc3305599/tišící-meditace3hzdeltahluboká-regenerace-organismu-s-mým-zpěvem.mp3 Dec 15 05:06:46 radiozelva volumio[25063]: info: Preload queue cleared Dec 15 05:06:46 radiozelva volumio[25063]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 15 05:06:46 radiozelva volumio[25063]: info: CoreStateMachine::ClearQueue Dec 15 05:06:46 radiozelva volumio[25063]: info: CoreStateMachine::stop Dec 15 05:06:46 radiozelva volumio[25063]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 05:06:46 radiozelva volumio[25063]: info: CorePlayQueue::clearPlayQueue Dec 15 05:06:46 radiozelva volumio[25063]: info: CorePlayQueue::saveQueue Dec 15 05:06:46 radiozelva volumio[25063]: info: CoreCommandRouter::volumioPushQueue Dec 15 05:06:46 radiozelva volumio[25063]: info: CoreStateMachine::addQueueItems Dec 15 05:06:46 radiozelva volumio[25063]: info: CorePlayQueue::addQueueItems Dec 15 05:06:46 radiozelva volumio[25063]: info: Preload queue cleared Dec 15 05:06:46 radiozelva volumio[25063]: info: Adding Item to queue: music-library/USB/8726816d-c5e7-4d32-a90c-046dc3305599/tišící-meditace3hzdeltahluboká-regenerace-organismu-s-mým-zpěvem.mp3 Dec 15 05:06:46 radiozelva volumio[25063]: info: Using cached record of: music-library/USB/8726816d-c5e7-4d32-a90c-046dc3305599/tišící-meditace3hzdeltahluboká-regenerace-organismu-s-mým-zpěvem.mp3 Dec 15 05:06:46 radiozelva volumio[25063]: info: CoreCommandRouter::volumioPushQueue Dec 15 05:06:46 radiozelva volumio[25063]: info: CorePlayQueue::saveQueue Dec 15 05:06:46 radiozelva volumio[25063]: info: CoreStateMachine::updateTrackBlock Dec 15 05:06:46 radiozelva volumio[25063]: info: CorePlayQueue::getTrackBlock Dec 15 05:06:46 radiozelva volumio[25063]: info: CoreCommandRouter::volumioPlay Dec 15 05:06:46 radiozelva volumio[25063]: info: CoreStateMachine::play index 0 Dec 15 05:06:46 radiozelva volumio[25063]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 05:06:46 radiozelva volumio[25063]: info: CoreStateMachine::addQueueItems Dec 15 05:06:46 radiozelva volumio[25063]: info: CorePlayQueue::addQueueItems Dec 15 05:06:46 radiozelva volumio[25063]: info: Preload queue cleared Dec 15 05:06:46 radiozelva volumio[25063]: info: Adding Item to queue: music-library/USB/8726816d-c5e7-4d32-a90c-046dc3305599/drive-download-20251206T144309Z-3-001 Dec 15 05:06:46 radiozelva volumio[25063]: info: Exploding uri music-library/USB/8726816d-c5e7-4d32-a90c-046dc3305599/drive-download-20251206T144309Z-3-001 in service mpd Dec 15 05:06:46 radiozelva volumio[25063]: info: Adding Item to queue: music-library/USB/8726816d-c5e7-4d32-a90c-046dc3305599/drive-download-20251206T144309Z-3-001.zip Dec 15 05:06:46 radiozelva volumio[25063]: info: Exploding uri music-library/USB/8726816d-c5e7-4d32-a90c-046dc3305599/drive-download-20251206T144309Z-3-001.zip in service mpd Dec 15 05:06:46 radiozelva volumio[25063]: info: Adding Item to queue: music-library/USB/8726816d-c5e7-4d32-a90c-046dc3305599/Filmy Dec 15 05:06:46 radiozelva volumio[25063]: info: Exploding uri music-library/USB/8726816d-c5e7-4d32-a90c-046dc3305599/Filmy in service mpd Dec 15 05:06:46 radiozelva volumio[25063]: info: Adding Item to queue: music-library/USB/8726816d-c5e7-4d32-a90c-046dc3305599/OBS nahrávky Dec 15 05:06:46 radiozelva volumio[25063]: info: Exploding uri music-library/USB/8726816d-c5e7-4d32-a90c-046dc3305599/OBS nahrávky in service mpd Dec 15 05:06:46 radiozelva volumio[25063]: info: CoreStateMachine::stop Dec 15 05:06:46 radiozelva volumio[25063]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 05:06:46 radiozelva volumio[25063]: info: CoreStateMachine::play index undefined Dec 15 05:06:46 radiozelva volumio[25063]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 05:06:46 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:06:46 radiozelva volumio[25063]: info: CoreStateMachine::startPlaybackTimer Dec 15 05:06:46 radiozelva volumio[25063]: info: CorePlayQueue::getTrack 0 Dec 15 05:06:46 radiozelva volumio[25063]: verbose: ControllerMpd::clearAddPlayTracks USB/8726816d-c5e7-4d32-a90c-046dc3305599/tišící-meditace3hzdeltahluboká-regenerace-organismu-s-mým-zpěvem.mp3 Dec 15 05:06:46 radiozelva volumio[25063]: verbose: ControllerMpd::sendMpdCommand stop Dec 15 05:06:46 radiozelva volumio[25063]: info: ALBUMART /albumart?cacheid=506&web=/9NINE%20D%26B%20PACK2/extralarge&path=%2Fmnt%2FUSB%2F8726816d-c5e7-4d32-a90c-046dc3305599%2Fdrive-download-20251206T144309Z-3-001%2FDon_t%20Baddadan(9NINE%20Mash%20Up).mp3&metadata=false Dec 15 05:06:46 radiozelva volumio[25063]: info: URI /mnt/USB/8726816d-c5e7-4d32-a90c-046dc3305599/drive-download-20251206T144309Z-3-001/Don_t Baddadan(9NINE Mash Up).mp3 Dec 15 05:06:46 radiozelva volumio[25063]: info: ALBUMART /albumart?cacheid=506&web=/9NINE%20D%26B%20PACK2/extralarge&path=%2Fmnt%2FUSB%2F8726816d-c5e7-4d32-a90c-046dc3305599%2Fdrive-download-20251206T144309Z-3-001%2FFeel%20the%20Blinding(9NINE%20Edit).mp3&metadata=false Dec 15 05:06:46 radiozelva volumio[25063]: info: URI /mnt/USB/8726816d-c5e7-4d32-a90c-046dc3305599/drive-download-20251206T144309Z-3-001/Feel the Blinding(9NINE Edit).mp3 Dec 15 05:06:46 radiozelva volumio[25063]: info: ALBUMART /albumart?cacheid=506&web=/9NINE%20D%26B%20PACK2/extralarge&path=%2Fmnt%2FUSB%2F8726816d-c5e7-4d32-a90c-046dc3305599%2Fdrive-download-20251206T144309Z-3-001%2FIn%20My%20Power%20(9NINE%20Mash%20Up).mp3&metadata=false Dec 15 05:06:46 radiozelva volumio[25063]: info: URI /mnt/USB/8726816d-c5e7-4d32-a90c-046dc3305599/drive-download-20251206T144309Z-3-001/In My Power (9NINE Mash Up).mp3 Dec 15 05:06:46 radiozelva volumio[25063]: info: ALBUMART /albumart?cacheid=506&web=/9NINE%20D%26B%20PACK2/extralarge&path=%2Fmnt%2FUSB%2F8726816d-c5e7-4d32-a90c-046dc3305599%2Fdrive-download-20251206T144309Z-3-001%2FRock%20that%20Acceleration(9NINE%20Edit).mp3&metadata=false Dec 15 05:06:46 radiozelva volumio[25063]: info: URI /mnt/USB/8726816d-c5e7-4d32-a90c-046dc3305599/drive-download-20251206T144309Z-3-001/Rock that Acceleration(9NINE Edit).mp3 Dec 15 05:06:46 radiozelva volumio[25063]: info: ALBUMART /albumart?cacheid=506&web=/9NINE%20D%26B%20PACK2/extralarge&path=%2Fmnt%2FUSB%2F8726816d-c5e7-4d32-a90c-046dc3305599%2Fdrive-download-20251206T144309Z-3-001%2FShae%20Sat%20No%20Reason(9NINE%20Edit).mp3&metadata=false Dec 15 05:06:46 radiozelva volumio[25063]: info: URI /mnt/USB/8726816d-c5e7-4d32-a90c-046dc3305599/drive-download-20251206T144309Z-3-001/Shae Sat No Reason(9NINE Edit).mp3 Dec 15 05:06:46 radiozelva volumio[25063]: info: ALBUMART /albumart?cacheid=506&web=/9NINE%20D%26B%20PACK2/extralarge&path=%2Fmnt%2FUSB%2F8726816d-c5e7-4d32-a90c-046dc3305599%2Fdrive-download-20251206T144309Z-3-001%2FSin%20Sar%20Par%20(9NINE%20Edit).mp3&metadata=false Dec 15 05:06:46 radiozelva volumio[25063]: info: URI /mnt/USB/8726816d-c5e7-4d32-a90c-046dc3305599/drive-download-20251206T144309Z-3-001/Sin Sar Par (9NINE Edit).mp3 Dec 15 05:06:46 radiozelva volumio[25063]: info: ALBUMART /albumart?cacheid=506&web=/9NINE%20D%26B%20PACK2/extralarge&path=%2Fmnt%2FUSB%2F8726816d-c5e7-4d32-a90c-046dc3305599%2Fdrive-download-20251206T144309Z-3-001%2FUltra%20Summer(9NINE%20Edit).mp3&metadata=false Dec 15 05:06:46 radiozelva volumio[25063]: info: URI /mnt/USB/8726816d-c5e7-4d32-a90c-046dc3305599/drive-download-20251206T144309Z-3-001/Ultra Summer(9NINE Edit).mp3 Dec 15 05:06:46 radiozelva volumio[25063]: info: ALBUMART /albumart?cacheid=506&web=/9NINE%20D%26B%20PACK2/extralarge&path=%2Fmnt%2FUSB%2F8726816d-c5e7-4d32-a90c-046dc3305599%2Fdrive-download-20251206T144309Z-3-001%2FVictory%20Laser(9NINE%20Edit).mp3&metadata=false Dec 15 05:06:46 radiozelva volumio[25063]: info: URI /mnt/USB/8726816d-c5e7-4d32-a90c-046dc3305599/drive-download-20251206T144309Z-3-001/Victory Laser(9NINE Edit).mp3 Dec 15 05:06:46 radiozelva volumio[25063]: info: ALBUMART /albumart?cacheid=506&web=/9NINE%20D%26B%20PACK2/extralarge&path=%2Fmnt%2FUSB%2F8726816d-c5e7-4d32-a90c-046dc3305599%2Fdrive-download-20251206T144309Z-3-001%2FVoices%20in%20my%20head(9NINE%20Edit).mp3&metadata=false Dec 15 05:06:46 radiozelva volumio[25063]: info: URI /mnt/USB/8726816d-c5e7-4d32-a90c-046dc3305599/drive-download-20251206T144309Z-3-001/Voices in my head(9NINE Edit).mp3 Dec 15 05:06:46 radiozelva volumio[25063]: info: ALBUMART /albumart?cacheid=506&web=/9NINE%20D%26B%20PACK2/extralarge&path=%2Fmnt%2FUSB%2F8726816d-c5e7-4d32-a90c-046dc3305599%2Fdrive-download-20251206T144309Z-3-001%2FWELCOME%20TO%20THE%20OFFENDER(9NINE%20Edit).mp3&metadata=false Dec 15 05:06:46 radiozelva volumio[25063]: info: URI /mnt/USB/8726816d-c5e7-4d32-a90c-046dc3305599/drive-download-20251206T144309Z-3-001/WELCOME TO THE OFFENDER(9NINE Edit).mp3 Dec 15 05:06:46 radiozelva volumio[25063]: info: ALBUMART /albumart?cacheid=506&web=/9NINE%20D%26B%20PACK2/extralarge&path=%2Fmnt%2FUSB%2F8726816d-c5e7-4d32-a90c-046dc3305599%2Fdrive-download-20251206T144309Z-3-001.zip&metadata=false Dec 15 05:06:46 radiozelva volumio[25063]: info: URI /mnt/USB/8726816d-c5e7-4d32-a90c-046dc3305599/drive-download-20251206T144309Z-3-001.zip Dec 15 05:06:46 radiozelva volumio[25063]: info: ALBUMART /albumart?cacheid=506&web=/9NINE%20D%26B%20PACK2/extralarge&path=%2Fmnt%2FUSB%2F8726816d-c5e7-4d32-a90c-046dc3305599%2Fdrive-download-20251206T144309Z-3-001.zip&metadata=false Dec 15 05:06:46 radiozelva volumio[25063]: info: URI /mnt/USB/8726816d-c5e7-4d32-a90c-046dc3305599/drive-download-20251206T144309Z-3-001.zip Dec 15 05:06:46 radiozelva volumio[25063]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 05:06:46 radiozelva volumio[25063]: Error: Unable to resolve or reject the same promise twice Dec 15 05:06:46 radiozelva volumio[25063]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 15 05:06:46 radiozelva volumio[25063]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Dec 15 05:06:46 radiozelva volumio[25063]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 15 05:06:46 radiozelva volumio[25063]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 15 05:06:46 radiozelva volumio[25063]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 15 05:06:46 radiozelva volumio[25063]: at Socket.emit (node:events:514:28) Dec 15 05:06:46 radiozelva volumio[25063]: at addChunk (node:internal/streams/readable:343:12) Dec 15 05:06:46 radiozelva volumio[25063]: at readableAddChunk (node:internal/streams/readable:312:11) Dec 15 05:06:46 radiozelva volumio[25063]: at Readable.push (node:internal/streams/readable:253:10) Dec 15 05:06:46 radiozelva volumio[25063]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Dec 15 05:06:46 radiozelva volumio[25063]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 05:06:49 radiozelva sudo[25632]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-15 05:05' Dec 15 05:06:49 radiozelva sudo[25632]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"