May 29 10:50:00 volumio volumio[1151]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh May 29 10:50:00 volumio volumio[1151]: Traceback (most recent call last): May 29 10:50:00 volumio volumio[1151]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in May 29 10:50:00 volumio volumio[1151]: from volumio_albumart import AlbumartAnimator May 29 10:50:00 volumio volumio[1151]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in May 29 10:50:00 volumio volumio[1151]: import socketio May 29 10:50:00 volumio volumio[1151]: ModuleNotFoundError: No module named 'socketio' May 29 10:50:01 volumio volumio[1151]: info: CoreCommandRouter::volumioPause May 29 10:50:01 volumio volumio[1151]: info: CoreStateMachine::pause May 29 10:50:05 volumio volumio[1151]: info: CoreCommandRouter::volumioGetState May 29 10:50:05 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 May 29 10:50:07 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 29 10:50:07 volumio dbus-daemon[787]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.66' (uid=0 pid=4685 comm="timedatectl show --property=NTPSynchronized --valu") May 29 10:50:07 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 29 10:50:07 volumio dbus-daemon[787]: [system] Successfully activated service 'org.freedesktop.timedate1' May 29 10:50:07 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 29 10:50:07 volumio setdatetime-helper.sh[4684]: Time is not synchronized. Attempting to sync... May 29 10:50:07 volumio sudo[4693]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 29 May 2025 07:50:08 GMT#015' May 29 10:50:07 volumio sudo[4693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 10:50:08 volumio setdatetime-helper.sh[4694]: Thu May 29 10:50:08 EEST 2025 May 29 10:50:08 volumio sudo[4693]: pam_unix(sudo:session): session closed for user root May 29 10:50:08 volumio setdatetime-helper.sh[4684]: Time synchronized successfully. May 29 10:50:08 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 29 10:50:08 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 29 10:50:08 volumio volumio[1151]: info: CoreCommandRouter::volumioGetQueue May 29 10:50:08 volumio volumio[1151]: info: CoreStateMachine::getQueue May 29 10:50:08 volumio volumio[1151]: info: CorePlayQueue::getQueue May 29 10:50:10 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. May 29 10:50:10 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... May 29 10:50:10 volumio systemd[1]: welcome.service: Deactivated successfully. May 29 10:50:10 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 29 10:50:10 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 29 10:50:10 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 29 10:50:10 volumio welcome[4720]: Resolved ip:[1] 192.168.0.193 May 29 10:50:10 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 29 10:50:10 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 29 10:50:12 volumio volumio[1151]: info: Preload queue cleared May 29 10:50:12 volumio volumio[1151]: info: CoreCommandRouter::volumioReplaceandPlayItems May 29 10:50:12 volumio volumio[1151]: info: CoreStateMachine::ClearQueue May 29 10:50:12 volumio volumio[1151]: info: CoreStateMachine::stop May 29 10:50:12 volumio volumio[1151]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 10:50:12 volumio volumio[1151]: info: CorePlayQueue::clearPlayQueue May 29 10:50:12 volumio volumio[1151]: info: CorePlayQueue::saveQueue May 29 10:50:12 volumio volumio[1151]: info: CoreCommandRouter::volumioPushQueue May 29 10:50:12 volumio volumio[1151]: info: CoreStateMachine::addQueueItems May 29 10:50:12 volumio volumio[1151]: info: CorePlayQueue::addQueueItems May 29 10:50:12 volumio volumio[1151]: info: Preload queue cleared May 29 10:50:12 volumio volumio[1151]: info: Adding Item to queue: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_explore%22%7D%7D/album@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%226EFvgfYsbNY%22%2C%22playlistId%22%3A%22OLAK5uy_kwTPSFQJI4AIkaoEToKHjZcjSSjZgpbx0%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22browse%22%3A%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22MPREb_TdVtWpdNOOv%22%7D%2C%22pageType%22%3A%22MUSIC_PAGE_TYPE_ALBUM%22%7D%7D May 29 10:50:12 volumio volumio[1151]: info: Exploding uri ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_explore%22%7D%7D/album@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%226EFvgfYsbNY%22%2C%22playlistId%22%3A%22OLAK5uy_kwTPSFQJI4AIkaoEToKHjZcjSSjZgpbx0%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22browse%22%3A%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22MPREb_TdVtWpdNOOv%22%7D%2C%22pageType%22%3A%22MUSIC_PAGE_TYPE_ALBUM%22%7D%7D in service ytmusic May 29 10:50:12 volumio volumio[1151]: info: [ytmusic-browse] explodeUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_explore%22%7D%7D/album@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%226EFvgfYsbNY%22%2C%22playlistId%22%3A%22OLAK5uy_kwTPSFQJI4AIkaoEToKHjZcjSSjZgpbx0%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22browse%22%3A%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22MPREb_TdVtWpdNOOv%22%7D%2C%22pageType%22%3A%22MUSIC_PAGE_TYPE_ALBUM%22%7D%7D May 29 10:50:13 volumio volumio[1151]: info: CoreCommandRouter::volumioPushQueue May 29 10:50:13 volumio volumio[1151]: info: CorePlayQueue::saveQueue May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::updateTrackBlock May 29 10:50:13 volumio volumio[1151]: info: CorePlayQueue::getTrackBlock May 29 10:50:13 volumio volumio[1151]: info: CoreCommandRouter::volumioPlay May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::play index 0 May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::stop May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::play index undefined May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 10:50:13 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::startPlaybackTimer May 29 10:50:13 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 May 29 10:50:13 volumio volumio[1151]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22%D0%9A%D0%B0%D0%B9%D1%81%D1%8F%22%2C%22artist%22%3A%22%D0%9D%D1%96%D0%BA%D1%96%D1%82%D0%B0%20%D0%9A%D1%96%D1%81%D0%B5%D0%BB%D1%8C%D0%BE%D0%B2%20%26%20iSKra%22%2C%22album%22%3A%22%D0%9A%D0%B0%D0%B9%D1%81%D1%8F%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FP9vOhBu5myFEIkxKIcUQaVcITdG38gkBa1AkShDedolEGytYzUWgkP3KgukOI_tn9kfBpkEUL8pILzMU1Q%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%226EFvgfYsbNY%22%2C%22playlistId%22%3A%22OLAK5uy_kwTPSFQJI4AIkaoEToKHjZcjSSjZgpbx0%22%2C%22params%22%3A%22OAHyAQIIAZIEI1FQcVpEWTRZRlF0a0RjNHpLM1d4WWgxdkR5ZXo3MWI0NzZ0%22%2C%22index%22%3A0%2C%22playlistSetVideoId%22%3A%224A07556FC5C9B361%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kwTPSFQJI4AIkaoEToKHjZcjSSjZgpbx0%22%2C%22params%22%3A%22OAHyAQIIAZIEI1FQcVpEWTRZRlF0a0RjNHpLM1d4WWgxdkR5ZXo3MWI0NzZ0%22%2C%22videoId%22%3A%226EFvgfYsbNY%22%7D%7D%7D%7D May 29 10:50:13 volumio volumio[1151]: info: [ytmusic] Preferred format is Opus May 29 10:50:13 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::sendMpdCommand stop May 29 10:50:13 volumio volumio[1151]: info: sendMpdCommand stop took 1 milliseconds May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::sendMpdCommand clear May 29 10:50:13 volumio volumio[1151]: info: sendMpdCommand clear took 0 milliseconds May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-a5oxu-uoal.googlevideo.com/videoplayback?expire=1748526613&ei=tRE4aIGpIuXJi9oPnv6FsQ4&ip=5.58.123.88&id=o-AHX8AX7b1ECEDA6rXmECouGsaP0OMhEuAbCZEcqP3XP4&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748505013%2C&mh=YD&mm=31%2C29&mn=sn-a5oxu-uoal%2Csn-3c27sne7&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&ctier=A&pfa=5&gcr=ua&initcwndbps=2137500&hightc=yes&siu=1&bui=AY1jyLOPOKDNtgz4xp7KgRk5I84qaxmpEh7mVgKjl6AQtld0jtzuR2WftfFJGcKVeX0mRpUpRQ&spc=l3OVKcX5ht9tZZVUsT_8JGozTBtDcGe2Imhr3SnxrN16_OqblNiMU-yWkwzaJQ1zgDrsrVvClHu6&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=wu2cXjigQl-bGKfOg8O1HxkQ&rqh=1&gir=yes&clen=6880162&dur=211.121&lmt=1747038041960357&mt=1748504700&fvip=16&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=-BceZpZevnPVQA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgWz4CFaPZpUj3fr-hcMgq3OfWLOxhSOo9skpIDSfIDwsCIQCVJ0hnesMnv_gZk-6sGTL-jd7lSG-qyVUHJ9WyM8Td9g%3D%3D&sig=AJfQdSswRQIgJIu0OWbbc13DylHIZI6bsKu8R8bNjgg7AVmKIziFSAQCIQDMBbhlG4zacogergz7ih2F9yh8yIJJgYCuBjl0IWUPGg%3D%3D&pot=Ml8jjH6kct3RYCOwAOlJJZacmdOCL36MxLa4237UqABLNQs1eaCbTh1fMMT9Wox6RyrDRXrFhYmOYTWvmFs_UoO-KI8DACmxlVzD0yFnrOL3fwL8vgteKfFntoOPm5qOpQ%3D%3D&cver=1.20250219.01.00&t.YouTube" May 29 10:50:13 volumio volumio[1151]: info: May 29 10:50:13 volumio volumio[1151]: ---------------------------- MPD announces system playlist update May 29 10:50:13 volumio volumio[1151]: info: Ignoring MPD Status Update May 29 10:50:13 volumio volumio[1151]: info: May 29 10:50:13 volumio volumio[1151]: ---------------------------- MPD announces system playlist update May 29 10:50:13 volumio volumio[1151]: info: Ignoring MPD Status Update May 29 10:50:13 volumio volumio[1151]: info: May 29 10:50:13 volumio volumio[1151]: ---------------------------- MPD announces system playlist update May 29 10:50:13 volumio volumio[1151]: info: Ignoring MPD Status Update May 29 10:50:13 volumio volumio[1151]: info: sendMpdCommand addid "https://rr1---sn-a5oxu-uoal.googlevideo.com/videoplayback?expire=1748526613&ei=tRE4aIGpIuXJi9oPnv6FsQ4&ip=5.58.123.88&id=o-AHX8AX7b1ECEDA6rXmECouGsaP0OMhEuAbCZEcqP3XP4&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748505013%2C&mh=YD&mm=31%2C29&mn=sn-a5oxu-uoal%2Csn-3c27sne7&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&ctier=A&pfa=5&gcr=ua&initcwndbps=2137500&hightc=yes&siu=1&bui=AY1jyLOPOKDNtgz4xp7KgRk5I84qaxmpEh7mVgKjl6AQtld0jtzuR2WftfFJGcKVeX0mRpUpRQ&spc=l3OVKcX5ht9tZZVUsT_8JGozTBtDcGe2Imhr3SnxrN16_OqblNiMU-yWkwzaJQ1zgDrsrVvClHu6&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=wu2cXjigQl-bGKfOg8O1HxkQ&rqh=1&gir=yes&clen=6880162&dur=211.121&lmt=1747038041960357&mt=1748504700&fvip=16&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=-BceZpZevnPVQA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgWz4CFaPZpUj3fr-hcMgq3OfWLOxhSOo9skpIDSfIDwsCIQCVJ0hnesMnv_gZk-6sGTL-jd7lSG-qyVUHJ9WyM8Td9g%3D%3D&sig=AJfQdSswRQIgJIu0OWbbc13DylHIZI6bsKu8R8bNjgg7AVmKIziFSAQCIQDMBbhlG4zacogergz7ih2F9yh8yIJJgYCuBjl0IWUPGg%3D%3D&pot=Ml8jjH6kct3RYCOwAOlJJZacmdOCL36MxLa4237UqABLNQs1eaCbTh1fMMT9Wox6RyrDRXrFhYmOYTWvmFs_UoO-KI8DACmxlVzD0yFnrOL3fwL8vgteKfFntoOPm5qOpQ%3D%3D&cver=1.20250219.01.00&t.YouTube" took 1 milliseconds May 29 10:50:13 volumio volumio[1151]: info: ------------------------------ 1ms May 29 10:50:13 volumio volumio[1151]: info: ------------------------------ 1ms May 29 10:50:13 volumio volumio[1151]: verbose: MPD COMMAND [object Object] May 29 10:50:13 volumio volumio[1151]: verbose: MPD COMMAND [object Object] May 29 10:50:13 volumio volumio[1151]: verbose: MPD COMMAND [object Object] May 29 10:50:13 volumio volumio[1151]: info: May 29 10:50:13 volumio volumio[1151]: ---------------------------- MPD announces system playlist update May 29 10:50:13 volumio volumio[1151]: info: Ignoring MPD Status Update May 29 10:50:13 volumio volumio[1151]: info: May 29 10:50:13 volumio volumio[1151]: ---------------------------- MPD announces system playlist update May 29 10:50:13 volumio volumio[1151]: info: Ignoring MPD Status Update May 29 10:50:13 volumio volumio[1151]: info: ------------------------------ 2ms May 29 10:50:13 volumio volumio[1151]: info: ------------------------------ 1ms May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::sendMpdCommand play May 29 10:50:13 volumio volumio[1151]: info: May 29 10:50:13 volumio volumio[1151]: ---------------------------- MPD announces system playlist update May 29 10:50:13 volumio volumio[1151]: info: Ignoring MPD Status Update May 29 10:50:13 volumio volumio[1151]: info: ------------------------------ 1ms May 29 10:50:13 volumio volumio[1151]: info: sendMpdCommand play took 1 milliseconds May 29 10:50:13 volumio volumio[1151]: info: ------------------------------ 1ms May 29 10:50:13 volumio volumio[1151]: info: May 29 10:50:13 volumio volumio[1151]: ---------------------------- MPD announces state update: player May 29 10:50:13 volumio volumio[1151]: info: ControllerMpd::getState May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::sendMpdCommand status May 29 10:50:13 volumio volumio[1151]: info: May 29 10:50:13 volumio volumio[1151]: ---------------------------- MPD announces state update: player May 29 10:50:13 volumio volumio[1151]: info: ControllerMpd::getState May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::sendMpdCommand status May 29 10:50:13 volumio volumio[1151]: info: May 29 10:50:13 volumio volumio[1151]: ---------------------------- MPD announces state update: player May 29 10:50:13 volumio volumio[1151]: info: sendMpdCommand status took 25 milliseconds May 29 10:50:13 volumio volumio[1151]: info: ControllerMpd::getState May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::sendMpdCommand status May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::parseState May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 10:50:13 volumio volumio[1151]: info: May 29 10:50:13 volumio volumio[1151]: ---------------------------- MPD announces state update: player May 29 10:50:13 volumio volumio[1151]: info: ControllerMpd::getState May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::sendMpdCommand status May 29 10:50:13 volumio volumio[1151]: info: sendMpdCommand status took 27 milliseconds May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::parseState May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 10:50:13 volumio volumio[1151]: info: sendMpdCommand status took 1 milliseconds May 29 10:50:13 volumio volumio[1151]: info: sendMpdCommand playlistinfo took 2 milliseconds May 29 10:50:13 volumio volumio[1151]: info: sendMpdCommand status took 2 milliseconds May 29 10:50:13 volumio volumio[1151]: info: sendMpdCommand playlistinfo took 1 milliseconds May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::parseState May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::parseTrackInfo May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::parseState May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::parseTrackInfo May 29 10:50:13 volumio volumio[1151]: info: ControllerMpd::pushState May 29 10:50:13 volumio volumio[1151]: info: CoreCommandRouter::servicePushState May 29 10:50:13 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 May 29 10:50:13 volumio volumio[1151]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Кайся","artist":"Нікіта Кісельов & iSKra","album":"Кайся","uri":"https://rr1---sn-a5oxu-uoal.googlevideo.com/videoplayback?expire=1748526613&ei=tRE4aIGpIuXJi9oPnv6FsQ4&ip=5.58.123.88&id=o-AHX8AX7b1ECEDA6rXmECouGsaP0OMhEuAbCZEcqP3XP4&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748505013%2C&mh=YD&mm=31%2C29&mn=sn-a5oxu-uoal%2Csn-3c27sne7&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&ctier=A&pfa=5&gcr=ua&initcwndbps=2137500&hightc=yes&siu=1&bui=AY1jyLOPOKDNtgz4xp7KgRk5I84qaxmpEh7mVgKjl6AQtld0jtzuR2WftfFJGcKVeX0mRpUpRQ&spc=l3OVKcX5ht9tZZVUsT_8JGozTBtDcGe2Imhr3SnxrN16_OqblNiMU-yWkwzaJQ1zgDrsrVvClHu6&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=wu2cXjigQl-bGKfOg8O1HxkQ&rqh=1&gir=yes&clen=6880162&dur=211.121&lmt=1747038041960357&mt=1748504700&fvip=16&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=-BceZpZevnPVQA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgWz4CFaPZpUj3fr-hcMgq3OfWLOxhSOo9skpIDSfIDwsCIQCVJ0hnesMnv_gZk-6sGTL-jd7lSG-qyVUHJ9WyM8Td9g%3D%3D&sig=AJfQdSswRQIgJIu0OWbbc13DylHIZI6bsKu8R8bNjgg7AVmKIziFSAQCIQDMBbhlG4zacogergz7ih2F9yh8yIJJgYCuBjl0IWUPGg%3D%3D&pot=Ml8jjH6kct3RYCOwAOlJJZacmdOCL36MxLa4237UqABLNQs1eaCbTh1fMMT9Wox6RyrDRXrFhYmOYTWvmFs_UoO-KI8DACmxlVzD0yFnrOL3fwL8vgteKfFntoOPm5qOpQ%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} May 29 10:50:13 volumio volumio[1151]: verbose: CURRENT POSITION 0 May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::syncState stateService play May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::syncState currentStatus stop May 29 10:50:13 volumio volumio[1151]: info: ControllerMpd::pushState May 29 10:50:13 volumio volumio[1151]: info: CoreCommandRouter::servicePushState May 29 10:50:13 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 May 29 10:50:13 volumio volumio[1151]: verbose: STATE SERVICE {"status":"play","position":0,"seek":489,"duration":211,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Кайся","artist":"Нікіта Кісельов & iSKra","album":"Кайся","uri":"https://rr1---sn-a5oxu-uoal.googlevideo.com/videoplayback?expire=1748526613&ei=tRE4aIGpIuXJi9oPnv6FsQ4&ip=5.58.123.88&id=o-AHX8AX7b1ECEDA6rXmECouGsaP0OMhEuAbCZEcqP3XP4&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748505013%2C&mh=YD&mm=31%2C29&mn=sn-a5oxu-uoal%2Csn-3c27sne7&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&ctier=A&pfa=5&gcr=ua&initcwndbps=2137500&hightc=yes&siu=1&bui=AY1jyLOPOKDNtgz4xp7KgRk5I84qaxmpEh7mVgKjl6AQtld0jtzuR2WftfFJGcKVeX0mRpUpRQ&spc=l3OVKcX5ht9tZZVUsT_8JGozTBtDcGe2Imhr3SnxrN16_OqblNiMU-yWkwzaJQ1zgDrsrVvClHu6&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=wu2cXjigQl-bGKfOg8O1HxkQ&rqh=1&gir=yes&clen=6880162&dur=211.121&lmt=1747038041960357&mt=1748504700&fvip=16&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=-BceZpZevnPVQA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgWz4CFaPZpUj3fr-hcMgq3OfWLOxhSOo9skpIDSfIDwsCIQCVJ0hnesMnv_gZk-6sGTL-jd7lSG-qyVUHJ9WyM8Td9g%3D%3D&sig=AJfQdSswRQIgJIu0OWbbc13DylHIZI6bsKu8R8bNjgg7AVmKIziFSAQCIQDMBbhlG4zacogergz7ih2F9yh8yIJJgYCuBjl0IWUPGg%3D%3D&pot=Ml8jjH6kct3RYCOwAOlJJZacmdOCL36MxLa4237UqABLNQs1eaCbTh1fMMT9Wox6RyrDRXrFhYmOYTWvmFs_UoO-KI8DACmxlVzD0yFnrOL3fwL8vgteKfFntoOPm5qOpQ%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} May 29 10:50:13 volumio volumio[1151]: verbose: CURRENT POSITION 0 May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::syncState stateService play May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::syncState currentStatus play May 29 10:50:13 volumio volumio[1151]: info: Received an update from plugin. extracting info from payload May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::pushState May 29 10:50:13 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 10:50:13 volumio volumio[1151]: info: CoreCommandRouter::volumioPushState May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::pushState May 29 10:50:13 volumio volumio[1151]: info: CoreCommandRouter::volumioPushState May 29 10:50:13 volumio volumio[1151]: info: ------------------------------ 35ms May 29 10:50:13 volumio volumio[1151]: info: ------------------------------ 44ms May 29 10:50:13 volumio volumio[1151]: info: sendMpdCommand playlistinfo took 17 milliseconds May 29 10:50:13 volumio volumio[1151]: info: sendMpdCommand playlistinfo took 17 milliseconds May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::parseTrackInfo May 29 10:50:13 volumio volumio[1151]: verbose: ControllerMpd::parseTrackInfo May 29 10:50:13 volumio volumio[1151]: info: ControllerMpd::pushState May 29 10:50:13 volumio volumio[1151]: info: CoreCommandRouter::servicePushState May 29 10:50:13 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 May 29 10:50:13 volumio volumio[1151]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":211,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Кайся","artist":"Нікіта Кісельов & iSKra","album":"Кайся","uri":"https://rr1---sn-a5oxu-uoal.googlevideo.com/videoplayback?expire=1748526613&ei=tRE4aIGpIuXJi9oPnv6FsQ4&ip=5.58.123.88&id=o-AHX8AX7b1ECEDA6rXmECouGsaP0OMhEuAbCZEcqP3XP4&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748505013%2C&mh=YD&mm=31%2C29&mn=sn-a5oxu-uoal%2Csn-3c27sne7&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&ctier=A&pfa=5&gcr=ua&initcwndbps=2137500&hightc=yes&siu=1&bui=AY1jyLOPOKDNtgz4xp7KgRk5I84qaxmpEh7mVgKjl6AQtld0jtzuR2WftfFJGcKVeX0mRpUpRQ&spc=l3OVKcX5ht9tZZVUsT_8JGozTBtDcGe2Imhr3SnxrN16_OqblNiMU-yWkwzaJQ1zgDrsrVvClHu6&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=wu2cXjigQl-bGKfOg8O1HxkQ&rqh=1&gir=yes&clen=6880162&dur=211.121&lmt=1747038041960357&mt=1748504700&fvip=16&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=-BceZpZevnPVQA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgWz4CFaPZpUj3fr-hcMgq3OfWLOxhSOo9skpIDSfIDwsCIQCVJ0hnesMnv_gZk-6sGTL-jd7lSG-qyVUHJ9WyM8Td9g%3D%3D&sig=AJfQdSswRQIgJIu0OWbbc13DylHIZI6bsKu8R8bNjgg7AVmKIziFSAQCIQDMBbhlG4zacogergz7ih2F9yh8yIJJgYCuBjl0IWUPGg%3D%3D&pot=Ml8jjH6kct3RYCOwAOlJJZacmdOCL36MxLa4237UqABLNQs1eaCbTh1fMMT9Wox6RyrDRXrFhYmOYTWvmFs_UoO-KI8DACmxlVzD0yFnrOL3fwL8vgteKfFntoOPm5qOpQ%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} May 29 10:50:13 volumio volumio[1151]: verbose: CURRENT POSITION 0 May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::syncState stateService play May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::syncState currentStatus play May 29 10:50:13 volumio volumio[1151]: info: Received an update from plugin. extracting info from payload May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::pushState May 29 10:50:13 volumio volumio[1151]: info: CoreCommandRouter::volumioPushState May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::pushState May 29 10:50:13 volumio volumio[1151]: info: CoreCommandRouter::volumioPushState May 29 10:50:13 volumio volumio[1151]: info: ControllerMpd::pushState May 29 10:50:13 volumio volumio[1151]: info: CoreCommandRouter::servicePushState May 29 10:50:13 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 May 29 10:50:13 volumio volumio[1151]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":211,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Кайся","artist":"Нікіта Кісельов & iSKra","album":"Кайся","uri":"https://rr1---sn-a5oxu-uoal.googlevideo.com/videoplayback?expire=1748526613&ei=tRE4aIGpIuXJi9oPnv6FsQ4&ip=5.58.123.88&id=o-AHX8AX7b1ECEDA6rXmECouGsaP0OMhEuAbCZEcqP3XP4&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748505013%2C&mh=YD&mm=31%2C29&mn=sn-a5oxu-uoal%2Csn-3c27sne7&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&ctier=A&pfa=5&gcr=ua&initcwndbps=2137500&hightc=yes&siu=1&bui=AY1jyLOPOKDNtgz4xp7KgRk5I84qaxmpEh7mVgKjl6AQtld0jtzuR2WftfFJGcKVeX0mRpUpRQ&spc=l3OVKcX5ht9tZZVUsT_8JGozTBtDcGe2Imhr3SnxrN16_OqblNiMU-yWkwzaJQ1zgDrsrVvClHu6&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=wu2cXjigQl-bGKfOg8O1HxkQ&rqh=1&gir=yes&clen=6880162&dur=211.121&lmt=1747038041960357&mt=1748504700&fvip=16&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=-BceZpZevnPVQA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgWz4CFaPZpUj3fr-hcMgq3OfWLOxhSOo9skpIDSfIDwsCIQCVJ0hnesMnv_gZk-6sGTL-jd7lSG-qyVUHJ9WyM8Td9g%3D%3D&sig=AJfQdSswRQIgJIu0OWbbc13DylHIZI6bsKu8R8bNjgg7AVmKIziFSAQCIQDMBbhlG4zacogergz7ih2F9yh8yIJJgYCuBjl0IWUPGg%3D%3D&pot=Ml8jjH6kct3RYCOwAOlJJZacmdOCL36MxLa4237UqABLNQs1eaCbTh1fMMT9Wox6RyrDRXrFhYmOYTWvmFs_UoO-KI8DACmxlVzD0yFnrOL3fwL8vgteKfFntoOPm5qOpQ%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} May 29 10:50:13 volumio volumio[1151]: verbose: CURRENT POSITION 0 May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::syncState stateService play May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::syncState currentStatus play May 29 10:50:13 volumio volumio[1151]: info: Received an update from plugin. extracting info from payload May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::pushState May 29 10:50:13 volumio volumio[1151]: info: CoreCommandRouter::volumioPushState May 29 10:50:13 volumio volumio[1151]: info: CoreStateMachine::pushState May 29 10:50:13 volumio volumio[1151]: info: CoreCommandRouter::volumioPushState May 29 10:50:13 volumio volumio[1151]: info: ------------------------------ 46ms May 29 10:50:13 volumio volumio[1151]: info: ------------------------------ 45ms May 29 10:50:13 volumio volumio[1151]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 29 10:50:13 volumio volumio[1151]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 29 10:50:13 volumio volumio[1151]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 29 10:50:13 volumio volumio[1151]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 29 10:50:13 volumio volumio[1151]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 29 10:50:13 volumio volumio[1151]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 29 10:50:13 volumio volumio[1151]: info: PeppyMeterBasic ---peppymeterbasic status play May 29 10:50:13 volumio volumio[1151]: info: PeppyMeterBasic ---peppymeterbasic status play May 29 10:50:13 volumio volumio[1151]: info: PeppyMeterBasic ---peppymeterbasic status play May 29 10:50:13 volumio sudo[4739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 29 10:50:13 volumio sudo[4739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 10:50:13 volumio sudo[4741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 29 10:50:13 volumio sudo[4741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 10:50:13 volumio volumio[1151]: info: PeppyMeterBasic ---peppymeterbasic status play May 29 10:50:13 volumio sudo[4741]: pam_unix(sudo:session): session closed for user root May 29 10:50:13 volumio volumio[1151]: info: PeppyMeterBasic ---peppymeterbasic status play May 29 10:50:13 volumio sudo[4739]: pam_unix(sudo:session): session closed for user root May 29 10:50:14 volumio volumio[1151]: info: PeppyMeterBasic ---peppymeterbasic status play May 29 10:50:14 volumio sudo[4745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 29 10:50:14 volumio sudo[4745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 10:50:14 volumio volumio[1151]: info: peppymeterbasic Daemon Started May 29 10:50:14 volumio volumio[1151]: info: peppymeterbasic Daemon Started May 29 10:50:14 volumio sudo[4747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 29 10:50:14 volumio sudo[4747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 10:50:14 volumio sudo[4749]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 29 10:50:14 volumio sudo[4749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 10:50:14 volumio sudo[4752]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 29 10:50:14 volumio sudo[4745]: pam_unix(sudo:session): session closed for user root May 29 10:50:14 volumio sudo[4752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 10:50:14 volumio sudo[4747]: pam_unix(sudo:session): session closed for user root May 29 10:50:14 volumio volumio[1151]: info: peppymeterbasic Daemon Started May 29 10:50:14 volumio volumio[1151]: info: peppymeterbasic Daemon Started May 29 10:50:14 volumio sudo[4749]: pam_unix(sudo:session): session closed for user root May 29 10:50:14 volumio volumio[1151]: info: peppymeterbasic Daemon Started May 29 10:50:14 volumio sudo[4752]: pam_unix(sudo:session): session closed for user root May 29 10:50:14 volumio volumio[1151]: info: peppymeterbasic Daemon Started May 29 10:50:18 volumio go-librespot[4138]: time="2025-05-29T10:50:18+03:00" level=trace msg="sent dealer ping" May 29 10:50:19 volumio go-librespot[4138]: time="2025-05-29T10:50:19+03:00" level=trace msg="received dealer pong" May 29 10:50:23 volumio volumio[1151]: info: CoreCommandRouter::volumioGetState May 29 10:50:31 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 29 10:50:31 volumio volumio[1151]: info: In handleBrowseUri, curUri=spotify May 29 10:50:31 volumio volumio[1151]: info: Preload queue cleared May 29 10:50:31 volumio volumio[1151]: info: Preload queue cleared May 29 10:50:31 volumio volumio[1151]: info: Preload queue cleared May 29 10:50:31 volumio volumio[1151]: info: Preload queue cleared May 29 10:50:33 volumio volumio[1151]: info: Preload queue cleared May 29 10:50:33 volumio volumio[1151]: info: CoreCommandRouter::volumioReplaceandPlayItems May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::ClearQueue May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::stop May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::stPlaybackTimer May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::updateTrackBlock May 29 10:50:33 volumio volumio[1151]: info: CorePlayQueue::getTrackBlock May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::pushState May 29 10:50:33 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 May 29 10:50:33 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 10:50:33 volumio volumio[1151]: info: CoreCommandRouter::volumioPushState May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::serviceStop May 29 10:50:33 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 May 29 10:50:33 volumio volumio[1151]: info: CoreCommandRouter::serviceStop May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 10:50:33 volumio volumio[1151]: info: ControllerMpd::stop May 29 10:50:33 volumio volumio[1151]: verbose: ControllerMpd::sendMpdCommand stop May 29 10:50:33 volumio volumio[1151]: info: CorePlayQueue::clearPlayQueue May 29 10:50:33 volumio volumio[1151]: info: CorePlayQueue::saveQueue May 29 10:50:33 volumio volumio[1151]: info: CoreCommandRouter::volumioPushQueue May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::addQueueItems May 29 10:50:33 volumio volumio[1151]: info: CorePlayQueue::addQueueItems May 29 10:50:33 volumio volumio[1151]: info: Preload queue cleared May 29 10:50:33 volumio volumio[1151]: info: Adding Item to queue: spotify:playlist:37i9dQZF1DX1V3tM4cuX0v May 29 10:50:33 volumio volumio[1151]: info: Exploding uri spotify:playlist:37i9dQZF1DX1V3tM4cuX0v in service spop May 29 10:50:33 volumio volumio[1151]: SPOTIFY: EXPLODING URI:spotify:playlist:37i9dQZF1DX1V3tM4cuX0v May 29 10:50:33 volumio volumio[1151]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 29 10:50:33 volumio volumio[1151]: info: May 29 10:50:33 volumio volumio[1151]: ---------------------------- MPD announces state update: player May 29 10:50:33 volumio volumio[1151]: info: sendMpdCommand stop took 20 milliseconds May 29 10:50:33 volumio volumio[1151]: info: ControllerMpd::getState May 29 10:50:33 volumio volumio[1151]: verbose: ControllerMpd::sendMpdCommand status May 29 10:50:33 volumio volumio[1151]: info: sendMpdCommand status took 1 milliseconds May 29 10:50:33 volumio volumio[1151]: verbose: ControllerMpd::parseState May 29 10:50:33 volumio volumio[1151]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 10:50:33 volumio volumio[1151]: info: May 29 10:50:33 volumio volumio[1151]: ---------------------------- MPD announces state update: player May 29 10:50:33 volumio volumio[1151]: info: ControllerMpd::getState May 29 10:50:33 volumio volumio[1151]: verbose: ControllerMpd::sendMpdCommand status May 29 10:50:33 volumio volumio[1151]: info: sendMpdCommand playlistinfo took 1 milliseconds May 29 10:50:33 volumio volumio[1151]: info: sendMpdCommand status took 1 milliseconds May 29 10:50:33 volumio volumio[1151]: verbose: ControllerMpd::parseTrackInfo May 29 10:50:33 volumio volumio[1151]: verbose: ControllerMpd::parseState May 29 10:50:33 volumio volumio[1151]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 10:50:33 volumio volumio[1151]: info: ControllerMpd::pushState May 29 10:50:33 volumio volumio[1151]: info: CoreCommandRouter::servicePushState May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::pushState May 29 10:50:33 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 10:50:33 volumio volumio[1151]: info: CoreCommandRouter::volumioPushState May 29 10:50:33 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 May 29 10:50:33 volumio volumio[1151]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Кайся","artist":"Нікіта Кісельов & iSKra","album":"Кайся","uri":"https://rr1---sn-a5oxu-uoal.googlevideo.com/videoplayback?expire=1748526613&ei=tRE4aIGpIuXJi9oPnv6FsQ4&ip=5.58.123.88&id=o-AHX8AX7b1ECEDA6rXmECouGsaP0OMhEuAbCZEcqP3XP4&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748505013%2C&mh=YD&mm=31%2C29&mn=sn-a5oxu-uoal%2Csn-3c27sne7&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&ctier=A&pfa=5&gcr=ua&initcwndbps=2137500&hightc=yes&siu=1&bui=AY1jyLOPOKDNtgz4xp7KgRk5I84qaxmpEh7mVgKjl6AQtld0jtzuR2WftfFJGcKVeX0mRpUpRQ&spc=l3OVKcX5ht9tZZVUsT_8JGozTBtDcGe2Imhr3SnxrN16_OqblNiMU-yWkwzaJQ1zgDrsrVvClHu6&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=wu2cXjigQl-bGKfOg8O1HxkQ&rqh=1&gir=yes&clen=6880162&dur=211.121&lmt=1747038041960357&mt=1748504700&fvip=16&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=-BceZpZevnPVQA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgWz4CFaPZpUj3fr-hcMgq3OfWLOxhSOo9skpIDSfIDwsCIQCVJ0hnesMnv_gZk-6sGTL-jd7lSG-qyVUHJ9WyM8Td9g%3D%3D&sig=AJfQdSswRQIgJIu0OWbbc13DylHIZI6bsKu8R8bNjgg7AVmKIziFSAQCIQDMBbhlG4zacogergz7ih2F9yh8yIJJgYCuBjl0IWUPGg%3D%3D&pot=Ml8jjH6kct3RYCOwAOlJJZacmdOCL36MxLa4237UqABLNQs1eaCbTh1fMMT9Wox6RyrDRXrFhYmOYTWvmFs_UoO-KI8DACmxlVzD0yFnrOL3fwL8vgteKfFntoOPm5qOpQ%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} May 29 10:50:33 volumio volumio[1151]: verbose: CURRENT POSITION 0 May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::syncState stateService stop May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::syncState currentStatus stop May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::pushState May 29 10:50:33 volumio volumio[1151]: info: CoreCommandRouter::volumioPushState May 29 10:50:33 volumio volumio[1151]: info: No code May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::pushState May 29 10:50:33 volumio volumio[1151]: info: CoreCommandRouter::volumioPushState May 29 10:50:33 volumio volumio[1151]: info: ------------------------------ 19ms May 29 10:50:33 volumio volumio[1151]: info: sendMpdCommand playlistinfo took 17 milliseconds May 29 10:50:33 volumio volumio[1151]: verbose: ControllerMpd::parseTrackInfo May 29 10:50:33 volumio volumio[1151]: info: ControllerMpd::pushState May 29 10:50:33 volumio volumio[1151]: info: CoreCommandRouter::servicePushState May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::pushState May 29 10:50:33 volumio volumio[1151]: info: CoreCommandRouter::volumioPushState May 29 10:50:33 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 May 29 10:50:33 volumio volumio[1151]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Кайся","artist":"Нікіта Кісельов & iSKra","album":"Кайся","uri":"https://rr1---sn-a5oxu-uoal.googlevideo.com/videoplayback?expire=1748526613&ei=tRE4aIGpIuXJi9oPnv6FsQ4&ip=5.58.123.88&id=o-AHX8AX7b1ECEDA6rXmECouGsaP0OMhEuAbCZEcqP3XP4&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748505013%2C&mh=YD&mm=31%2C29&mn=sn-a5oxu-uoal%2Csn-3c27sne7&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&ctier=A&pfa=5&gcr=ua&initcwndbps=2137500&hightc=yes&siu=1&bui=AY1jyLOPOKDNtgz4xp7KgRk5I84qaxmpEh7mVgKjl6AQtld0jtzuR2WftfFJGcKVeX0mRpUpRQ&spc=l3OVKcX5ht9tZZVUsT_8JGozTBtDcGe2Imhr3SnxrN16_OqblNiMU-yWkwzaJQ1zgDrsrVvClHu6&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=wu2cXjigQl-bGKfOg8O1HxkQ&rqh=1&gir=yes&clen=6880162&dur=211.121&lmt=1747038041960357&mt=1748504700&fvip=16&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=-BceZpZevnPVQA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgWz4CFaPZpUj3fr-hcMgq3OfWLOxhSOo9skpIDSfIDwsCIQCVJ0hnesMnv_gZk-6sGTL-jd7lSG-qyVUHJ9WyM8Td9g%3D%3D&sig=AJfQdSswRQIgJIu0OWbbc13DylHIZI6bsKu8R8bNjgg7AVmKIziFSAQCIQDMBbhlG4zacogergz7ih2F9yh8yIJJgYCuBjl0IWUPGg%3D%3D&pot=Ml8jjH6kct3RYCOwAOlJJZacmdOCL36MxLa4237UqABLNQs1eaCbTh1fMMT9Wox6RyrDRXrFhYmOYTWvmFs_UoO-KI8DACmxlVzD0yFnrOL3fwL8vgteKfFntoOPm5qOpQ%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} May 29 10:50:33 volumio volumio[1151]: verbose: CURRENT POSITION 0 May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::syncState stateService stop May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::syncState currentStatus stop May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::pushState May 29 10:50:33 volumio volumio[1151]: info: CoreCommandRouter::volumioPushState May 29 10:50:33 volumio volumio[1151]: info: No code May 29 10:50:33 volumio volumio[1151]: info: CoreStateMachine::pushState May 29 10:50:33 volumio volumio[1151]: info: CoreCommandRouter::volumioPushState May 29 10:50:33 volumio volumio[1151]: info: ------------------------------ 34ms May 29 10:50:33 volumio volumio[1151]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 29 10:50:33 volumio volumio[1151]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 29 10:50:33 volumio volumio[1151]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 29 10:50:33 volumio volumio[1151]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 29 10:50:33 volumio volumio[1151]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 29 10:50:33 volumio volumio[1151]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 29 10:50:33 volumio volumio[1151]: info: PeppyMeterBasic ---peppymeterbasic status stop May 29 10:50:33 volumio volumio[1151]: info: PeppyMeterBasic ---peppymeterbasic status play May 29 10:50:33 volumio volumio[1151]: info: PeppyMeterBasic ---peppymeterbasic status play May 29 10:50:33 volumio sudo[4786]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 29 10:50:33 volumio sudo[4786]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 10:50:33 volumio volumio[1151]: info: PeppyMeterBasic ---peppymeterbasic status play May 29 10:50:33 volumio sudo[4788]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 29 10:50:33 volumio sudo[4788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 10:50:33 volumio volumio[1151]: info: PeppyMeterBasic ---peppymeterbasic status play May 29 10:50:33 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... May 29 10:50:33 volumio sudo[4791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 29 10:50:33 volumio sudo[4791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 10:50:33 volumio volumio[1151]: info: PeppyMeterBasic ---peppymeterbasic status play May 29 10:50:33 volumio sudo[4794]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 29 10:50:33 volumio sudo[4794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 10:50:33 volumio volumio[1151]: info: PeppyMeterBasic ---peppymeterbasic status play May 29 10:50:33 volumio sudo[4797]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 29 10:50:33 volumio sudo[4797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 10:50:33 volumio sudo[4786]: pam_unix(sudo:session): session closed for user root May 29 10:50:33 volumio sudo[4800]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 29 10:50:33 volumio sudo[4800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 10:50:33 volumio volumio[1151]: info: PeppyMeterBasic ---peppymeterbasic failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppymeterbasic.service May 29 10:50:33 volumio volumio[1151]: Job for peppymeterbasic.service canceled. May 29 10:50:33 volumio sudo[4802]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 29 10:50:33 volumio sudo[4802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 10:50:33 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. May 29 10:50:33 volumio systemd[1]: peppymeterbasic.service: Consumed 1.952s CPU time. May 29 10:50:33 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. May 29 10:50:33 volumio sudo[4791]: pam_unix(sudo:session): session closed for user root May 29 10:50:33 volumio sudo[4788]: pam_unix(sudo:session): session closed for user root May 29 10:50:33 volumio sudo[4794]: pam_unix(sudo:session): session closed for user root May 29 10:50:33 volumio sudo[4802]: pam_unix(sudo:session): session closed for user root May 29 10:50:33 volumio sudo[4797]: pam_unix(sudo:session): session closed for user root May 29 10:50:33 volumio sudo[4800]: pam_unix(sudo:session): session closed for user root May 29 10:50:33 volumio volumio[1151]: info: peppymeterbasic Daemon Started May 29 10:50:33 volumio volumio[1151]: info: peppymeterbasic Daemon Started May 29 10:50:33 volumio volumio[1151]: info: peppymeterbasic Daemon Started May 29 10:50:33 volumio volumio[1151]: info: peppymeterbasic Daemon Started May 29 10:50:33 volumio volumio[1151]: info: peppymeterbasic Daemon Started May 29 10:50:33 volumio volumio[1151]: info: peppymeterbasic Daemon Started May 29 10:50:34 volumio volumio[1151]: info: CoreCommandRouter::volumioPushQueue May 29 10:50:34 volumio volumio[1151]: info: CorePlayQueue::saveQueue May 29 10:50:34 volumio volumio[1151]: info: CoreStateMachine::updateTrackBlock May 29 10:50:34 volumio volumio[1151]: info: CorePlayQueue::getTrackBlock May 29 10:50:34 volumio volumio[1151]: info: CoreCommandRouter::volumioPlay May 29 10:50:34 volumio volumio[1151]: info: CoreStateMachine::play index 0 May 29 10:50:34 volumio volumio[1151]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 10:50:34 volumio volumio[1151]: info: CoreStateMachine::stop May 29 10:50:34 volumio volumio[1151]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 10:50:34 volumio volumio[1151]: info: CoreStateMachine::play index undefined May 29 10:50:34 volumio volumio[1151]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 10:50:34 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 May 29 10:50:34 volumio volumio[1151]: info: CoreStateMachine::startPlaybackTimer May 29 10:50:34 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 May 29 10:50:34 volumio volumio[1151]: info: [1748505034067] ControllerSpotify::clearAddPlayTrack May 29 10:50:34 volumio volumio[1151]: info: Sending Spotify command with payload to local API: /player/play May 29 10:50:34 volumio go-librespot[4138]: time="2025-05-29T10:50:34+03:00" level=debug msg="resolved context of track" uri="spotify:track:7JZs5hNCz4be2sJxaLIy5l" May 29 10:50:34 volumio go-librespot[4138]: time="2025-05-29T10:50:34+03:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:7JZs5hNCz4be2sJxaLIy5l" May 29 10:50:34 volumio go-librespot[4138]: time="2025-05-29T10:50:34+03:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:7JZs5hNCz4be2sJxaLIy5l" May 29 10:50:34 volumio go-librespot[4138]: time="2025-05-29T10:50:34+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 29 10:50:34 volumio go-librespot[4138]: time="2025-05-29T10:50:34+03:00" level=trace msg="emitting websocket event: will_play" May 29 10:50:34 volumio volumio[1151]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7JZs5hNCz4be2sJxaLIy5l","play_origin":"go-librespot"}} May 29 10:50:34 volumio volumio[1151]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7JZs5hNCz4be2sJxaLIy5l","play_origin":"go-librespot"}} May 29 10:50:34 volumio go-librespot[4138]: time="2025-05-29T10:50:34+03:00" level=debug msg="selected format OGG_VORBIS_320 (f5e9ca3d8f1dc911ffae90a1ff6f7abfaf244c4a)" uri="spotify:track:7JZs5hNCz4be2sJxaLIy5l" May 29 10:50:34 volumio go-librespot[4138]: time="2025-05-29T10:50:34+03:00" level=debug msg="requested aes key for file f5e9ca3d8f1dc911ffae90a1ff6f7abfaf244c4a, gid: 7JZs5hNCz4be2sJxaLIy5l" May 29 10:50:34 volumio go-librespot[4138]: time="2025-05-29T10:50:34+03:00" level=debug msg="fetched first chunk of 16, total size is 7924565 bytes" uri="spotify:track:7JZs5hNCz4be2sJxaLIy5l" May 29 10:50:34 volumio go-librespot[4138]: time="2025-05-29T10:50:34+03:00" level=debug msg="created new output device" May 29 10:50:34 volumio go-librespot[4138]: time="2025-05-29T10:50:34+03:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" May 29 10:50:34 volumio go-librespot[4138]: time="2025-05-29T10:50:34+03:00" level=info msg="loaded track \"З якого ти поверху неба?\" (paused: false, position: 0ms, duration: 196817ms, prefetched: false)" uri="spotify:track:7JZs5hNCz4be2sJxaLIy5l" May 29 10:50:34 volumio go-librespot[4138]: go-librespot: pcm_meter.c:1220: snd_pcm_scope_s16_get_channel_buffer: Assertion `s16->buf_areas' failed. May 29 10:50:34 volumio go-librespot[4137]: Aborted May 29 10:50:34 volumio volumio[1151]: info: Connection to go-librespot Websocket closed May 29 10:50:34 volumio volumio[1151]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up May 29 10:50:34 volumio volumio[1151]: info: Connection to go-librespot Websocket closed May 29 10:50:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a May 29 10:50:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 29 10:50:37 volumio volumio[1151]: info: Initializing connection to go-librespot Websocket May 29 10:50:37 volumio volumio[1151]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 29 10:50:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. May 29 10:50:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 29 10:50:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 29 10:50:37 volumio go-librespot[4820]: go-librespot daemon starting... May 29 10:50:37 volumio go-librespot[4821]: time="2025-05-29T10:50:37+03:00" level=info msg="running go-librespot 0.2.0" May 29 10:50:37 volumio go-librespot[4821]: time="2025-05-29T10:50:37+03:00" level=debug msg="app state loaded" May 29 10:50:37 volumio go-librespot[4821]: time="2025-05-29T10:50:37+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 29 10:50:37 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 29 10:50:37 volumio go-librespot[4821]: time="2025-05-29T10:50:37+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 29 10:50:37 volumio go-librespot[4821]: time="2025-05-29T10:50:37+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 29 10:50:37 volumio go-librespot[4821]: time="2025-05-29T10:50:37+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 29 10:50:37 volumio go-librespot[4821]: time="2025-05-29T10:50:37+03:00" level=info msg="zeroconf server listening on port 44879" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=debug msg="obtained new client token: AAA+AbcokiiLg1xjzZqU9op8HnkITU63eVXnnCB5xDFkvhXKi4UUSNdKACaY07Z/w7PfMPBYjMZHikVL/V6KX5cmS+0031J+PjF0g49t7FmrnLF5yK7D9JBTY0NRELSgzUF4XBMJFtqkO4Y+pMxPMaHGd+iDjp5yOvWnGafY5hdx2qgoOPUXc47J+Xkd7F35OF5i8L2/M0j58amGI7fgR7LIZyL+Jodb+7gwJ5L6QFJ2Wfp6IVe9/PI=" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=debug msg="connected to ap-gew1.spotify.com:443" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=debug msg="completed keyexchange" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=debug msg="completed challenge" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=info msg="authenticated AP as pb5pp9zr5gss9vfrrdysusbut" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=info msg="authenticated Login5 as pb5pp9zr5gss9vfrrdysusbut" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=debug msg="initializing zeroconf session, username: pb5pp9zr5gss9vfrrdysusbut" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=debug msg="dealer connection opened" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=trace msg="starting accesspoint recv loop" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=trace msg="starting dealer recv loop" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=trace msg="received accesspoint ping" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=debug msg="received connection id: NzViMDJkYWUtZjcyZC00ZWNkLWJhNjAtZDY4NzlmZGMwMDZhK2RlYWxlcit0Y3A6Ly8wYWNhNDI1ZS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArQkVDODlEREY0NjM3NTI5MzU1QjhGRjU3REJDMDYzMDQwQjdCQjcxOEE4NzRGMjY5MEY4Rjk1QTlDRjY3MkUxMA==" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=trace msg="received accesspoint pong ack" May 29 10:50:38 volumio go-librespot[4821]: time="2025-05-29T10:50:38+03:00" level=debug msg="put connect state because NEW_DEVICE" May 29 10:50:38 volumio volumio[1151]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh May 29 10:50:38 volumio volumio[1151]: Traceback (most recent call last): May 29 10:50:38 volumio volumio[1151]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in May 29 10:50:38 volumio volumio[1151]: from volumio_albumart import AlbumartAnimator May 29 10:50:38 volumio volumio[1151]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in May 29 10:50:38 volumio volumio[1151]: import socketio May 29 10:50:38 volumio volumio[1151]: ModuleNotFoundError: No module named 'socketio' May 29 10:50:40 volumio volumio[1151]: info: Initializing connection to go-librespot Websocket May 29 10:50:40 volumio go-librespot[4821]: time="2025-05-29T10:50:40+03:00" level=debug msg="new websocket client" May 29 10:50:40 volumio volumio[1151]: info: Connection to go-librespot Websocket established May 29 10:50:43 volumio volumio[1151]: info: Getting Spotify volume May 29 10:50:43 volumio volumio[1151]: info: Spotify volume: 100 May 29 10:50:43 volumio volumio[1151]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 May 29 10:50:43 volumio volumio[1151]: info: CoreCommandRouter::volumioGetState May 29 10:50:43 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 May 29 10:50:43 volumio volumio[1151]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 29 10:50:43 volumio volumio[1151]: SPOTIFY: SPOTIFY VOLUME 100 May 29 10:50:43 volumio volumio[1151]: SPOTIFY: VOLUMIO VOLUME 95 May 29 10:50:43 volumio volumio[1151]: SPOTIFY: DELTA VOLUME ENOUGH: true May 29 10:50:43 volumio volumio[1151]: info: Setting Spotify Volume from Volumio: 95 May 29 10:50:45 volumio volumio[1151]: SPOTIFY: SETTING SPOTIFY VOLUME 95 May 29 10:50:45 volumio volumio[1151]: info: Sending Spotify command with payload to local API: /player/volume May 29 10:50:45 volumio go-librespot[4821]: time="2025-05-29T10:50:45+03:00" level=debug msg="update volume to 62258/65535" May 29 10:50:46 volumio go-librespot[4821]: time="2025-05-29T10:50:46+03:00" level=debug msg="put connect state because VOLUME_CHANGED" May 29 10:50:46 volumio go-librespot[4821]: time="2025-05-29T10:50:46+03:00" level=trace msg="emitting websocket event: volume" May 29 10:50:46 volumio volumio[1151]: SPOTIFY: received: {"type":"volume","data":{"value":95,"max":100}} May 29 10:50:46 volumio volumio[1151]: SPOTIFY: RECEIVED SPOTIFY VOLUME 95 May 29 10:50:52 volumio volumio[1151]: info: CoreCommandRouter::volumioNext May 29 10:50:52 volumio volumio[1151]: info: CoreStateMachine::next May 29 10:50:52 volumio volumio[1151]: info: CoreStateMachine::stop May 29 10:50:52 volumio volumio[1151]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 10:50:52 volumio volumio[1151]: info: CoreStateMachine::play index undefined May 29 10:50:52 volumio volumio[1151]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 10:50:52 volumio volumio[1151]: info: CorePlayQueue::getTrack 1 May 29 10:50:52 volumio volumio[1151]: info: CoreStateMachine::startPlaybackTimer May 29 10:50:52 volumio volumio[1151]: info: CorePlayQueue::getTrack 1 May 29 10:50:52 volumio volumio[1151]: info: [1748505052888] ControllerSpotify::clearAddPlayTrack May 29 10:50:52 volumio volumio[1151]: info: Sending Spotify command with payload to local API: /player/play May 29 10:50:52 volumio volumio[1151]: info: CoreStateMachine::updateTrackBlock May 29 10:50:52 volumio volumio[1151]: info: CorePlayQueue::getTrackBlock May 29 10:50:52 volumio go-librespot[4821]: time="2025-05-29T10:50:52+03:00" level=debug msg="resolved context of track" uri="spotify:track:4PMQ591DnjhGRYZNLETsno" May 29 10:50:52 volumio go-librespot[4821]: time="2025-05-29T10:50:52+03:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:4PMQ591DnjhGRYZNLETsno" May 29 10:50:52 volumio go-librespot[4821]: time="2025-05-29T10:50:52+03:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:4PMQ591DnjhGRYZNLETsno" May 29 10:50:53 volumio go-librespot[4821]: time="2025-05-29T10:50:53+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 29 10:50:53 volumio go-librespot[4821]: time="2025-05-29T10:50:53+03:00" level=trace msg="emitting websocket event: will_play" May 29 10:50:53 volumio volumio[1151]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4PMQ591DnjhGRYZNLETsno","play_origin":"go-librespot"}} May 29 10:50:53 volumio go-librespot[4821]: time="2025-05-29T10:50:53+03:00" level=debug msg="selected format OGG_VORBIS_320 (ebbf782d4f8d6119e19e33fc2effc659da58175a)" uri="spotify:track:4PMQ591DnjhGRYZNLETsno" May 29 10:50:53 volumio go-librespot[4821]: time="2025-05-29T10:50:53+03:00" level=debug msg="requested aes key for file ebbf782d4f8d6119e19e33fc2effc659da58175a, gid: 4PMQ591DnjhGRYZNLETsno" May 29 10:50:53 volumio go-librespot[4821]: time="2025-05-29T10:50:53+03:00" level=debug msg="fetched first chunk of 17, total size is 8517550 bytes" uri="spotify:track:4PMQ591DnjhGRYZNLETsno" May 29 10:50:53 volumio go-librespot[4821]: time="2025-05-29T10:50:53+03:00" level=debug msg="created new output device" May 29 10:50:53 volumio go-librespot[4821]: time="2025-05-29T10:50:53+03:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" May 29 10:50:53 volumio go-librespot[4821]: time="2025-05-29T10:50:53+03:00" level=info msg="loaded track \"Пінаколада\" (paused: false, position: 0ms, duration: 195784ms, prefetched: false)" uri="spotify:track:4PMQ591DnjhGRYZNLETsno" May 29 10:50:53 volumio go-librespot[4821]: go-librespot: pcm_meter.c:1220: snd_pcm_scope_s16_get_channel_buffer: Assertion `s16->buf_areas' failed. May 29 10:50:53 volumio go-librespot[4820]: Aborted May 29 10:50:53 volumio volumio[1151]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up May 29 10:50:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a May 29 10:50:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 29 10:50:53 volumio volumio[1151]: info: Connection to go-librespot Websocket closed May 29 10:50:56 volumio volumio[1151]: info: Initializing connection to go-librespot Websocket May 29 10:50:56 volumio volumio[1151]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 29 10:50:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. May 29 10:50:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 29 10:50:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 29 10:50:56 volumio go-librespot[4865]: go-librespot daemon starting... May 29 10:50:56 volumio go-librespot[4866]: time="2025-05-29T10:50:56+03:00" level=info msg="running go-librespot 0.2.0" May 29 10:50:56 volumio go-librespot[4866]: time="2025-05-29T10:50:56+03:00" level=debug msg="app state loaded" May 29 10:50:56 volumio go-librespot[4866]: time="2025-05-29T10:50:56+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 29 10:50:56 volumio go-librespot[4866]: time="2025-05-29T10:50:56+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 29 10:50:56 volumio go-librespot[4866]: time="2025-05-29T10:50:56+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 29 10:50:56 volumio go-librespot[4866]: time="2025-05-29T10:50:56+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 29 10:50:56 volumio go-librespot[4866]: time="2025-05-29T10:50:56+03:00" level=info msg="zeroconf server listening on port 43913" May 29 10:50:56 volumio go-librespot[4866]: time="2025-05-29T10:50:56+03:00" level=debug msg="obtained new client token: AAChYoE1MdDGWjZe5KpLNI3k83GRhC4THfxySmkjfti9OvBDhLjyXxbLR9NgIEWlSiZglQdGNF7cL4njyOpf7l2Ose+jqQ02wP4ZuVADeONUNiGb+KOmZYsdkUEBLe+pKDgk4SU3uymTKrIZE8L7wa+fwVRQsW0Ep7tAShX7FXu7Os1BDAzxNoTYJL5gM0PCjDkjQhVFlNMZvl6U4j8jD9snax5EfP7BoPStemMUOqhrYzbPrvdG" May 29 10:50:56 volumio go-librespot[4866]: time="2025-05-29T10:50:56+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" May 29 10:50:56 volumio go-librespot[4866]: time="2025-05-29T10:50:56+03:00" level=debug msg="connected to ap-gew1.spotify.com:443" May 29 10:50:57 volumio go-librespot[4866]: time="2025-05-29T10:50:57+03:00" level=debug msg="completed keyexchange" May 29 10:50:57 volumio go-librespot[4866]: time="2025-05-29T10:50:57+03:00" level=debug msg="completed challenge" May 29 10:50:57 volumio go-librespot[4866]: time="2025-05-29T10:50:57+03:00" level=info msg="authenticated AP as pb5pp9zr5gss9vfrrdysusbut" May 29 10:50:57 volumio go-librespot[4866]: time="2025-05-29T10:50:57+03:00" level=info msg="authenticated Login5 as pb5pp9zr5gss9vfrrdysusbut" May 29 10:50:57 volumio go-librespot[4866]: time="2025-05-29T10:50:57+03:00" level=debug msg="initializing zeroconf session, username: pb5pp9zr5gss9vfrrdysusbut" May 29 10:50:57 volumio go-librespot[4866]: time="2025-05-29T10:50:57+03:00" level=debug msg="dealer connection opened" May 29 10:50:57 volumio go-librespot[4866]: time="2025-05-29T10:50:57+03:00" level=trace msg="starting accesspoint recv loop" May 29 10:50:57 volumio go-librespot[4866]: time="2025-05-29T10:50:57+03:00" level=trace msg="starting dealer recv loop" May 29 10:50:57 volumio go-librespot[4866]: time="2025-05-29T10:50:57+03:00" level=trace msg="received accesspoint ping" May 29 10:50:57 volumio go-librespot[4866]: time="2025-05-29T10:50:57+03:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 29 10:50:57 volumio go-librespot[4866]: time="2025-05-29T10:50:57+03:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 29 10:50:57 volumio go-librespot[4866]: time="2025-05-29T10:50:57+03:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 29 10:50:57 volumio go-librespot[4866]: time="2025-05-29T10:50:57+03:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 29 10:50:57 volumio go-librespot[4866]: time="2025-05-29T10:50:57+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 29 10:50:57 volumio go-librespot[4866]: time="2025-05-29T10:50:57+03:00" level=debug msg="received connection id: NjZhYTgxODMtZTdlNy00NjFlLWFmOTktZGIxN2M4Zjc0ZGNlK2RlYWxlcit0Y3A6Ly8wYWNhNDBjZi5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArODg1QzI4MzUxNzVFMEVDQ0UwMjIzRDIwRjVEMkI5OUQ2OTJGRDUxNDZFOEYxQ0EzNEZGNjkwNDk3RjE2Q0I1MQ==" May 29 10:50:57 volumio go-librespot[4866]: time="2025-05-29T10:50:57+03:00" level=trace msg="received accesspoint pong ack" May 29 10:50:57 volumio go-librespot[4866]: time="2025-05-29T10:50:57+03:00" level=debug msg="put connect state because NEW_DEVICE" May 29 10:50:59 volumio volumio[1151]: info: Initializing connection to go-librespot Websocket May 29 10:50:59 volumio go-librespot[4866]: time="2025-05-29T10:50:59+03:00" level=debug msg="new websocket client" May 29 10:50:59 volumio volumio[1151]: info: Connection to go-librespot Websocket established May 29 10:51:00 volumio go-librespot[4866]: time="2025-05-29T10:51:00+03:00" level=debug msg="handling transfer player command from af881a349bd158521b44e98ad5270227089ffecc" May 29 10:51:00 volumio go-librespot[4866]: time="2025-05-29T10:51:00+03:00" level=debug msg="resolved context of track" uri="spotify:track:4PMQ591DnjhGRYZNLETsno" May 29 10:51:00 volumio go-librespot[4866]: time="2025-05-29T10:51:00+03:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:4PMQ591DnjhGRYZNLETsno" May 29 10:51:00 volumio go-librespot[4866]: time="2025-05-29T10:51:00+03:00" level=debug msg="loading track (paused: false, position: 7401ms)" uri="spotify:track:4PMQ591DnjhGRYZNLETsno" May 29 10:51:00 volumio go-librespot[4866]: time="2025-05-29T10:51:00+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 29 10:51:00 volumio go-librespot[4866]: time="2025-05-29T10:51:00+03:00" level=trace msg="emitting websocket event: will_play" May 29 10:51:00 volumio volumio[1151]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4PMQ591DnjhGRYZNLETsno","play_origin":"go-librespot"}} May 29 10:51:00 volumio go-librespot[4866]: time="2025-05-29T10:51:00+03:00" level=debug msg="selected format OGG_VORBIS_320 (ebbf782d4f8d6119e19e33fc2effc659da58175a)" uri="spotify:track:4PMQ591DnjhGRYZNLETsno" May 29 10:51:00 volumio go-librespot[4866]: time="2025-05-29T10:51:00+03:00" level=debug msg="requested aes key for file ebbf782d4f8d6119e19e33fc2effc659da58175a, gid: 4PMQ591DnjhGRYZNLETsno" May 29 10:51:00 volumio volumio[1151]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh May 29 10:51:00 volumio volumio[1151]: Traceback (most recent call last): May 29 10:51:00 volumio volumio[1151]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in May 29 10:51:00 volumio volumio[1151]: from volumio_albumart import AlbumartAnimator May 29 10:51:00 volumio volumio[1151]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in May 29 10:51:00 volumio volumio[1151]: import socketio May 29 10:51:00 volumio volumio[1151]: ModuleNotFoundError: No module named 'socketio' May 29 10:51:01 volumio go-librespot[4866]: time="2025-05-29T10:51:01+03:00" level=debug msg="fetched first chunk of 17, total size is 8517550 bytes" uri="spotify:track:4PMQ591DnjhGRYZNLETsno" May 29 10:51:01 volumio go-librespot[4866]: time="2025-05-29T10:51:01+03:00" level=trace msg="seek to 7401ms (diff: 115ms, samples: 326384, bytes: 298696)" uri="spotify:track:4PMQ591DnjhGRYZNLETsno" May 29 10:51:01 volumio go-librespot[4866]: time="2025-05-29T10:51:01+03:00" level=debug msg="created new output device" May 29 10:51:01 volumio go-librespot[4866]: time="2025-05-29T10:51:01+03:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" May 29 10:51:01 volumio go-librespot[4866]: time="2025-05-29T10:51:01+03:00" level=info msg="loaded track \"Пінаколада\" (paused: false, position: 7401ms, duration: 195784ms, prefetched: false)" uri="spotify:track:4PMQ591DnjhGRYZNLETsno" May 29 10:51:01 volumio go-librespot[4866]: go-librespot: pcm_meter.c:1220: snd_pcm_scope_s16_get_channel_buffer: Assertion `s16->buf_areas' failed. May 29 10:51:01 volumio go-librespot[4865]: Aborted May 29 10:51:01 volumio volumio[1151]: info: Connection to go-librespot Websocket closed May 29 10:51:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a May 29 10:51:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 29 10:51:02 volumio volumio[1151]: info: Getting Spotify volume May 29 10:51:02 volumio volumio[1151]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 10:51:02 volumio volumio[1151]: Error: connect ECONNREFUSED 127.0.0.1:9879 May 29 10:51:02 volumio volumio[1151]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { May 29 10:51:02 volumio volumio[1151]: errno: -111, May 29 10:51:02 volumio volumio[1151]: code: 'ECONNREFUSED', May 29 10:51:02 volumio volumio[1151]: syscall: 'connect', May 29 10:51:02 volumio volumio[1151]: address: '127.0.0.1', May 29 10:51:02 volumio volumio[1151]: port: 9879, May 29 10:51:02 volumio volumio[1151]: response: undefined May 29 10:51:02 volumio volumio[1151]: } May 29 10:51:02 volumio volumio[1151]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 10:51:02 volumio sudo[4906]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-29 10:50' May 29 10:51:02 volumio sudo[4906]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="fd567ba9bc0dc34c0403e2d5b72a0f46467d1983" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri May 23 07:33:33 UTC 2025" VOLUMIO_VERSION="0.069" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="d67a2da4ffe3f001e986b1f31eaa3a76"