Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: SPOTIFY: User informations: {"country":"DE","display_name":"Volumio Kinderzimmer","email":"dominik.meyer@outlook.com","explicit_content":{"filter_enabled":true,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31eoyht2wvupu66blolgprllyv4a"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31eoyht2wvupu66blolgprllyv4a","id":"31eoyht2wvupu66blolgprllyv4a","images":[],"product":"premium","type":"user","uri":"spotify:user:31eoyht2wvupu66blolgprllyv4a"} Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: Spotify Successfully logged in Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: [1765808940082] CoreMusicLibrary::Adding element Spotify Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: Cannot find translation for source Spotify Dec 15 15:29:00 volumio-kinderzimmer sudo[3224]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: Listing playlists Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: Listing playlists Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CoreCommandRouter::volumioGetVisibleSources Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: Received Get System Info Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: Discovery: Getting this device information Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:00 volumio-kinderzimmer volumio[3177]: info: Listing playlists Dec 15 15:29:01 volumio-kinderzimmer volumio[3177]: info: go-librespot daemon successfully initialized Dec 15 15:29:02 volumio-kinderzimmer sudo[3441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 15 15:29:02 volumio-kinderzimmer sudo[3441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:02 volumio-kinderzimmer sudo[3443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 15 15:29:02 volumio-kinderzimmer sudo[3443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:02 volumio-kinderzimmer systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 15 15:29:02 volumio-kinderzimmer systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 15 15:29:02 volumio-kinderzimmer systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 15 15:29:02 volumio-kinderzimmer systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 15 15:29:02 volumio-kinderzimmer mpd_monitor.sh[3446]: MPD Monitor Service: Starting MPD Monitor Service Dec 15 15:29:02 volumio-kinderzimmer sudo[3441]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:02 volumio-kinderzimmer systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 15 15:29:02 volumio-kinderzimmer volumio[3177]: info: Successfully started MPD Monitor Dec 15 15:29:02 volumio-kinderzimmer systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 15 15:29:02 volumio-kinderzimmer systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 15 15:29:02 volumio-kinderzimmer systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 15 15:29:02 volumio-kinderzimmer sudo[3443]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:02 volumio-kinderzimmer mpd_monitor.sh[3449]: MPD Monitor Service: Starting MPD Monitor Service Dec 15 15:29:02 volumio-kinderzimmer volumio[3177]: info: Successfully started MPD Monitor Dec 15 15:29:03 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 15 15:29:03 volumio-kinderzimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:03 volumio-kinderzimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:03 volumio-kinderzimmer go-librespot[3453]: go-librespot daemon starting... Dec 15 15:29:03 volumio-kinderzimmer go-librespot[3454]: time="2025-12-15T15:29:03+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:29:03 volumio-kinderzimmer go-librespot[3454]: time="2025-12-15T15:29:03+01:00" level=debug msg="app state loaded" Dec 15 15:29:03 volumio-kinderzimmer go-librespot[3454]: time="2025-12-15T15:29:03+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:29:03 volumio-kinderzimmer go-librespot[3454]: time="2025-12-15T15:29:03+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 15:29:03 volumio-kinderzimmer go-librespot[3454]: time="2025-12-15T15:29:03+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 15:29:03 volumio-kinderzimmer go-librespot[3454]: time="2025-12-15T15:29:03+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 15:29:03 volumio-kinderzimmer go-librespot[3454]: time="2025-12-15T15:29:03+01:00" level=info msg="zeroconf server listening on port 45275" Dec 15 15:29:03 volumio-kinderzimmer go-librespot[3454]: time="2025-12-15T15:29:03+01:00" level=debug msg="obtained new client token: AADOq8XT3MOCnZdQvVasETNw4x8ekGo37simbfzdvmBjeY1c+Wo+MKxrT+1AB6g/B1h2bqj5ERIq/vjj1UMlbLmVnTv9z4Gb1o88nRUXhh+7lyis0pJMDcqnvh2KZB0o//In+gRuRyV2FGrdaGBOcXlM2zRT7KPnEpEqM1h+KXepmNlcWFBVweUHGnC6puV6xOJo5Rfw/7DEvf5w+Dhd0AeCTbYwrBKl+1skwUX+p5XLbahKmkAIh03aLvI=" Dec 15 15:29:03 volumio-kinderzimmer go-librespot[3454]: time="2025-12-15T15:29:03+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:29:03 volumio-kinderzimmer go-librespot[3454]: time="2025-12-15T15:29:03+01:00" level=debug msg="completed keyexchange" Dec 15 15:29:03 volumio-kinderzimmer go-librespot[3454]: time="2025-12-15T15:29:03+01:00" level=debug msg="completed challenge" Dec 15 15:29:03 volumio-kinderzimmer go-librespot[3454]: time="2025-12-15T15:29:03+01:00" level=info msg="authenticated AP" username="31************************4a" Dec 15 15:29:04 volumio-kinderzimmer volumio[3177]: info: Initializing connection to go-librespot Websocket Dec 15 15:29:04 volumio-kinderzimmer sudo[3478]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 15:29:04 volumio-kinderzimmer sudo[3478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:04 volumio-kinderzimmer go-librespot[3454]: time="2025-12-15T15:29:04+01:00" level=debug msg="new websocket client" Dec 15 15:29:04 volumio-kinderzimmer sudo[3480]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 15:29:04 volumio-kinderzimmer sudo[3480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:04 volumio-kinderzimmer sudo[3480]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:04 volumio-kinderzimmer sudo[3478]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:04 volumio-kinderzimmer volumio[3177]: info: Connection to go-librespot Websocket established Dec 15 15:29:04 volumio-kinderzimmer sudo[3486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 15 15:29:04 volumio-kinderzimmer sudo[3486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:04 volumio-kinderzimmer sudo[3486]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:04 volumio-kinderzimmer volumio[3177]: info: Upmpdcli Daemon Started Dec 15 15:29:06 volumio-kinderzimmer go-librespot[3454]: time="2025-12-15T15:29:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 15:29:06 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:29:06 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:29:06 volumio-kinderzimmer volumio[3177]: info: Connection to go-librespot Websocket closed Dec 15 15:29:07 volumio-kinderzimmer volumio[3177]: info: Getting Spotify volume Dec 15 15:29:07 volumio-kinderzimmer volumio[3177]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 15:29:07 volumio-kinderzimmer volumio[3177]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 15 15:29:07 volumio-kinderzimmer volumio[3177]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 15 15:29:07 volumio-kinderzimmer volumio[3177]: errno: -111, Dec 15 15:29:07 volumio-kinderzimmer volumio[3177]: code: 'ECONNREFUSED', Dec 15 15:29:07 volumio-kinderzimmer volumio[3177]: syscall: 'connect', Dec 15 15:29:07 volumio-kinderzimmer volumio[3177]: address: '127.0.0.1', Dec 15 15:29:07 volumio-kinderzimmer volumio[3177]: port: 9879, Dec 15 15:29:07 volumio-kinderzimmer volumio[3177]: response: undefined Dec 15 15:29:07 volumio-kinderzimmer volumio[3177]: } Dec 15 15:29:07 volumio-kinderzimmer volumio[3177]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 15:29:08 volumio-kinderzimmer sudo[3505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-15 15:28' Dec 15 15:29:08 volumio-kinderzimmer sudo[3505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:08 volumio-kinderzimmer sudo[3505]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:08 volumio-kinderzimmer volumio-remote-updater[972]: [2025-12-15 15:29:08] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 15 15:29:08 volumio-kinderzimmer volumio-remote-updater[972]: [2025-12-15 15:29:08] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 15 15:29:08 volumio-kinderzimmer systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:29:08 volumio-kinderzimmer systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 15 15:29:08 volumio-kinderzimmer systemd[1]: volumio.service: Consumed 14.905s CPU time. Dec 15 15:29:08 volumio-kinderzimmer systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 15 15:29:08 volumio-kinderzimmer systemd[1]: dynamicswap.service: Deactivated successfully. Dec 15 15:29:09 volumio-kinderzimmer systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Dec 15 15:29:09 volumio-kinderzimmer systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 15 15:29:09 volumio-kinderzimmer systemd[1]: Stopped volumio.service - Volumio Backend Module. Dec 15 15:29:09 volumio-kinderzimmer systemd[1]: volumio.service: Consumed 14.905s CPU time. Dec 15 15:29:09 volumio-kinderzimmer systemd[1]: Started volumio.service - Volumio Backend Module. Dec 15 15:29:09 volumio-kinderzimmer systemd[1]: dynamicswap.service: Deactivated successfully. Dec 15 15:29:09 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Dec 15 15:29:09 volumio-kinderzimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:09 volumio-kinderzimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:09 volumio-kinderzimmer go-librespot[3537]: go-librespot daemon starting... Dec 15 15:29:09 volumio-kinderzimmer go-librespot[3538]: time="2025-12-15T15:29:09+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:29:09 volumio-kinderzimmer go-librespot[3538]: time="2025-12-15T15:29:09+01:00" level=debug msg="app state loaded" Dec 15 15:29:09 volumio-kinderzimmer go-librespot[3538]: time="2025-12-15T15:29:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:29:09 volumio-kinderzimmer go-librespot[3538]: time="2025-12-15T15:29:09+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 15 15:29:09 volumio-kinderzimmer go-librespot[3538]: time="2025-12-15T15:29:09+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 15 15:29:09 volumio-kinderzimmer go-librespot[3538]: time="2025-12-15T15:29:09+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 15 15:29:09 volumio-kinderzimmer go-librespot[3538]: time="2025-12-15T15:29:09+01:00" level=info msg="zeroconf server listening on port 41391" Dec 15 15:29:09 volumio-kinderzimmer go-librespot[3538]: time="2025-12-15T15:29:09+01:00" level=debug msg="obtained new client token: AADpfC34guJbI5rbSJgBF38qnV/2o6U+RsS2LgrQx7kwTf+5ZrYM7yiBh7PsMWv1scf0BCn7tBahGcqTrgdboTcyqFPdYG2E0PYxMP9QlsJljgV7/+Yc0PnNYOhxrchhiSKnGcY3FOfII7VXT60jueWfyuzdlWP3jhuZqnQp044RHmVYzIDfX1obBzKpw5LQSdbqrplZHPjATuGsCuNvWfnRyWwiTF0aYTagfr8hrXRUi+nD+2Xx+BOfcPw=" Dec 15 15:29:10 volumio-kinderzimmer go-librespot[3538]: time="2025-12-15T15:29:10+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:29:10 volumio-kinderzimmer go-librespot[3538]: time="2025-12-15T15:29:10+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 10.10.30.152:48990->34.158.1.133:4070: read: connection reset by peer" Dec 15 15:29:10 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:29:10 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: ------------------------------------------- Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: ----- Volumio3 ---- Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: ------------------------------------------- Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: ----- System startup ---- Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: ------------------------------------------- Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: MYVOLUMIO Environment detected Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: Plugin folders cleanup Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: Scanning into folder /volumio/app/plugins/ Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: Scanning category audio_interface Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: Scanning category miscellanea Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: Scanning category music_service Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: Scanning category plugins.json Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: Scanning category system_controller Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: Scanning category user_interface Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: Scanning into folder /data/plugins/ Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: Scanning category music_service Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: Plugin folders cleanup completed Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: ------------------------------------------- Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: ----- Core plugins startup ---- Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: ------------------------------------------- Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: Loading plugins from folder /volumio/app/plugins/ Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: Adding plugin upnp to MyMusic Plugins Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: Loading plugins from folder /data/plugins/ Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: Loading plugin "system"... Dec 15 15:29:10 volumio-kinderzimmer volumio[3521]: info: Loading plugin "appearance"... Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: Loading plugin "network"... Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: Refreshing Cached IP Addresses Dec 15 15:29:11 volumio-kinderzimmer sudo[3561]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 15:29:11 volumio-kinderzimmer sudo[3561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: Loading plugin "services"... Dec 15 15:29:11 volumio-kinderzimmer sudo[3563]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 15:29:11 volumio-kinderzimmer sudo[3563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: Loading plugin "alsa_controller"... Dec 15 15:29:11 volumio-kinderzimmer sudo[3563]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:11 volumio-kinderzimmer sudo[3571]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 15 15:29:11 volumio-kinderzimmer sudo[3561]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:11 volumio-kinderzimmer sudo[3571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: Loading plugin "wizard"... Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: Loading plugin "networkfs"... Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: Starting Udev Watcher for removable devices Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: Ignoring mount for partition: boot Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: Ignoring mount for partition: volumio Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: Ignoring mount for partition: volumio_data Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: Loading plugin "volumio_command_line_client"... Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: Loading plugin "upnp"... Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: [1765808951615] Starting Upmpd Daemon Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: Loading plugin "my_music"... Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: Loading plugin "mpd"... Dec 15 15:29:11 volumio-kinderzimmer volumio[3521]: info: Loading plugin "upnp_browser"... Dec 15 15:29:12 volumio-kinderzimmer volumio[3521]: info: Loading plugin "alarm-clock"... Dec 15 15:29:12 volumio-kinderzimmer volumio[3521]: info: Loading plugin "airplay_emulation"... Dec 15 15:29:12 volumio-kinderzimmer volumio[3521]: info: Starting Shairport Sync Dec 15 15:29:12 volumio-kinderzimmer volumio[3521]: info: Loading plugin "last_100"... Dec 15 15:29:12 volumio-kinderzimmer volumio[3521]: info: Loading plugin "webradio"... Dec 15 15:29:12 volumio-kinderzimmer volumio[3521]: info: Loading plugin "i2s_dacs"... Dec 15 15:29:12 volumio-kinderzimmer volumio[3521]: info: I2S DAC not set, start Auto-detection Dec 15 15:29:12 volumio-kinderzimmer volumio[3521]: info: Loading plugin "volumiodiscovery"... Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: *** WARNING *** For more information see Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:29:13 volumio-kinderzimmer node[3521]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: *** WARNING *** For more information see Dec 15 15:29:13 volumio-kinderzimmer node[3521]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:29:13 volumio-kinderzimmer node[3521]: *** WARNING *** For more information see Dec 15 15:29:13 volumio-kinderzimmer node[3521]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 15 15:29:13 volumio-kinderzimmer node[3521]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:29:13 volumio-kinderzimmer node[3521]: *** WARNING *** For more information see Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: info: Discovery: Started advertising with name: Volumio-Kinderzimmer Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: info: Loading plugin "spop"... Dec 15 15:29:13 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Dec 15 15:29:13 volumio-kinderzimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:13 volumio-kinderzimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:13 volumio-kinderzimmer go-librespot[3599]: go-librespot daemon starting... Dec 15 15:29:13 volumio-kinderzimmer go-librespot[3600]: time="2025-12-15T15:29:13+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:29:13 volumio-kinderzimmer go-librespot[3600]: time="2025-12-15T15:29:13+01:00" level=debug msg="app state loaded" Dec 15 15:29:13 volumio-kinderzimmer go-librespot[3600]: time="2025-12-15T15:29:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:29:13 volumio-kinderzimmer volumio-remote-updater[972]: [2025-12-15 15:29:13] [connect] Successful connection Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: info: Loading plugin "outputs"... Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: info: Loading plugin "albumart"... Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: info: Plugin example_plugin is not enabled Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: info: Loading plugin "inputs"... Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: info: Loading plugin "updater_comm"... Dec 15 15:29:13 volumio-kinderzimmer go-librespot[3600]: time="2025-12-15T15:29:13+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 15 15:29:13 volumio-kinderzimmer go-librespot[3600]: time="2025-12-15T15:29:13+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 15 15:29:13 volumio-kinderzimmer go-librespot[3600]: time="2025-12-15T15:29:13+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 15 15:29:13 volumio-kinderzimmer go-librespot[3600]: time="2025-12-15T15:29:13+01:00" level=info msg="zeroconf server listening on port 36075" Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: info: Plugin mpdemulation is not enabled Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: info: Loading plugin "rest_api"... Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: info: Loading plugin "websocket"... Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: info: Starting Socket.io Server version 1.7.4 Dec 15 15:29:13 volumio-kinderzimmer volumio[3609]: Forking 3 albumart workers Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: info: Loading i18n strings for locale de Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: Updating browse sources language Dec 15 15:29:13 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::initPlayerControls Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: Express server listening on port 3000 Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: [Metrics] WebUI: 4s 285.59ms Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: Setting Device type: x86 Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreStateMachine::resetVolumioState Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreStateMachine::getcurrentVolume Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioRetrievevolume Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: Completed loading Core Plugins Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: Preparing to generate the ALSA configuration file Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: Reading ALSA contributions from plugins. Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: Volumio Network Manager: Network status updated: 2 Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 15 15:29:14 volumio-kinderzimmer volumio[3621]: Starting albumart workers Dec 15 15:29:14 volumio-kinderzimmer volumio[3619]: Starting albumart workers Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: verbose: New Socket.io Connection to 10.10.30.152:3000 from 10.10.30.151 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: VolumeController:: Volume=12 Mute =false Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreStateMachine::pushState Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioPushState Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreStateMachine::updateTrackBlock Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CorePlayQueue::getTrackBlock Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioRetrievevolume Dec 15 15:29:14 volumio-kinderzimmer volumio[3620]: Starting albumart workers Dec 15 15:29:14 volumio-kinderzimmer go-librespot[3600]: time="2025-12-15T15:29:14+01:00" level=debug msg="obtained new client token: AADx/neLXbGT5ZC7KMEOcw5m5UofJOPGMPhRbZ8h/bvuD7ziRrmKFJletWth3sBfRvgpm1EeEBRViAVC7hO8J+3HHnjw7XEVOkglFXs6aVoQK+7eoyOREmlXfl9bkQlmG2qKDu8yMb3R6/VGwNFEfjYnvjNh+EYd642w3k+vvYCu/vnt1Z092Zb+3A70q6UQU6nGDuVEvrTC6yk/zVNyRUmUkpWfBRUcKNzG9JH86S5ikyDFJ+i5y67w90s=" Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: Reloading queue from file Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreStateMachine::setRepeat null single undefined Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreStateMachine::pushState Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioPushState Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreStateMachine::setRandom null Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreStateMachine::pushState Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioPushState Dec 15 15:29:14 volumio-kinderzimmer volumio-remote-updater[972]: [2025-12-15 15:29:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765808953 101 Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: 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: 3 Dec 15 15:29:14 volumio-kinderzimmer go-librespot[3600]: time="2025-12-15T15:29:14+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: verbose: New Socket.io Connection to 10.10.30.152:3000 from 10.10.30.151 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: VolumeController:: Volume=12 Mute =false Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreStateMachine::pushState Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioPushState Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: Discovery: adding c64eab90-9ec5-4a46-9b89-d432040ab5b1 Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: Discovery: Found device Volumio-Wohnzimmer Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: Discovery: Connecting to remote: 10.10.30.151 Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: Asound.conf file unchanged, so no further update is needed Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: Output device has changed, restarting MPD Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: Output device has changed, restarting Shairport Sync Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:14 volumio-kinderzimmer sudo[3665]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 15 15:29:14 volumio-kinderzimmer sudo[3665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:14 volumio-kinderzimmer sudo[3669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 15 15:29:14 volumio-kinderzimmer sudo[3669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:14 volumio-kinderzimmer sudo[3665]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 15:29:14 volumio-kinderzimmer systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: ___________ START PLUGINS ___________ Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: ControllerMpd::onStart: Initializing MPD Dec 15 15:29:14 volumio-kinderzimmer volumio[3521]: info: Creating MPD Configuration file Dec 15 15:29:14 volumio-kinderzimmer systemd[1]: mpd.service: Deactivated successfully. Dec 15 15:29:14 volumio-kinderzimmer systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 15 15:29:14 volumio-kinderzimmer systemd[1]: mpd.service: Consumed 1.657s CPU time. Dec 15 15:29:14 volumio-kinderzimmer systemd[1]: mpd.socket: Deactivated successfully. Dec 15 15:29:14 volumio-kinderzimmer systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 15 15:29:14 volumio-kinderzimmer systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 15 15:29:14 volumio-kinderzimmer go-librespot[3600]: time="2025-12-15T15:29:14+01:00" level=debug msg="completed keyexchange" Dec 15 15:29:14 volumio-kinderzimmer go-librespot[3600]: time="2025-12-15T15:29:14+01:00" level=debug msg="completed challenge" Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: [1765808955012] CoreMusicLibrary::Adding element Medienserver Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:15 volumio-kinderzimmer systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 15 15:29:15 volumio-kinderzimmer sudo[3680]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 15 15:29:15 volumio-kinderzimmer sudo[3680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:15 volumio-kinderzimmer systemd[1]: Starting mpd.service - Music Player Daemon... Dec 15 15:29:15 volumio-kinderzimmer sudo[3680]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:15 volumio-kinderzimmer sudo[3683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 15 15:29:15 volumio-kinderzimmer sudo[3683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer systemd[1]: mpd.service: Deactivated successfully. Dec 15 15:29:15 volumio-kinderzimmer systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 15 15:29:15 volumio-kinderzimmer systemd[1]: mpd.socket: Deactivated successfully. Dec 15 15:29:15 volumio-kinderzimmer systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 15:29:15 volumio-kinderzimmer systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 15:29:15 volumio-kinderzimmer systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:29:15 volumio-kinderzimmer systemd[1]: Starting mpd.service - Music Player Daemon... Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: [1765808955132] CoreMusicLibrary::Adding element Last_100 Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: [1765808955141] CoreMusicLibrary::Adding element Webradio Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: Initializing BBC Radios Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer go-librespot[3600]: time="2025-12-15T15:29:15+01:00" level=info msg="authenticated AP" username="31************************4a" Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: Creating Spotify config file Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer sudo[3709]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 15 15:29:15 volumio-kinderzimmer sudo[3709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 15 15:29:15 volumio-kinderzimmer sudo[3709]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: Volumio Calling Home Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: MPD Permissions set Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: MPD Permissions set Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: Spotify config file written Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer sudo[3731]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 15 15:29:15 volumio-kinderzimmer sudo[3731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 15 15:29:15 volumio-kinderzimmer systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 15 15:29:15 volumio-kinderzimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 15 15:29:15 volumio-kinderzimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:15 volumio-kinderzimmer go-librespot[3741]: go-librespot daemon starting... Dec 15 15:29:15 volumio-kinderzimmer sudo[3731]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: No need to fix Spotify hosts Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: Discovery: adding 6698be19-c1b4-464f-8221-c1bbc80acc38 Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: Discovery: Found device Volumio-Kinderzimmer Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: Discovery: this is already registered, 6698be19-c1b4-464f-8221-c1bbc80acc38 Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: Discovery: Found device Volumio-Kinderzimmer Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:15 volumio-kinderzimmer go-librespot[3750]: time="2025-12-15T15:29:15+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:29:15 volumio-kinderzimmer go-librespot[3750]: time="2025-12-15T15:29:15+01:00" level=debug msg="app state loaded" Dec 15 15:29:15 volumio-kinderzimmer go-librespot[3750]: time="2025-12-15T15:29:15+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: Starting Shairport Sync Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: Starting Shairport Sync Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: Starting Shairport Sync Dec 15 15:29:15 volumio-kinderzimmer sudo[3759]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 15 15:29:15 volumio-kinderzimmer sudo[3759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:15 volumio-kinderzimmer sudo[3757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 15 15:29:15 volumio-kinderzimmer sudo[3757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:15 volumio-kinderzimmer sudo[3761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 15 15:29:15 volumio-kinderzimmer sudo[3761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 15 15:29:15 volumio-kinderzimmer systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 15 15:29:15 volumio-kinderzimmer systemd[1]: shairport-sync.service: Deactivated successfully. Dec 15 15:29:15 volumio-kinderzimmer systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 15 15:29:15 volumio-kinderzimmer systemd[1]: shairport-sync.service: Consumed 1.756s CPU time. Dec 15 15:29:15 volumio-kinderzimmer systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 15 15:29:15 volumio-kinderzimmer sudo[3757]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:15 volumio-kinderzimmer sudo[3761]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: Shairport-Sync Started Dec 15 15:29:15 volumio-kinderzimmer sudo[3759]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: Error adding Membership: Error: addMembership EINVAL Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: Shairport-Sync Started Dec 15 15:29:15 volumio-kinderzimmer volumio[3521]: info: Shairport-Sync Started Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: Discovery: Connected to remote: 10.10.30.151 Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: Volumio called home Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: SPOTIFY: BQA0Mw_RzPvrdF-sTspw1K8fRSaoXoPHdtHv2QExx5fZkBXAd5L65UmeS-f931Aj2WQSA5PUs1RWQ7jCzCJxR_p7VIsW6wU5rDWnSMAIMoSWYZLuqpnsh0DQ07LfWalEls-H5JDmUGI6uexHE7t3IsZBr1CAfF5U0gFVr1gaaVJNHGOYQMnFu9WYfCuajlvT3r02gpIvocOXE6NomrC6_ml_JnONfs27c7jYMwLfJ1WvWdDYpC7D2l7E_1ylRKftWSaw1PPPyg7_xrs9H6wbE1TTrE3YsMC0R_9Go9hK_UBEl5MNDCxY6R2FTiSb Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: New Spotify access token = BQA0Mw_RzPvrdF-sTspw1K8fRSaoXoPHdtHv2QExx5fZkBXAd5L65UmeS-f931Aj2WQSA5PUs1RWQ7jCzCJxR_p7VIsW6wU5rDWnSMAIMoSWYZLuqpnsh0DQ07LfWalEls-H5JDmUGI6uexHE7t3IsZBr1CAfF5U0gFVr1gaaVJNHGOYQMnFu9WYfCuajlvT3r02gpIvocOXE6NomrC6_ml_JnONfs27c7jYMwLfJ1WvWdDYpC7D2l7E_1ylRKftWSaw1PPPyg7_xrs9H6wbE1TTrE3YsMC0R_9Go9hK_UBEl5MNDCxY6R2FTiSb Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 15 15:29:16 volumio-kinderzimmer go-librespot[3750]: time="2025-12-15T15:29:16+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 15 15:29:16 volumio-kinderzimmer go-librespot[3750]: time="2025-12-15T15:29:16+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 15 15:29:16 volumio-kinderzimmer go-librespot[3750]: time="2025-12-15T15:29:16+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 15 15:29:16 volumio-kinderzimmer go-librespot[3750]: time="2025-12-15T15:29:16+01:00" level=info msg="zeroconf server listening on port 40217" Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioGetVisibleSources Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: Received Get System Info Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: Discovery: Getting this device information Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: Listing playlists Dec 15 15:29:16 volumio-kinderzimmer mpd[3725]: 2025-12-15T15:29:16 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 15 15:29:16 volumio-kinderzimmer systemd[1]: Started mpd.service - Music Player Daemon. Dec 15 15:29:16 volumio-kinderzimmer sudo[3669]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:16 volumio-kinderzimmer sudo[3683]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: Completed starting Core Plugins Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: ------------------------------------------- Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: ----- MyVolumio plugins startup ---- Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: ------------------------------------------- Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: error: MPD error: The expression evaluated to a falsy value: Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: assert.ok(self.idling) Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: error: The expression evaluated to a falsy value: Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: assert.ok(self.idling) Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: info: MPD running with PID3725 Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: ,establishing connection Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: error: updateQueue error: null Dec 15 15:29:16 volumio-kinderzimmer volumio[3521]: error: updateQueue error: null Dec 15 15:29:17 volumio-kinderzimmer sudo[3571]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:17 volumio-kinderzimmer go-librespot[3750]: time="2025-12-15T15:29:17+01:00" level=debug msg="obtained new client token: AAAou+51qpQWvxbKVFLxQNgN4u7zCKnFvFZ7uiIoJQPLM870CE2XSDlIz3xaghuyJirRBzVP+WHneVw9Wm85sJPyu9AMjpiPmWV+3VoGhvm7TM3vFthZgJfnFYSliRR3UAKCC9a8tOu09Yyufx5bKx2g5n4vH8l/lQqeR7sWx5SeWWgAwYT2+7IQ7FODKt+KNnSyisZqYvSGI0MvUpgAbYjn1bDoBOORhEGDfBWwKohV8/fSDMuMzNxf" Dec 15 15:29:17 volumio-kinderzimmer volumio[3521]: SPOTIFY: User informations: {"country":"DE","display_name":"Volumio Kinderzimmer","email":"dominik.meyer@outlook.com","explicit_content":{"filter_enabled":true,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31eoyht2wvupu66blolgprllyv4a"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31eoyht2wvupu66blolgprllyv4a","id":"31eoyht2wvupu66blolgprllyv4a","images":[],"product":"premium","type":"user","uri":"spotify:user:31eoyht2wvupu66blolgprllyv4a"} Dec 15 15:29:17 volumio-kinderzimmer volumio[3521]: info: Spotify Successfully logged in Dec 15 15:29:17 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:29:17 volumio-kinderzimmer volumio[3521]: info: [1765808957135] CoreMusicLibrary::Adding element Spotify Dec 15 15:29:17 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:17 volumio-kinderzimmer volumio[3521]: Cannot find translation for source Spotify Dec 15 15:29:17 volumio-kinderzimmer go-librespot[3750]: time="2025-12-15T15:29:17+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:29:17 volumio-kinderzimmer go-librespot[3750]: time="2025-12-15T15:29:17+01:00" level=debug msg="completed keyexchange" Dec 15 15:29:17 volumio-kinderzimmer go-librespot[3750]: time="2025-12-15T15:29:17+01:00" level=debug msg="completed challenge" Dec 15 15:29:18 volumio-kinderzimmer go-librespot[3750]: time="2025-12-15T15:29:18+01:00" level=info msg="authenticated AP" username="31************************4a" Dec 15 15:29:18 volumio-kinderzimmer volumio[3521]: info: go-librespot daemon successfully initialized Dec 15 15:29:19 volumio-kinderzimmer sudo[3792]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 15 15:29:19 volumio-kinderzimmer sudo[3792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:19 volumio-kinderzimmer systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 15 15:29:19 volumio-kinderzimmer sudo[3795]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 15 15:29:19 volumio-kinderzimmer sudo[3795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:19 volumio-kinderzimmer systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 15 15:29:19 volumio-kinderzimmer systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 15 15:29:19 volumio-kinderzimmer systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 15 15:29:19 volumio-kinderzimmer sudo[3792]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:19 volumio-kinderzimmer mpd_monitor.sh[3797]: MPD Monitor Service: Starting MPD Monitor Service Dec 15 15:29:19 volumio-kinderzimmer volumio[3521]: info: Successfully started MPD Monitor Dec 15 15:29:19 volumio-kinderzimmer systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 15 15:29:19 volumio-kinderzimmer systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 15 15:29:19 volumio-kinderzimmer systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 15 15:29:19 volumio-kinderzimmer systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 15 15:29:19 volumio-kinderzimmer sudo[3795]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:19 volumio-kinderzimmer mpd_monitor.sh[3800]: MPD Monitor Service: Starting MPD Monitor Service Dec 15 15:29:19 volumio-kinderzimmer volumio[3521]: info: Successfully started MPD Monitor Dec 15 15:29:20 volumio-kinderzimmer volumio[3521]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:20 volumio-kinderzimmer volumio[3521]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:20 volumio-kinderzimmer volumio[3521]: info: Listing playlists Dec 15 15:29:20 volumio-kinderzimmer volumio[3521]: info: Listing playlists Dec 15 15:29:21 volumio-kinderzimmer sudo[3821]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 15:29:21 volumio-kinderzimmer sudo[3821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:21 volumio-kinderzimmer sudo[3823]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 15:29:21 volumio-kinderzimmer sudo[3823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:21 volumio-kinderzimmer sudo[3823]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:21 volumio-kinderzimmer sudo[3826]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 15 15:29:21 volumio-kinderzimmer sudo[3826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:21 volumio-kinderzimmer sudo[3821]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:21 volumio-kinderzimmer sudo[3826]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:21 volumio-kinderzimmer volumio[3521]: info: Upmpdcli Daemon Started Dec 15 15:29:21 volumio-kinderzimmer volumio[3521]: info: Initializing connection to go-librespot Websocket Dec 15 15:29:21 volumio-kinderzimmer go-librespot[3750]: time="2025-12-15T15:29:21+01:00" level=debug msg="new websocket client" Dec 15 15:29:21 volumio-kinderzimmer volumio[3521]: info: Connection to go-librespot Websocket established Dec 15 15:29:22 volumio-kinderzimmer go-librespot[3750]: time="2025-12-15T15:29:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 15:29:22 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:29:22 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:29:22 volumio-kinderzimmer volumio[3521]: info: Connection to go-librespot Websocket closed Dec 15 15:29:24 volumio-kinderzimmer volumio[3521]: info: Getting Spotify volume Dec 15 15:29:24 volumio-kinderzimmer volumio[3521]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 15:29:24 volumio-kinderzimmer volumio[3521]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 15 15:29:24 volumio-kinderzimmer volumio[3521]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 15 15:29:24 volumio-kinderzimmer volumio[3521]: errno: -111, Dec 15 15:29:24 volumio-kinderzimmer volumio[3521]: code: 'ECONNREFUSED', Dec 15 15:29:24 volumio-kinderzimmer volumio[3521]: syscall: 'connect', Dec 15 15:29:24 volumio-kinderzimmer volumio[3521]: address: '127.0.0.1', Dec 15 15:29:24 volumio-kinderzimmer volumio[3521]: port: 9879, Dec 15 15:29:24 volumio-kinderzimmer volumio[3521]: response: undefined Dec 15 15:29:24 volumio-kinderzimmer volumio[3521]: } Dec 15 15:29:24 volumio-kinderzimmer volumio[3521]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 15:29:25 volumio-kinderzimmer sudo[3848]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-15 15:28' Dec 15 15:29:25 volumio-kinderzimmer sudo[3848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:25 volumio-kinderzimmer sudo[3848]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:25 volumio-kinderzimmer volumio-remote-updater[972]: [2025-12-15 15:29:25] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 15 15:29:25 volumio-kinderzimmer volumio-remote-updater[972]: [2025-12-15 15:29:25] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 15 15:29:25 volumio-kinderzimmer systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:29:25 volumio-kinderzimmer systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 15 15:29:25 volumio-kinderzimmer systemd[1]: volumio.service: Consumed 14.881s CPU time. Dec 15 15:29:25 volumio-kinderzimmer systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 15 15:29:25 volumio-kinderzimmer systemd[1]: dynamicswap.service: Deactivated successfully. Dec 15 15:29:25 volumio-kinderzimmer systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Dec 15 15:29:25 volumio-kinderzimmer systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 15 15:29:25 volumio-kinderzimmer systemd[1]: Stopped volumio.service - Volumio Backend Module. Dec 15 15:29:25 volumio-kinderzimmer systemd[1]: volumio.service: Consumed 14.881s CPU time. Dec 15 15:29:25 volumio-kinderzimmer systemd[1]: Started volumio.service - Volumio Backend Module. Dec 15 15:29:25 volumio-kinderzimmer systemd[1]: dynamicswap.service: Deactivated successfully. Dec 15 15:29:26 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 15 15:29:26 volumio-kinderzimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:26 volumio-kinderzimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:26 volumio-kinderzimmer go-librespot[3876]: go-librespot daemon starting... Dec 15 15:29:26 volumio-kinderzimmer go-librespot[3877]: time="2025-12-15T15:29:26+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:29:26 volumio-kinderzimmer go-librespot[3877]: time="2025-12-15T15:29:26+01:00" level=debug msg="app state loaded" Dec 15 15:29:26 volumio-kinderzimmer go-librespot[3877]: time="2025-12-15T15:29:26+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:29:26 volumio-kinderzimmer go-librespot[3877]: time="2025-12-15T15:29:26+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 15 15:29:26 volumio-kinderzimmer go-librespot[3877]: time="2025-12-15T15:29:26+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 15 15:29:26 volumio-kinderzimmer go-librespot[3877]: time="2025-12-15T15:29:26+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 15 15:29:26 volumio-kinderzimmer go-librespot[3877]: time="2025-12-15T15:29:26+01:00" level=info msg="zeroconf server listening on port 44929" Dec 15 15:29:26 volumio-kinderzimmer go-librespot[3877]: time="2025-12-15T15:29:26+01:00" level=debug msg="obtained new client token: AACEOMX1t63VTe0wTCO07V1Trw/PFloB7DSqn1Dgi0gkVJyXdobUSnIjA93jf8hIpXSyLnH70vYrfHrLWpAY+IMpyuM+dr41Xjr/VwcT/DZyM1xJqhvMcN8IQBejekriS/Uos7Sj6vKMls2MpB1IpcPGRfgxykBczMVuRgwFlIhMqn/9sNenmsUtZZYDMiB8MTEw5r63jLqMX1JRqCk6jGecScXjtxcPLh9qVZQqNug6IXibwBzvgHK9k8Y=" Dec 15 15:29:26 volumio-kinderzimmer go-librespot[3877]: time="2025-12-15T15:29:26+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:29:26 volumio-kinderzimmer go-librespot[3877]: time="2025-12-15T15:29:26+01:00" level=debug msg="completed keyexchange" Dec 15 15:29:26 volumio-kinderzimmer go-librespot[3877]: time="2025-12-15T15:29:26+01:00" level=debug msg="completed challenge" Dec 15 15:29:26 volumio-kinderzimmer volumio[3861]: info: ------------------------------------------- Dec 15 15:29:26 volumio-kinderzimmer volumio[3861]: info: ----- Volumio3 ---- Dec 15 15:29:26 volumio-kinderzimmer volumio[3861]: info: ------------------------------------------- Dec 15 15:29:26 volumio-kinderzimmer volumio[3861]: info: ----- System startup ---- Dec 15 15:29:26 volumio-kinderzimmer volumio[3861]: info: ------------------------------------------- Dec 15 15:29:26 volumio-kinderzimmer go-librespot[3877]: time="2025-12-15T15:29:26+01:00" level=info msg="authenticated AP" username="31************************4a" Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: MYVOLUMIO Environment detected Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Plugin folders cleanup Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Scanning into folder /volumio/app/plugins/ Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Scanning category audio_interface Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Scanning category miscellanea Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Scanning category music_service Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Scanning category plugins.json Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Scanning category system_controller Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Scanning category user_interface Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Scanning into folder /data/plugins/ Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Scanning category music_service Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Plugin folders cleanup completed Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: ------------------------------------------- Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: ----- Core plugins startup ---- Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: ------------------------------------------- Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Loading plugins from folder /volumio/app/plugins/ Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Adding plugin upnp to MyMusic Plugins Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Loading plugins from folder /data/plugins/ Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Loading plugin "system"... Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Loading plugin "appearance"... Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Loading plugin "network"... Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Refreshing Cached IP Addresses Dec 15 15:29:27 volumio-kinderzimmer sudo[3899]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 15:29:27 volumio-kinderzimmer sudo[3899]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:27 volumio-kinderzimmer sudo[3901]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Loading plugin "services"... Dec 15 15:29:27 volumio-kinderzimmer sudo[3901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:27 volumio-kinderzimmer volumio[3861]: info: Loading plugin "alsa_controller"... Dec 15 15:29:27 volumio-kinderzimmer sudo[3899]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:27 volumio-kinderzimmer sudo[3901]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:27 volumio-kinderzimmer sudo[3910]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 15 15:29:27 volumio-kinderzimmer sudo[3910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:28 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 15:29:28 volumio-kinderzimmer volumio[3861]: info: Loading plugin "wizard"... Dec 15 15:29:28 volumio-kinderzimmer volumio[3861]: info: Loading plugin "networkfs"... Dec 15 15:29:28 volumio-kinderzimmer volumio[3861]: info: Starting Udev Watcher for removable devices Dec 15 15:29:28 volumio-kinderzimmer volumio[3861]: info: Ignoring mount for partition: boot Dec 15 15:29:28 volumio-kinderzimmer volumio[3861]: info: Ignoring mount for partition: volumio Dec 15 15:29:28 volumio-kinderzimmer volumio[3861]: info: Ignoring mount for partition: volumio_data Dec 15 15:29:28 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 15 15:29:28 volumio-kinderzimmer volumio[3861]: info: Loading plugin "volumio_command_line_client"... Dec 15 15:29:28 volumio-kinderzimmer volumio[3861]: info: Loading plugin "upnp"... Dec 15 15:29:28 volumio-kinderzimmer volumio[3861]: info: [1765808968098] Starting Upmpd Daemon Dec 15 15:29:28 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 15 15:29:28 volumio-kinderzimmer volumio[3861]: info: Loading plugin "my_music"... Dec 15 15:29:28 volumio-kinderzimmer volumio[3861]: info: Loading plugin "mpd"... Dec 15 15:29:28 volumio-kinderzimmer volumio[3861]: info: Loading plugin "upnp_browser"... Dec 15 15:29:29 volumio-kinderzimmer volumio[3861]: info: Loading plugin "alarm-clock"... Dec 15 15:29:29 volumio-kinderzimmer volumio[3861]: info: Loading plugin "airplay_emulation"... Dec 15 15:29:29 volumio-kinderzimmer volumio[3861]: info: Starting Shairport Sync Dec 15 15:29:29 volumio-kinderzimmer volumio[3861]: info: Loading plugin "last_100"... Dec 15 15:29:29 volumio-kinderzimmer volumio[3861]: info: Loading plugin "webradio"... Dec 15 15:29:29 volumio-kinderzimmer volumio[3861]: info: Loading plugin "i2s_dacs"... Dec 15 15:29:29 volumio-kinderzimmer volumio[3861]: info: I2S DAC not set, start Auto-detection Dec 15 15:29:29 volumio-kinderzimmer volumio[3861]: info: Loading plugin "volumiodiscovery"... Dec 15 15:29:29 volumio-kinderzimmer volumio[3861]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 15 15:29:29 volumio-kinderzimmer volumio[3861]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:29:29 volumio-kinderzimmer volumio[3861]: *** WARNING *** For more information see Dec 15 15:29:29 volumio-kinderzimmer volumio[3861]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 15 15:29:29 volumio-kinderzimmer volumio[3861]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:29:29 volumio-kinderzimmer volumio[3861]: *** WARNING *** For more information see Dec 15 15:29:29 volumio-kinderzimmer node[3861]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 15 15:29:29 volumio-kinderzimmer node[3861]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:29:29 volumio-kinderzimmer node[3861]: *** WARNING *** For more information see Dec 15 15:29:29 volumio-kinderzimmer node[3861]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 15 15:29:29 volumio-kinderzimmer node[3861]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:29:29 volumio-kinderzimmer volumio[3861]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 15 15:29:29 volumio-kinderzimmer node[3861]: *** WARNING *** For more information see Dec 15 15:29:29 volumio-kinderzimmer volumio[3861]: info: Discovery: Started advertising with name: Volumio-Kinderzimmer Dec 15 15:29:29 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 15 15:29:29 volumio-kinderzimmer volumio[3861]: info: Loading plugin "spop"... Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: Loading plugin "outputs"... Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: Loading plugin "albumart"... Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: Plugin example_plugin is not enabled Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: Loading plugin "inputs"... Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: Loading plugin "updater_comm"... Dec 15 15:29:30 volumio-kinderzimmer volumio-remote-updater[972]: [2025-12-15 15:29:30] [connect] Successful connection Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: Plugin mpdemulation is not enabled Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: Loading plugin "rest_api"... Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: Loading plugin "websocket"... Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: Starting Socket.io Server version 1.7.4 Dec 15 15:29:30 volumio-kinderzimmer volumio[3938]: Forking 3 albumart workers Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: Loading i18n strings for locale de Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: Updating browse sources language Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::initPlayerControls Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: Express server listening on port 3000 Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: [Metrics] WebUI: 4s 275.35ms Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: Setting Device type: x86 Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreStateMachine::resetVolumioState Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreStateMachine::getcurrentVolume Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioRetrievevolume Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: Completed loading Core Plugins Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: Preparing to generate the ALSA configuration file Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: Reading ALSA contributions from plugins. Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: Volumio Network Manager: Network status updated: 2 Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: VolumeController:: Volume=12 Mute =false Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreStateMachine::pushState Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioPushState Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreStateMachine::updateTrackBlock Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrackBlock Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioRetrievevolume Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: Reloading queue from file Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreStateMachine::setRepeat null single undefined Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreStateMachine::pushState Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioPushState Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreStateMachine::setRandom null Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreStateMachine::pushState Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:30 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioPushState Dec 15 15:29:30 volumio-kinderzimmer volumio[3948]: Starting albumart workers Dec 15 15:29:31 volumio-kinderzimmer volumio-remote-updater[972]: [2025-12-15 15:29:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765808970 101 Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: 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: 3 Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: verbose: New Socket.io Connection to 10.10.30.152:3000 from 10.10.30.151 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: Discovery: adding c64eab90-9ec5-4a46-9b89-d432040ab5b1 Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: Discovery: Found device Volumio-Wohnzimmer Dec 15 15:29:31 volumio-kinderzimmer volumio[3949]: Starting albumart workers Dec 15 15:29:31 volumio-kinderzimmer volumio[3950]: Starting albumart workers Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: Discovery: Connecting to remote: 10.10.30.151 Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: VolumeController:: Volume=12 Mute =false Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreStateMachine::pushState Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioPushState Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: Asound.conf file unchanged, so no further update is needed Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: Output device has changed, restarting MPD Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: Output device has changed, restarting Shairport Sync Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:31 volumio-kinderzimmer sudo[3993]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 15 15:29:31 volumio-kinderzimmer sudo[3993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:31 volumio-kinderzimmer sudo[3993]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:31 volumio-kinderzimmer sudo[3995]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 15 15:29:31 volumio-kinderzimmer sudo[3995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: ___________ START PLUGINS ___________ Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: ControllerMpd::onStart: Initializing MPD Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: Creating MPD Configuration file Dec 15 15:29:31 volumio-kinderzimmer systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: [1765808971287] CoreMusicLibrary::Adding element Medienserver Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:31 volumio-kinderzimmer sudo[4003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 15 15:29:31 volumio-kinderzimmer systemd[1]: mpd.service: Deactivated successfully. Dec 15 15:29:31 volumio-kinderzimmer systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 15 15:29:31 volumio-kinderzimmer systemd[1]: mpd.service: Consumed 1.621s CPU time. Dec 15 15:29:31 volumio-kinderzimmer systemd[1]: mpd.socket: Deactivated successfully. Dec 15 15:29:31 volumio-kinderzimmer systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 15 15:29:31 volumio-kinderzimmer systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 15 15:29:31 volumio-kinderzimmer sudo[4003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:31 volumio-kinderzimmer sudo[4005]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 15 15:29:31 volumio-kinderzimmer sudo[4005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:31 volumio-kinderzimmer sudo[4003]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:31 volumio-kinderzimmer systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 15 15:29:31 volumio-kinderzimmer systemd[1]: Starting mpd.service - Music Player Daemon... Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:31 volumio-kinderzimmer systemd[1]: mpd.service: Deactivated successfully. Dec 15 15:29:31 volumio-kinderzimmer systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 15 15:29:31 volumio-kinderzimmer systemd[1]: mpd.socket: Deactivated successfully. Dec 15 15:29:31 volumio-kinderzimmer systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 15 15:29:31 volumio-kinderzimmer systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:31 volumio-kinderzimmer systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 15 15:29:31 volumio-kinderzimmer systemd[1]: Starting mpd.service - Music Player Daemon... Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: [1765808971480] CoreMusicLibrary::Adding element Last_100 Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: [1765808971491] CoreMusicLibrary::Adding element Webradio Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: Initializing BBC Radios Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:31 volumio-kinderzimmer sudo[4017]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 15 15:29:31 volumio-kinderzimmer sudo[4017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 15 15:29:31 volumio-kinderzimmer sudo[4017]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: Creating Spotify config file Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: Volumio Calling Home Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: Discovery: adding 6698be19-c1b4-464f-8221-c1bbc80acc38 Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: Discovery: Found device Volumio-Kinderzimmer Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: Discovery: this is already registered, 6698be19-c1b4-464f-8221-c1bbc80acc38 Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: Discovery: Found device Volumio-Kinderzimmer Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:31 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: MPD Permissions set Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: MPD Permissions set Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: Spotify config file written Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer sudo[4058]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 15 15:29:32 volumio-kinderzimmer sudo[4058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Killing process 3880 (go-librespot) with signal SIGKILL. Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 15 15:29:32 volumio-kinderzimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 15 15:29:32 volumio-kinderzimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:32 volumio-kinderzimmer go-librespot[4073]: go-librespot daemon starting... Dec 15 15:29:32 volumio-kinderzimmer sudo[4058]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: No need to fix Spotify hosts Dec 15 15:29:32 volumio-kinderzimmer go-librespot[4076]: time="2025-12-15T15:29:32+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:29:32 volumio-kinderzimmer go-librespot[4076]: time="2025-12-15T15:29:32+01:00" level=debug msg="app state loaded" Dec 15 15:29:32 volumio-kinderzimmer go-librespot[4076]: time="2025-12-15T15:29:32+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: verbose: New Socket.io Connection to 10.10.30.152:3000 from 10.10.30.151 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: Volumio called home Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: Starting Shairport Sync Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: Starting Shairport Sync Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: Starting Shairport Sync Dec 15 15:29:32 volumio-kinderzimmer sudo[4084]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 15 15:29:32 volumio-kinderzimmer sudo[4084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:32 volumio-kinderzimmer sudo[4086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 15 15:29:32 volumio-kinderzimmer sudo[4086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:32 volumio-kinderzimmer sudo[4088]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 15 15:29:32 volumio-kinderzimmer sudo[4088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:32 volumio-kinderzimmer systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 15 15:29:32 volumio-kinderzimmer systemd[1]: shairport-sync.service: Deactivated successfully. Dec 15 15:29:32 volumio-kinderzimmer systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 15 15:29:32 volumio-kinderzimmer systemd[1]: shairport-sync.service: Consumed 1.737s CPU time. Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:32 volumio-kinderzimmer systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 15 15:29:32 volumio-kinderzimmer sudo[4084]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:32 volumio-kinderzimmer sudo[4088]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:32 volumio-kinderzimmer sudo[4086]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: Discovery: Connected to remote: 10.10.30.151 Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: Shairport-Sync Started Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: Error adding Membership: Error: addMembership EINVAL Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: Shairport-Sync Started Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: Shairport-Sync Started Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: SPOTIFY: BQDkM4x54Viz31uYbcyiykoSvLlHAUh5lNxFZIz7DcWNDsy_goWX3IT45ieiXyapCx2ImzaNruoS0gyMWdwJtD4SlIkFJbypB3e_MO_oddacXMvG5yZrbgfJceiV_GhGVn-rmqIyzpwFzHy1KZBqC2QokCA1--ggNMBeEUyYW0WZxOJEeUAP1RlB_NAKvxqwwXdCaccDyDx56uGX3GvCRxoptvxOINWscCSEOR-_s93z82VwbT7_s5eR6EAZ8ByuSSbmGvHYB1fA1syrr_yR5VyBtl24QyzXBdkj5m14OvxdaJGWry9Ue6C8DseF Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: New Spotify access token = BQDkM4x54Viz31uYbcyiykoSvLlHAUh5lNxFZIz7DcWNDsy_goWX3IT45ieiXyapCx2ImzaNruoS0gyMWdwJtD4SlIkFJbypB3e_MO_oddacXMvG5yZrbgfJceiV_GhGVn-rmqIyzpwFzHy1KZBqC2QokCA1--ggNMBeEUyYW0WZxOJEeUAP1RlB_NAKvxqwwXdCaccDyDx56uGX3GvCRxoptvxOINWscCSEOR-_s93z82VwbT7_s5eR6EAZ8ByuSSbmGvHYB1fA1syrr_yR5VyBtl24QyzXBdkj5m14OvxdaJGWry9Ue6C8DseF Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 15 15:29:32 volumio-kinderzimmer mpd[4038]: 2025-12-15T15:29:32 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 15 15:29:32 volumio-kinderzimmer go-librespot[4076]: time="2025-12-15T15:29:32+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 15 15:29:32 volumio-kinderzimmer go-librespot[4076]: time="2025-12-15T15:29:32+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 15 15:29:32 volumio-kinderzimmer go-librespot[4076]: time="2025-12-15T15:29:32+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 15 15:29:32 volumio-kinderzimmer go-librespot[4076]: time="2025-12-15T15:29:32+01:00" level=info msg="zeroconf server listening on port 34727" Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:32 volumio-kinderzimmer systemd[1]: Started mpd.service - Music Player Daemon. Dec 15 15:29:32 volumio-kinderzimmer sudo[3995]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:32 volumio-kinderzimmer sudo[4005]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: Completed starting Core Plugins Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: ------------------------------------------- Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: ----- MyVolumio plugins startup ---- Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: ------------------------------------------- Dec 15 15:29:32 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 15 15:29:33 volumio-kinderzimmer volumio[3861]: error: MPD error: The expression evaluated to a falsy value: Dec 15 15:29:33 volumio-kinderzimmer volumio[3861]: assert.ok(self.idling) Dec 15 15:29:33 volumio-kinderzimmer volumio[3861]: error: The expression evaluated to a falsy value: Dec 15 15:29:33 volumio-kinderzimmer volumio[3861]: assert.ok(self.idling) Dec 15 15:29:33 volumio-kinderzimmer volumio[3861]: error: updateQueue error: null Dec 15 15:29:33 volumio-kinderzimmer volumio[3861]: info: MPD running with PID4038 Dec 15 15:29:33 volumio-kinderzimmer volumio[3861]: ,establishing connection Dec 15 15:29:33 volumio-kinderzimmer volumio[3861]: error: updateQueue error: null Dec 15 15:29:33 volumio-kinderzimmer go-librespot[4076]: time="2025-12-15T15:29:33+01:00" level=debug msg="obtained new client token: AACOW4un6tprXgr3v6a0OuMcILuy/2OhzASgGgZz+aWiLBvygM1UL1u0np2YG2R39MOLrOZ8ag/cGABVaI9ZcE5SQD5Mrv01YDSTRUPpOdeZ5+yU/SwSLB2ZgEXSqjO5cLq1GmuE3seckfI3x3YIugE3YoINtX11fnApoTgmZh/N0HWSoVpaX6HvwWTk+oTg5Cq8KEl8SsfYM7b+rSn8U8qtQdSIl6TRsEWnd6IZIaNtZ8/UaWwZN97bz/E=" Dec 15 15:29:33 volumio-kinderzimmer volumio[3861]: SPOTIFY: User informations: {"country":"DE","display_name":"Volumio Kinderzimmer","email":"dominik.meyer@outlook.com","explicit_content":{"filter_enabled":true,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31eoyht2wvupu66blolgprllyv4a"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31eoyht2wvupu66blolgprllyv4a","id":"31eoyht2wvupu66blolgprllyv4a","images":[],"product":"premium","type":"user","uri":"spotify:user:31eoyht2wvupu66blolgprllyv4a"} Dec 15 15:29:33 volumio-kinderzimmer volumio[3861]: info: Spotify Successfully logged in Dec 15 15:29:33 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:29:33 volumio-kinderzimmer volumio[3861]: info: [1765808973541] CoreMusicLibrary::Adding element Spotify Dec 15 15:29:33 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:33 volumio-kinderzimmer volumio[3861]: Cannot find translation for source Spotify Dec 15 15:29:33 volumio-kinderzimmer go-librespot[4076]: time="2025-12-15T15:29:33+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:29:33 volumio-kinderzimmer sudo[3910]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:33 volumio-kinderzimmer go-librespot[4076]: time="2025-12-15T15:29:33+01:00" level=debug msg="completed keyexchange" Dec 15 15:29:33 volumio-kinderzimmer go-librespot[4076]: time="2025-12-15T15:29:33+01:00" level=debug msg="completed challenge" Dec 15 15:29:33 volumio-kinderzimmer go-librespot[4076]: time="2025-12-15T15:29:33+01:00" level=info msg="authenticated AP" username="31************************4a" Dec 15 15:29:34 volumio-kinderzimmer go-librespot[4076]: time="2025-12-15T15:29:34+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 15:29:34 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:29:34 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:29:34 volumio-kinderzimmer volumio[3861]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 15 15:29:34 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 15 15:29:34 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioGetVisibleSources Dec 15 15:29:34 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:34 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:34 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:34 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:34 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:34 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 15 15:29:34 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 15 15:29:34 volumio-kinderzimmer volumio[3861]: info: Received Get System Info Dec 15 15:29:34 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 15:29:34 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 15:29:34 volumio-kinderzimmer volumio[3861]: info: Discovery: Getting this device information Dec 15 15:29:34 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:34 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:34 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 15:29:35 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:35 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:35 volumio-kinderzimmer volumio[3861]: info: Listing playlists Dec 15 15:29:35 volumio-kinderzimmer volumio[3861]: info: go-librespot daemon successfully initialized Dec 15 15:29:35 volumio-kinderzimmer sudo[4119]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 15 15:29:35 volumio-kinderzimmer sudo[4119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:36 volumio-kinderzimmer sudo[4121]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 15 15:29:36 volumio-kinderzimmer sudo[4121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:36 volumio-kinderzimmer systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 15 15:29:36 volumio-kinderzimmer systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 15 15:29:36 volumio-kinderzimmer systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 15 15:29:36 volumio-kinderzimmer systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 15 15:29:36 volumio-kinderzimmer mpd_monitor.sh[4124]: MPD Monitor Service: Starting MPD Monitor Service Dec 15 15:29:36 volumio-kinderzimmer sudo[4119]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:36 volumio-kinderzimmer volumio[3861]: info: Successfully started MPD Monitor Dec 15 15:29:36 volumio-kinderzimmer systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 15 15:29:36 volumio-kinderzimmer systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 15 15:29:36 volumio-kinderzimmer systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 15 15:29:36 volumio-kinderzimmer systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 15 15:29:36 volumio-kinderzimmer sudo[4121]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:36 volumio-kinderzimmer volumio[3861]: info: Successfully started MPD Monitor Dec 15 15:29:36 volumio-kinderzimmer mpd_monitor.sh[4127]: MPD Monitor Service: Starting MPD Monitor Service Dec 15 15:29:37 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 15 15:29:37 volumio-kinderzimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:37 volumio-kinderzimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:37 volumio-kinderzimmer go-librespot[4132]: go-librespot daemon starting... Dec 15 15:29:37 volumio-kinderzimmer go-librespot[4133]: time="2025-12-15T15:29:37+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:29:37 volumio-kinderzimmer go-librespot[4133]: time="2025-12-15T15:29:37+01:00" level=debug msg="app state loaded" Dec 15 15:29:37 volumio-kinderzimmer go-librespot[4133]: time="2025-12-15T15:29:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:29:37 volumio-kinderzimmer go-librespot[4133]: time="2025-12-15T15:29:37+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 15 15:29:37 volumio-kinderzimmer go-librespot[4133]: time="2025-12-15T15:29:37+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 15 15:29:37 volumio-kinderzimmer go-librespot[4133]: time="2025-12-15T15:29:37+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 15 15:29:37 volumio-kinderzimmer go-librespot[4133]: time="2025-12-15T15:29:37+01:00" level=info msg="zeroconf server listening on port 37763" Dec 15 15:29:37 volumio-kinderzimmer go-librespot[4133]: time="2025-12-15T15:29:37+01:00" level=debug msg="obtained new client token: AADabKMWAgIsnGrJF/ihSQUtZP+1Ptc+PezudvAUS0SGcYszdPm4e1ctMQNT8bGf1CIBtyNMVDHvLe9gVRCXTCcdMUJ58yxXxZRcjWqQj0PYwzz2CACES3BPuTxwqr/ijE2rbEjTG00/ptwB457i5t73FlZNJmxHczvkM1qZO/P6/ywwqTB9wmGbZBLMnJ/CHPW8h3lygyg8/aJHqb6JsKmJNSd/LRUSyUw463W7Fli5gHVV7XYTCqrkK5o=" Dec 15 15:29:37 volumio-kinderzimmer go-librespot[4133]: time="2025-12-15T15:29:37+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:29:37 volumio-kinderzimmer go-librespot[4133]: time="2025-12-15T15:29:37+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Dec 15 15:29:37 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:29:37 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:29:38 volumio-kinderzimmer sudo[4156]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 15:29:38 volumio-kinderzimmer sudo[4156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:38 volumio-kinderzimmer sudo[4158]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 15:29:38 volumio-kinderzimmer sudo[4158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:38 volumio-kinderzimmer sudo[4158]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:38 volumio-kinderzimmer sudo[4156]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:38 volumio-kinderzimmer sudo[4164]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 15 15:29:38 volumio-kinderzimmer sudo[4164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:38 volumio-kinderzimmer sudo[4164]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:38 volumio-kinderzimmer volumio[3861]: info: Upmpdcli Daemon Started Dec 15 15:29:38 volumio-kinderzimmer volumio[3861]: info: Initializing connection to go-librespot Websocket Dec 15 15:29:38 volumio-kinderzimmer volumio[3861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 15 15:29:40 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:40 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:40 volumio-kinderzimmer volumio[3861]: info: Listing playlists Dec 15 15:29:40 volumio-kinderzimmer volumio[3861]: info: Listing playlists Dec 15 15:29:41 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Dec 15 15:29:41 volumio-kinderzimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:41 volumio-kinderzimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:41 volumio-kinderzimmer go-librespot[4170]: go-librespot daemon starting... Dec 15 15:29:41 volumio-kinderzimmer go-librespot[4171]: time="2025-12-15T15:29:41+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:29:41 volumio-kinderzimmer go-librespot[4171]: time="2025-12-15T15:29:41+01:00" level=debug msg="app state loaded" Dec 15 15:29:41 volumio-kinderzimmer go-librespot[4171]: time="2025-12-15T15:29:41+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:29:41 volumio-kinderzimmer go-librespot[4171]: time="2025-12-15T15:29:41+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 15:29:41 volumio-kinderzimmer go-librespot[4171]: time="2025-12-15T15:29:41+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 15:29:41 volumio-kinderzimmer go-librespot[4171]: time="2025-12-15T15:29:41+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 15:29:41 volumio-kinderzimmer go-librespot[4171]: time="2025-12-15T15:29:41+01:00" level=info msg="zeroconf server listening on port 37853" Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: Initializing connection to go-librespot Websocket Dec 15 15:29:41 volumio-kinderzimmer go-librespot[4171]: time="2025-12-15T15:29:41+01:00" level=debug msg="new websocket client" Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: Connection to go-librespot Websocket established Dec 15 15:29:41 volumio-kinderzimmer go-librespot[4171]: time="2025-12-15T15:29:41+01:00" level=debug msg="obtained new client token: AADFBMtAxxr1e4AVcJDNP+3NRd9SDS9Vurnihq1WGCYkarltBsyaWWaXzrxVMww9B1srNsPByzcseyIG6y3NhyvzN/G/hQh2UQBA8OJdyC24CdcSdunm27JPUiodh2+tY8dq9Vh7G+gftYJ0I1bsK/iQMk6CLh2nPA56tNqg1t3wsg56Ad8mP7y+A1mJpYhDPLIQ4rz1UlO2Lvv/QaBELPWjtAe28on+SpziuKloBymfpv0Q+AhZ3IVR8wo=" Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: Adding plugin bluetooth to MyMusic Plugins Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: Adding plugin multiroom to MyMusic Plugins Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: Adding plugin metavolumio to MyMusic Plugins Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: Adding plugin cd_controller to MyMusic Plugins Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 15 15:29:41 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 15 15:29:41 volumio-kinderzimmer go-librespot[4171]: time="2025-12-15T15:29:41+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:29:41 volumio-kinderzimmer go-librespot[4171]: time="2025-12-15T15:29:41+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Dec 15 15:29:41 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:29:41 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:29:42 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 15 15:29:42 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 15 15:29:42 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:42 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:42 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:42 volumio-kinderzimmer volumio[3861]: info: Starting MyVolumio Remote Streaming Endpoints Dec 15 15:29:42 volumio-kinderzimmer volumio[3861]: info: MyVolumio login type: Token Dec 15 15:29:42 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 15 15:29:42 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 15 15:29:42 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 15 15:29:42 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 15 15:29:42 volumio-kinderzimmer volumio[3861]: info: Streaming services startup Dec 15 15:29:42 volumio-kinderzimmer volumio[3861]: info: Starting Streaming Daemon Dec 15 15:29:42 volumio-kinderzimmer sudo[4181]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 15 15:29:42 volumio-kinderzimmer sudo[4181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:42 volumio-kinderzimmer volumio[3861]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 15 15:29:42 volumio-kinderzimmer sudo[4181]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:42 volumio-kinderzimmer volumio[3861]: info: Connection to go-librespot Websocket closed Dec 15 15:29:42 volumio-kinderzimmer volumio[3861]: error: Cannot start Volumio Streaming Daemon Dec 15 15:29:42 volumio-kinderzimmer volumio[3861]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 15 15:29:42 volumio-kinderzimmer volumio[3861]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: error: MyVolumio Custom Token format not valid, refreshing it Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: verbose: New Socket.io Connection to 10.10.30.152:3000 from 10.10.20.74 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: Discovery: Getting this device information Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: Discovery: Getting this device information Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 15 15:29:43 volumio-kinderzimmer sudo[4189]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 15 15:29:43 volumio-kinderzimmer sudo[4189]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:43 volumio-kinderzimmer sudo[4194]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 15 15:29:43 volumio-kinderzimmer sudo[4194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:43 volumio-kinderzimmer sudo[4201]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 15 15:29:43 volumio-kinderzimmer sudo[4201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:43 volumio-kinderzimmer sudo[4206]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 15 15:29:43 volumio-kinderzimmer sudo[4206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:43 volumio-kinderzimmer sudo[4194]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: MyVolumio login type: Token Dec 15 15:29:43 volumio-kinderzimmer sudo[4189]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:43 volumio-kinderzimmer sudo[4201]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:43 volumio-kinderzimmer sudo[4206]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:43 volumio-kinderzimmer sudo[4214]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 15:29:43 volumio-kinderzimmer sudo[4214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:43 volumio-kinderzimmer sudo[4211]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 15:29:43 volumio-kinderzimmer sudo[4211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:43 volumio-kinderzimmer sudo[4214]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:43 volumio-kinderzimmer sudo[4211]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: Discovery: Getting this device information Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: Discovery: Getting this device information Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:43 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 15:29:44 volumio-kinderzimmer volumio[3861]: verbose: New Socket.io Connection to 10.10.30.152:3000 from 10.10.20.74 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 15 15:29:44 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 15 15:29:44 volumio-kinderzimmer volumio[3861]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 15 15:29:44 volumio-kinderzimmer volumio[3861]: info: Getting Spotify volume Dec 15 15:29:44 volumio-kinderzimmer volumio[3861]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 15:29:44 volumio-kinderzimmer volumio[3861]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 15 15:29:44 volumio-kinderzimmer volumio[3861]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 15 15:29:44 volumio-kinderzimmer volumio[3861]: errno: -111, Dec 15 15:29:44 volumio-kinderzimmer volumio[3861]: code: 'ECONNREFUSED', Dec 15 15:29:44 volumio-kinderzimmer volumio[3861]: syscall: 'connect', Dec 15 15:29:44 volumio-kinderzimmer volumio[3861]: address: '127.0.0.1', Dec 15 15:29:44 volumio-kinderzimmer volumio[3861]: port: 9879, Dec 15 15:29:44 volumio-kinderzimmer volumio[3861]: response: undefined Dec 15 15:29:44 volumio-kinderzimmer volumio[3861]: } Dec 15 15:29:44 volumio-kinderzimmer volumio[3861]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 15:29:44 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Dec 15 15:29:44 volumio-kinderzimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:44 volumio-kinderzimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:44 volumio-kinderzimmer go-librespot[4235]: go-librespot daemon starting... Dec 15 15:29:44 volumio-kinderzimmer sudo[4239]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-15 15:28' Dec 15 15:29:44 volumio-kinderzimmer go-librespot[4238]: time="2025-12-15T15:29:44+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:29:44 volumio-kinderzimmer go-librespot[4238]: time="2025-12-15T15:29:44+01:00" level=debug msg="app state loaded" Dec 15 15:29:44 volumio-kinderzimmer sudo[4239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:44 volumio-kinderzimmer go-librespot[4238]: time="2025-12-15T15:29:44+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:29:44 volumio-kinderzimmer sudo[4239]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:45 volumio-kinderzimmer volumio-remote-updater[972]: [2025-12-15 15:29:45] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 15 15:29:45 volumio-kinderzimmer volumio-remote-updater[972]: [2025-12-15 15:29:45] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 15 15:29:45 volumio-kinderzimmer systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:29:45 volumio-kinderzimmer systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 15 15:29:45 volumio-kinderzimmer systemd[1]: volumio.service: Consumed 17.134s CPU time. Dec 15 15:29:45 volumio-kinderzimmer systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 15 15:29:45 volumio-kinderzimmer go-librespot[4238]: time="2025-12-15T15:29:45+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 15 15:29:45 volumio-kinderzimmer go-librespot[4238]: time="2025-12-15T15:29:45+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 15 15:29:45 volumio-kinderzimmer go-librespot[4238]: time="2025-12-15T15:29:45+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 15 15:29:45 volumio-kinderzimmer systemd[1]: dynamicswap.service: Deactivated successfully. Dec 15 15:29:45 volumio-kinderzimmer go-librespot[4238]: time="2025-12-15T15:29:45+01:00" level=info msg="zeroconf server listening on port 32955" Dec 15 15:29:45 volumio-kinderzimmer go-librespot[4238]: time="2025-12-15T15:29:45+01:00" level=debug msg="obtained new client token: AADtXB5DAK2A74WF+yP4DnAsnP5Ah03jHIQvrzY0yZUQljPJgsEdsnYP6ARknlZs8Q+7yHksHv7bCr9oHn+dK+Xki/Y2UmmXQ7J2cHJ2TqL1foqBnIuXJ65L2HEG+H26lFp4DN6Qrz2q7zlYBgXLhxJx4fU/8APnf1pjO/lq39oHS4CwAhaE0i4AXgRr2h4aO/zqWjhpvhPkgnc/EUeNy7DlwHj948XamcVPrN0gWdcFflb8CfRNQVva" Dec 15 15:29:45 volumio-kinderzimmer systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Dec 15 15:29:45 volumio-kinderzimmer systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 15 15:29:45 volumio-kinderzimmer systemd[1]: Stopped volumio.service - Volumio Backend Module. Dec 15 15:29:45 volumio-kinderzimmer systemd[1]: volumio.service: Consumed 17.134s CPU time. Dec 15 15:29:45 volumio-kinderzimmer go-librespot[4238]: time="2025-12-15T15:29:45+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:29:45 volumio-kinderzimmer systemd[1]: Started volumio.service - Volumio Backend Module. Dec 15 15:29:45 volumio-kinderzimmer systemd[1]: dynamicswap.service: Deactivated successfully. Dec 15 15:29:45 volumio-kinderzimmer go-librespot[4238]: time="2025-12-15T15:29:45+01:00" level=debug msg="completed keyexchange" Dec 15 15:29:45 volumio-kinderzimmer go-librespot[4238]: time="2025-12-15T15:29:45+01:00" level=debug msg="completed challenge" Dec 15 15:29:45 volumio-kinderzimmer go-librespot[4238]: time="2025-12-15T15:29:45+01:00" level=info msg="authenticated AP" username="31************************4a" Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: ------------------------------------------- Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: ----- Volumio3 ---- Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: ------------------------------------------- Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: ----- System startup ---- Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: ------------------------------------------- Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: MYVOLUMIO Environment detected Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: Plugin folders cleanup Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: Scanning into folder /volumio/app/plugins/ Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: Scanning category audio_interface Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: Scanning category miscellanea Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: Scanning category music_service Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: Scanning category plugins.json Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: Scanning category system_controller Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: Scanning category user_interface Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: Scanning into folder /data/plugins/ Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: Scanning category music_service Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: Plugin folders cleanup completed Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: ------------------------------------------- Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: ----- Core plugins startup ---- Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: ------------------------------------------- Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: Loading plugins from folder /volumio/app/plugins/ Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: Adding plugin upnp to MyMusic Plugins Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: Loading plugins from folder /data/plugins/ Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: Loading plugin "system"... Dec 15 15:29:46 volumio-kinderzimmer volumio[4262]: info: Loading plugin "appearance"... Dec 15 15:29:47 volumio-kinderzimmer volumio[4262]: info: Loading plugin "network"... Dec 15 15:29:47 volumio-kinderzimmer volumio[4262]: info: Refreshing Cached IP Addresses Dec 15 15:29:47 volumio-kinderzimmer sudo[4290]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 15:29:47 volumio-kinderzimmer sudo[4290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:47 volumio-kinderzimmer sudo[4292]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 15:29:47 volumio-kinderzimmer sudo[4292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:47 volumio-kinderzimmer volumio[4262]: info: Loading plugin "services"... Dec 15 15:29:47 volumio-kinderzimmer sudo[4292]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:47 volumio-kinderzimmer volumio[4262]: info: Loading plugin "alsa_controller"... Dec 15 15:29:47 volumio-kinderzimmer sudo[4300]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 15 15:29:47 volumio-kinderzimmer sudo[4300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:47 volumio-kinderzimmer sudo[4290]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:47 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 15:29:47 volumio-kinderzimmer volumio[4262]: info: Loading plugin "wizard"... Dec 15 15:29:47 volumio-kinderzimmer volumio[4262]: info: Loading plugin "networkfs"... Dec 15 15:29:47 volumio-kinderzimmer volumio[4262]: info: Starting Udev Watcher for removable devices Dec 15 15:29:47 volumio-kinderzimmer volumio[4262]: info: Ignoring mount for partition: boot Dec 15 15:29:47 volumio-kinderzimmer volumio[4262]: info: Ignoring mount for partition: volumio Dec 15 15:29:47 volumio-kinderzimmer volumio[4262]: info: Ignoring mount for partition: volumio_data Dec 15 15:29:47 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 15 15:29:47 volumio-kinderzimmer volumio[4262]: info: Loading plugin "volumio_command_line_client"... Dec 15 15:29:47 volumio-kinderzimmer volumio[4262]: info: Loading plugin "upnp"... Dec 15 15:29:47 volumio-kinderzimmer volumio[4262]: info: [1765808987833] Starting Upmpd Daemon Dec 15 15:29:47 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 15 15:29:47 volumio-kinderzimmer volumio[4262]: info: Loading plugin "my_music"... Dec 15 15:29:47 volumio-kinderzimmer volumio[4262]: info: Loading plugin "mpd"... Dec 15 15:29:48 volumio-kinderzimmer volumio[4262]: info: Loading plugin "upnp_browser"... Dec 15 15:29:48 volumio-kinderzimmer volumio[4262]: info: Loading plugin "alarm-clock"... Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: info: Loading plugin "airplay_emulation"... Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: info: Starting Shairport Sync Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: info: Loading plugin "last_100"... Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: info: Loading plugin "webradio"... Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: info: Loading plugin "i2s_dacs"... Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: info: I2S DAC not set, start Auto-detection Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: info: Loading plugin "volumiodiscovery"... Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: *** WARNING *** For more information see Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: *** WARNING *** For more information see Dec 15 15:29:49 volumio-kinderzimmer node[4262]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 15 15:29:49 volumio-kinderzimmer node[4262]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:29:49 volumio-kinderzimmer node[4262]: *** WARNING *** For more information see Dec 15 15:29:49 volumio-kinderzimmer node[4262]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 15 15:29:49 volumio-kinderzimmer node[4262]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 15 15:29:49 volumio-kinderzimmer node[4262]: *** WARNING *** For more information see Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: info: Discovery: Started advertising with name: Volumio-Kinderzimmer Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: info: Loading plugin "spop"... Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: info: Loading plugin "outputs"... Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: info: Loading plugin "albumart"... Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: info: Plugin example_plugin is not enabled Dec 15 15:29:49 volumio-kinderzimmer volumio[4262]: info: Loading plugin "inputs"... Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Loading plugin "updater_comm"... Dec 15 15:29:50 volumio-kinderzimmer volumio-remote-updater[972]: [2025-12-15 15:29:50] [connect] Successful connection Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Plugin mpdemulation is not enabled Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Loading plugin "rest_api"... Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Loading plugin "websocket"... Dec 15 15:29:50 volumio-kinderzimmer volumio[4329]: Forking 3 albumart workers Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Starting Socket.io Server version 1.7.4 Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Loading i18n strings for locale de Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: Updating browse sources language Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::initPlayerControls Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: Express server listening on port 3000 Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: [Metrics] WebUI: 4s 249.49ms Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Setting Device type: x86 Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::resetVolumioState Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::getcurrentVolume Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioRetrievevolume Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Completed loading Core Plugins Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Preparing to generate the ALSA configuration file Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Reading ALSA contributions from plugins. Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Volumio Network Manager: Network status updated: 2 Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: verbose: New Socket.io Connection to 10.10.30.152:3000 from 10.10.20.74 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: VolumeController:: Volume=12 Mute =false Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::pushState Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioPushState Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::updateTrackBlock Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrackBlock Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioRetrievevolume Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Reloading queue from file Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::setRepeat null single undefined Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::pushState Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioPushState Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::setRandom null Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::pushState Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioPushState Dec 15 15:29:50 volumio-kinderzimmer volumio[4340]: Starting albumart workers Dec 15 15:29:50 volumio-kinderzimmer volumio-remote-updater[972]: [2025-12-15 15:29:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765808990 101 Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: 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: 4 Dec 15 15:29:50 volumio-kinderzimmer volumio[4339]: Starting albumart workers Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Discovery: adding c64eab90-9ec5-4a46-9b89-d432040ab5b1 Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Discovery: Found device Volumio-Wohnzimmer Dec 15 15:29:50 volumio-kinderzimmer volumio[4341]: Starting albumart workers Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Discovery: Connecting to remote: 10.10.30.151 Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: verbose: New Socket.io Connection to 10.10.30.152:3000 from 10.10.30.151 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: VolumeController:: Volume=12 Mute =false Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::pushState Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioPushState Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Asound.conf file unchanged, so no further update is needed Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Output device has changed, restarting MPD Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: Output device has changed, restarting Shairport Sync Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:50 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:50 volumio-kinderzimmer sudo[4385]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 15 15:29:50 volumio-kinderzimmer sudo[4385]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:50 volumio-kinderzimmer sudo[4385]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:50 volumio-kinderzimmer sudo[4387]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 15 15:29:50 volumio-kinderzimmer sudo[4387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: ___________ START PLUGINS ___________ Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: ControllerMpd::onStart: Initializing MPD Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: Creating MPD Configuration file Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: mpd.service: Deactivated successfully. Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: mpd.service: Consumed 1.642s CPU time. Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: mpd.socket: Deactivated successfully. Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: [1765808991069] CoreMusicLibrary::Adding element Medienserver Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 15 15:29:51 volumio-kinderzimmer sudo[4395]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 15 15:29:51 volumio-kinderzimmer sudo[4395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: Starting mpd.service - Music Player Daemon... Dec 15 15:29:51 volumio-kinderzimmer sudo[4395]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:51 volumio-kinderzimmer sudo[4398]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 15 15:29:51 volumio-kinderzimmer sudo[4398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: mpd.service: Deactivated successfully. Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: mpd.socket: Deactivated successfully. Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: [1765808991247] CoreMusicLibrary::Adding element Last_100 Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: [1765808991253] CoreMusicLibrary::Adding element Webradio Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: Initializing BBC Radios Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: Starting mpd.service - Music Player Daemon... Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: Creating Spotify config file Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer sudo[4413]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 15 15:29:51 volumio-kinderzimmer sudo[4413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 15 15:29:51 volumio-kinderzimmer sudo[4413]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: Volumio Calling Home Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: Discovery: Getting this device information Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: Discovery: Getting this device information Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: MPD Permissions set Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: MPD Permissions set Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: Spotify config file written Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer sudo[4450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 15 15:29:51 volumio-kinderzimmer sudo[4450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Killing process 4243 (go-librespot) with signal SIGKILL. Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:51 volumio-kinderzimmer go-librespot[4460]: go-librespot daemon starting... Dec 15 15:29:51 volumio-kinderzimmer sudo[4450]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:29:51 volumio-kinderzimmer go-librespot[4466]: time="2025-12-15T15:29:51+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:29:51 volumio-kinderzimmer go-librespot[4466]: time="2025-12-15T15:29:51+01:00" level=debug msg="app state loaded" Dec 15 15:29:51 volumio-kinderzimmer go-librespot[4466]: time="2025-12-15T15:29:51+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: No need to fix Spotify hosts Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: Discovery: adding 6698be19-c1b4-464f-8221-c1bbc80acc38 Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: Discovery: Found device Volumio-Kinderzimmer Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: Discovery: this is already registered, 6698be19-c1b4-464f-8221-c1bbc80acc38 Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: Discovery: Found device Volumio-Kinderzimmer Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:51 volumio-kinderzimmer volumio[4262]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: verbose: New Socket.io Connection to 10.10.30.152:3000 from 10.10.20.74 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: Starting Shairport Sync Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: Starting Shairport Sync Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: Starting Shairport Sync Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: Volumio called home Dec 15 15:29:52 volumio-kinderzimmer sudo[4477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 15 15:29:52 volumio-kinderzimmer sudo[4477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:52 volumio-kinderzimmer sudo[4481]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 15 15:29:52 volumio-kinderzimmer sudo[4481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:52 volumio-kinderzimmer sudo[4479]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 15 15:29:52 volumio-kinderzimmer sudo[4479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 15 15:29:52 volumio-kinderzimmer systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 15 15:29:52 volumio-kinderzimmer systemd[1]: shairport-sync.service: Deactivated successfully. Dec 15 15:29:52 volumio-kinderzimmer systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 15 15:29:52 volumio-kinderzimmer systemd[1]: shairport-sync.service: Consumed 1.808s CPU time. Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:52 volumio-kinderzimmer systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 15 15:29:52 volumio-kinderzimmer sudo[4479]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: Shairport-Sync Started Dec 15 15:29:52 volumio-kinderzimmer sudo[4477]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: Error adding Membership: Error: addMembership EINVAL Dec 15 15:29:52 volumio-kinderzimmer sudo[4481]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: Shairport-Sync Started Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: Shairport-Sync Started Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: Discovery: Connected to remote: 10.10.30.151 Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: SPOTIFY: BQAE9gZTQJLor4BKmH9Fahw1JjZpKQGHt-l82oRtJt4tKERn52j4hZkxa7UDbGm1if1jbnq3EyKJe-AH-rJnhf7h0fGwYilely55oeOk_8PIDh4M3aNxmkcCEPCitfGqvaCAwH78Nb6Jra8vYXYczNLWnoRrFUcoqXNeoL7dclSD8_9O8160YNm2ttflw4OGneJd3DYnka3rPz2KDSAHfU_Hbhgsx94eRpy4iNiiat7DbCMwf01iK29Zu1ozKIo4ENDrb4QpMYuBgXF9TLKUmCH3-JV30V5XuPhzLciuu6vIHQQBpM7w91B1wbaV Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: New Spotify access token = BQAE9gZTQJLor4BKmH9Fahw1JjZpKQGHt-l82oRtJt4tKERn52j4hZkxa7UDbGm1if1jbnq3EyKJe-AH-rJnhf7h0fGwYilely55oeOk_8PIDh4M3aNxmkcCEPCitfGqvaCAwH78Nb6Jra8vYXYczNLWnoRrFUcoqXNeoL7dclSD8_9O8160YNm2ttflw4OGneJd3DYnka3rPz2KDSAHfU_Hbhgsx94eRpy4iNiiat7DbCMwf01iK29Zu1ozKIo4ENDrb4QpMYuBgXF9TLKUmCH3-JV30V5XuPhzLciuu6vIHQQBpM7w91B1wbaV Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 15 15:29:52 volumio-kinderzimmer mpd[4438]: 2025-12-15T15:29:52 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 15 15:29:52 volumio-kinderzimmer systemd[1]: Started mpd.service - Music Player Daemon. Dec 15 15:29:52 volumio-kinderzimmer sudo[4387]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:52 volumio-kinderzimmer sudo[4398]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:52 volumio-kinderzimmer go-librespot[4466]: time="2025-12-15T15:29:52+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 15 15:29:52 volumio-kinderzimmer go-librespot[4466]: time="2025-12-15T15:29:52+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 15 15:29:52 volumio-kinderzimmer go-librespot[4466]: time="2025-12-15T15:29:52+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 15 15:29:52 volumio-kinderzimmer go-librespot[4466]: time="2025-12-15T15:29:52+01:00" level=info msg="zeroconf server listening on port 36555" Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: Completed starting Core Plugins Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: ------------------------------------------- Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: ----- MyVolumio plugins startup ---- Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: ------------------------------------------- Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: error: MPD error: The expression evaluated to a falsy value: Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: assert.ok(self.idling) Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: error: The expression evaluated to a falsy value: Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: assert.ok(self.idling) Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: info: MPD running with PID4438 Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: ,establishing connection Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: error: updateQueue error: null Dec 15 15:29:52 volumio-kinderzimmer volumio[4262]: error: updateQueue error: null Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: SPOTIFY: User informations: {"country":"DE","display_name":"Volumio Kinderzimmer","email":"dominik.meyer@outlook.com","explicit_content":{"filter_enabled":true,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31eoyht2wvupu66blolgprllyv4a"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31eoyht2wvupu66blolgprllyv4a","id":"31eoyht2wvupu66blolgprllyv4a","images":[],"product":"premium","type":"user","uri":"spotify:user:31eoyht2wvupu66blolgprllyv4a"} Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: Spotify Successfully logged in Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: [1765808993289] CoreMusicLibrary::Adding element Spotify Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: Cannot find translation for source Spotify Dec 15 15:29:53 volumio-kinderzimmer sudo[4300]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:53 volumio-kinderzimmer go-librespot[4466]: time="2025-12-15T15:29:53+01:00" level=debug msg="obtained new client token: AAC/wJy8+xRTQvuOWsZ5F3Zj3PcLeFRoCcteE+hJapYqqQE53BKphNvTYO2AB5LY9uuYYYXM7bAq2h1gQABMW0Y8EtjKY4O9Dl6XU2aQcg4DfX7zHNouz5nUfKPPY+u6GfZzve+wzO0wQUWVQ+DzoepPP4hdPI8OVXGFdZevBwuOrYIOTdl14XpBnKRbj4gWOmoW32FWzsI8qF6bVLLBKzvD/2qH0nZeCAYf1B9Ci5Uz5JPuNaOyqGi6BBM=" Dec 15 15:29:53 volumio-kinderzimmer go-librespot[4466]: time="2025-12-15T15:29:53+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 15 15:29:53 volumio-kinderzimmer go-librespot[4466]: time="2025-12-15T15:29:53+01:00" level=debug msg="completed keyexchange" Dec 15 15:29:53 volumio-kinderzimmer go-librespot[4466]: time="2025-12-15T15:29:53+01:00" level=debug msg="completed challenge" Dec 15 15:29:53 volumio-kinderzimmer go-librespot[4466]: time="2025-12-15T15:29:53+01:00" level=info msg="authenticated AP" username="31************************4a" Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 15 15:29:53 volumio-kinderzimmer go-librespot[4466]: time="2025-12-15T15:29:53+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 15:29:53 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:29:53 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetVisibleSources Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: Received Get System Info Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: Discovery: Getting this device information Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:53 volumio-kinderzimmer volumio[4262]: info: Listing playlists Dec 15 15:29:54 volumio-kinderzimmer sudo[4510]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 15:29:54 volumio-kinderzimmer sudo[4510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:54 volumio-kinderzimmer sudo[4512]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 15:29:54 volumio-kinderzimmer sudo[4512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:54 volumio-kinderzimmer sudo[4512]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:54 volumio-kinderzimmer sudo[4510]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:54 volumio-kinderzimmer volumio[4262]: verbose: New Socket.io Connection to 10.10.30.152 from 10.10.20.74 UA: Mozilla/5.0 (Linux; Android 14; 22101320G Build/UKQ1.240624.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 15:29:55 volumio-kinderzimmer volumio[4262]: info: go-librespot daemon successfully initialized Dec 15 15:29:55 volumio-kinderzimmer sudo[4518]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 15:29:55 volumio-kinderzimmer sudo[4518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:55 volumio-kinderzimmer sudo[4520]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 15:29:55 volumio-kinderzimmer sudo[4520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:55 volumio-kinderzimmer sudo[4520]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:55 volumio-kinderzimmer sudo[4518]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: verbose: New Socket.io Connection to 10.10.30.152 from 10.10.20.74 UA: Mozilla/5.0 (Linux; Android 14; 22101320G Build/UKQ1.240624.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: 9 Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 15 15:29:55 volumio-kinderzimmer sudo[4527]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 15 15:29:55 volumio-kinderzimmer sudo[4527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:55 volumio-kinderzimmer sudo[4529]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 15 15:29:55 volumio-kinderzimmer sudo[4529]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:55 volumio-kinderzimmer systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 15 15:29:55 volumio-kinderzimmer systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 15 15:29:55 volumio-kinderzimmer systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetVisibleSources Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:55 volumio-kinderzimmer systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 15 15:29:55 volumio-kinderzimmer systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 15 15:29:55 volumio-kinderzimmer sudo[4527]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:55 volumio-kinderzimmer systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 15 15:29:55 volumio-kinderzimmer systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetQueue Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::getQueue Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getQueue Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: Listing playlists Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: Received Get System Info Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: Discovery: Getting this device information Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 15:29:55 volumio-kinderzimmer systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:55 volumio-kinderzimmer sudo[4529]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:55 volumio-kinderzimmer mpd_monitor.sh[4533]: MPD Monitor Service: Starting MPD Monitor Service Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: Successfully started MPD Monitor Dec 15 15:29:55 volumio-kinderzimmer volumio[4262]: info: Successfully started MPD Monitor Dec 15 15:29:57 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 15 15:29:57 volumio-kinderzimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:57 volumio-kinderzimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:29:57 volumio-kinderzimmer go-librespot[4538]: go-librespot daemon starting... Dec 15 15:29:57 volumio-kinderzimmer go-librespot[4539]: time="2025-12-15T15:29:57+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:29:57 volumio-kinderzimmer go-librespot[4539]: time="2025-12-15T15:29:57+01:00" level=debug msg="app state loaded" Dec 15 15:29:57 volumio-kinderzimmer go-librespot[4539]: time="2025-12-15T15:29:57+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:29:57 volumio-kinderzimmer go-librespot[4539]: time="2025-12-15T15:29:57+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 15 15:29:57 volumio-kinderzimmer go-librespot[4539]: time="2025-12-15T15:29:57+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 15 15:29:57 volumio-kinderzimmer go-librespot[4539]: time="2025-12-15T15:29:57+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 15 15:29:57 volumio-kinderzimmer go-librespot[4539]: time="2025-12-15T15:29:57+01:00" level=info msg="zeroconf server listening on port 42917" Dec 15 15:29:57 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 15 15:29:57 volumio-kinderzimmer volumio[4262]: info: Received Get System Info Dec 15 15:29:57 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 15:29:57 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 15:29:57 volumio-kinderzimmer volumio[4262]: info: Discovery: Getting this device information Dec 15 15:29:57 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:57 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:57 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 15:29:57 volumio-kinderzimmer go-librespot[4539]: time="2025-12-15T15:29:57+01:00" level=debug msg="obtained new client token: AAChA9LDWaT/tdUPzdUcJeFlBa7nZjKPR/ohRdjx7Iw5bHyZ7rXY+9faEOgUNtL/LYY+Yu9fc+r9oQ0k3EwjOVm7eTYM4Q2Pth/EM1x7wsrNGPhvsHJCdHnbYrSlC+UPSY79Bwf28mEuaHW+fSNnPBbyhTWfo9FgMcQuO3KBCBPy+W+FArHbTDNPLTKy91H8iytopXWTAZLn3UqHXBiugLL3eC6qYj5bMMOnSm7MlHk4Qv+11fgGUr0vQ84=" Dec 15 15:29:57 volumio-kinderzimmer go-librespot[4539]: time="2025-12-15T15:29:57+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:29:57 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 15 15:29:57 volumio-kinderzimmer go-librespot[4539]: time="2025-12-15T15:29:57+01:00" level=debug msg="completed keyexchange" Dec 15 15:29:57 volumio-kinderzimmer go-librespot[4539]: time="2025-12-15T15:29:57+01:00" level=debug msg="completed challenge" Dec 15 15:29:57 volumio-kinderzimmer sudo[4563]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 15:29:57 volumio-kinderzimmer sudo[4563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:57 volumio-kinderzimmer sudo[4565]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 15:29:57 volumio-kinderzimmer sudo[4565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:57 volumio-kinderzimmer sudo[4565]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:57 volumio-kinderzimmer sudo[4563]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:57 volumio-kinderzimmer sudo[4568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 15 15:29:57 volumio-kinderzimmer sudo[4568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:29:57 volumio-kinderzimmer sudo[4568]: pam_unix(sudo:session): session closed for user root Dec 15 15:29:57 volumio-kinderzimmer volumio[4262]: info: Upmpdcli Daemon Started Dec 15 15:29:58 volumio-kinderzimmer volumio[4262]: info: Initializing connection to go-librespot Websocket Dec 15 15:29:58 volumio-kinderzimmer go-librespot[4539]: time="2025-12-15T15:29:58+01:00" level=debug msg="new websocket client" Dec 15 15:29:58 volumio-kinderzimmer volumio[4262]: info: Connection to go-librespot Websocket established Dec 15 15:29:58 volumio-kinderzimmer go-librespot[4539]: time="2025-12-15T15:29:58+01:00" level=info msg="authenticated AP" username="31************************4a" Dec 15 15:29:58 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 15 15:29:58 volumio-kinderzimmer volumio[4262]: info: Received Get System Info Dec 15 15:29:58 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 15:29:58 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 15:29:58 volumio-kinderzimmer volumio[4262]: info: Discovery: Getting this device information Dec 15 15:29:58 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:29:58 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:29:58 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 15:29:58 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 15 15:29:59 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 15 15:29:59 volumio-kinderzimmer volumio[4262]: info: Preload queue cleared Dec 15 15:29:59 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 15 15:29:59 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::ClearQueue Dec 15 15:29:59 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::stop Dec 15 15:29:59 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 15:29:59 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::clearPlayQueue Dec 15 15:29:59 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::saveQueue Dec 15 15:29:59 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioPushQueue Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::addQueueItems Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::addQueueItems Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Preload queue cleared Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Adding Item to queue: spotify:track:0qx854pulAsF45EmRWeON1 Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Exploding uri spotify:track:0qx854pulAsF45EmRWeON1 in service spop Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: EXPLODING URI:spotify:track:0qx854pulAsF45EmRWeON1 Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Adding Item to queue: spotify:track:5LWCDCIB5GWKoA9RV4ZWrs Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Exploding uri spotify:track:5LWCDCIB5GWKoA9RV4ZWrs in service spop Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: EXPLODING URI:spotify:track:5LWCDCIB5GWKoA9RV4ZWrs Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Listing playlists Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Listing playlists Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5LWCDCIB5GWKoA9RV4ZWrs","service":"spop","name":"Kapitel 02: Der kleine Tiger und der große Mut - Der kleine Tiger und der große Mut","artist":"Florian Fickel","album":"Der kleine Tiger und der große Mut (Nach einer Figurenwelt von Janosch)","type":"song","duration":214,"albumart":"https://i.scdn.co/image/ab67616d0000b273cd565f1bf34c0a763554eb0e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0qx854pulAsF45EmRWeON1","service":"spop","name":"Kapitel 01: Der kleine Tiger und der große Mut - Der kleine Tiger und der große Mut","artist":"Florian Fickel","album":"Der kleine Tiger und der große Mut (Nach einer Figurenwelt von Janosch)","type":"song","duration":190,"albumart":"https://i.scdn.co/image/ab67616d0000b273cd565f1bf34c0a763554eb0e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioPushQueue Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::saveQueue Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::updateTrackBlock Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrackBlock Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioPlay Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::play index 1 Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::addQueueItems Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::addQueueItems Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Preload queue cleared Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Adding Item to queue: spotify:track:3OtjfquNgWeJ9FWmKTnNOQ Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Exploding uri spotify:track:3OtjfquNgWeJ9FWmKTnNOQ in service spop Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: EXPLODING URI:spotify:track:3OtjfquNgWeJ9FWmKTnNOQ Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Adding Item to queue: spotify:track:6jQOpJVYi3mm9FndWhtx3T Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Exploding uri spotify:track:6jQOpJVYi3mm9FndWhtx3T in service spop Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: EXPLODING URI:spotify:track:6jQOpJVYi3mm9FndWhtx3T Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Adding Item to queue: spotify:track:1kBxCcIPhcrWW6EOftNRM8 Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Exploding uri spotify:track:1kBxCcIPhcrWW6EOftNRM8 in service spop Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: EXPLODING URI:spotify:track:1kBxCcIPhcrWW6EOftNRM8 Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Adding Item to queue: spotify:track:3RqQB94dnk6HD4lqz9vzsy Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Exploding uri spotify:track:3RqQB94dnk6HD4lqz9vzsy in service spop Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: EXPLODING URI:spotify:track:3RqQB94dnk6HD4lqz9vzsy Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Adding Item to queue: spotify:track:2pRzuwYRgt8ZFCkmB9zyXS Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Exploding uri spotify:track:2pRzuwYRgt8ZFCkmB9zyXS in service spop Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: EXPLODING URI:spotify:track:2pRzuwYRgt8ZFCkmB9zyXS Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Adding Item to queue: spotify:track:7qa6KGNNnyPidNNE5GOoo0 Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Exploding uri spotify:track:7qa6KGNNnyPidNNE5GOoo0 in service spop Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: EXPLODING URI:spotify:track:7qa6KGNNnyPidNNE5GOoo0 Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Adding Item to queue: spotify:track:4esUkRcdCrRg5IIQBj2n3H Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Exploding uri spotify:track:4esUkRcdCrRg5IIQBj2n3H in service spop Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: EXPLODING URI:spotify:track:4esUkRcdCrRg5IIQBj2n3H Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Adding Item to queue: spotify:track:6k2d9gUHBWEm58aWBOXMt3 Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Exploding uri spotify:track:6k2d9gUHBWEm58aWBOXMt3 in service spop Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: EXPLODING URI:spotify:track:6k2d9gUHBWEm58aWBOXMt3 Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Adding Item to queue: spotify:track:2OIPN1fTqvcFw7F71lsH3Y Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Exploding uri spotify:track:2OIPN1fTqvcFw7F71lsH3Y in service spop Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: EXPLODING URI:spotify:track:2OIPN1fTqvcFw7F71lsH3Y Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Adding Item to queue: spotify:track:3yhnNWBI3XrT8RKdUeEgoV Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Exploding uri spotify:track:3yhnNWBI3XrT8RKdUeEgoV in service spop Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: EXPLODING URI:spotify:track:3yhnNWBI3XrT8RKdUeEgoV Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::stop Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::play index undefined Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 1 Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::startPlaybackTimer Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 1 Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: [1765809000282] ControllerSpotify::clearAddPlayTrack Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: Sending Spotify command with payload to local API: /player/play Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2pRzuwYRgt8ZFCkmB9zyXS","service":"spop","name":"Kapitel 07: Der kleine Tiger und der große Mut - Der kleine Tiger und der große Mut","artist":"Florian Fickel","album":"Der kleine Tiger und der große Mut (Nach einer Figurenwelt von Janosch)","type":"song","duration":207,"albumart":"https://i.scdn.co/image/ab67616d0000b273cd565f1bf34c0a763554eb0e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1kBxCcIPhcrWW6EOftNRM8","service":"spop","name":"Kapitel 05: Der kleine Tiger und der große Mut - Der kleine Tiger und der große Mut","artist":"Florian Fickel","album":"Der kleine Tiger und der große Mut (Nach einer Figurenwelt von Janosch)","type":"song","duration":196,"albumart":"https://i.scdn.co/image/ab67616d0000b273cd565f1bf34c0a763554eb0e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2OIPN1fTqvcFw7F71lsH3Y","service":"spop","name":"Kapitel 04: Mein Freund, die Tigerente! - Der kleine Tiger und der große Mut","artist":"Florian Fickel","album":"Der kleine Tiger und der große Mut (Nach einer Figurenwelt von Janosch)","type":"song","duration":182,"albumart":"https://i.scdn.co/image/ab67616d0000b273cd565f1bf34c0a763554eb0e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3RqQB94dnk6HD4lqz9vzsy","service":"spop","name":"Kapitel 06: Der kleine Tiger und der große Mut - Der kleine Tiger und der große Mut","artist":"Florian Fickel","album":"Der kleine Tiger und der große Mut (Nach einer Figurenwelt von Janosch)","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b273cd565f1bf34c0a763554eb0e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6jQOpJVYi3mm9FndWhtx3T","service":"spop","name":"Kapitel 04: Der kleine Tiger und der große Mut - Der kleine Tiger und der große Mut","artist":"Florian Fickel","album":"Der kleine Tiger und der große Mut (Nach einer Figurenwelt von Janosch)","type":"song","duration":204,"albumart":"https://i.scdn.co/image/ab67616d0000b273cd565f1bf34c0a763554eb0e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7qa6KGNNnyPidNNE5GOoo0","service":"spop","name":"Kapitel 01: Mein Freund, die Tigerente! - Der kleine Tiger und der große Mut","artist":"Florian Fickel","album":"Der kleine Tiger und der große Mut (Nach einer Figurenwelt von Janosch)","type":"song","duration":184,"albumart":"https://i.scdn.co/image/ab67616d0000b273cd565f1bf34c0a763554eb0e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6k2d9gUHBWEm58aWBOXMt3","service":"spop","name":"Kapitel 03: Mein Freund, die Tigerente! - Der kleine Tiger und der große Mut","artist":"Florian Fickel","album":"Der kleine Tiger und der große Mut (Nach einer Figurenwelt von Janosch)","type":"song","duration":185,"albumart":"https://i.scdn.co/image/ab67616d0000b273cd565f1bf34c0a763554eb0e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3yhnNWBI3XrT8RKdUeEgoV","service":"spop","name":"Kapitel 05: Mein Freund, die Tigerente! - Der kleine Tiger und der große Mut","artist":"Florian Fickel","album":"Der kleine Tiger und der große Mut (Nach einer Figurenwelt von Janosch)","type":"song","duration":216,"albumart":"https://i.scdn.co/image/ab67616d0000b273cd565f1bf34c0a763554eb0e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4esUkRcdCrRg5IIQBj2n3H","service":"spop","name":"Kapitel 02: Mein Freund, die Tigerente! - Der kleine Tiger und der große Mut","artist":"Florian Fickel","album":"Der kleine Tiger und der große Mut (Nach einer Figurenwelt von Janosch)","type":"song","duration":208,"albumart":"https://i.scdn.co/image/ab67616d0000b273cd565f1bf34c0a763554eb0e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3OtjfquNgWeJ9FWmKTnNOQ","service":"spop","name":"Kapitel 03: Der kleine Tiger und der große Mut - Der kleine Tiger und der große Mut","artist":"Florian Fickel","album":"Der kleine Tiger und der große Mut (Nach einer Figurenwelt von Janosch)","type":"song","duration":181,"albumart":"https://i.scdn.co/image/ab67616d0000b273cd565f1bf34c0a763554eb0e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioPushQueue Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::saveQueue Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::updateTrackBlock Dec 15 15:30:00 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrackBlock Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: Getting Spotify volume Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 1 Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: SPOTIFY: RECEIVED VOLUMIO VOLUME 12 Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: SPOTIFY: SPOTIFY VOLUME undefined Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: SPOTIFY: VOLUMIO VOLUME 12 Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: Aligning Spotify Volume to Volumio Volume Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioGetState Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 1 Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: Setting Spotify Volume from Volumio: 12 Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: Adding plugin bluetooth to MyMusic Plugins Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: Adding plugin multiroom to MyMusic Plugins Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: Adding plugin metavolumio to MyMusic Plugins Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: Adding plugin cd_controller to MyMusic Plugins Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: Starting MyVolumio Remote Streaming Endpoints Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: MyVolumio login type: Token Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 15 15:30:01 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 15 15:30:02 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 15 15:30:02 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 15 15:30:02 volumio-kinderzimmer volumio[4262]: info: Streaming services startup Dec 15 15:30:02 volumio-kinderzimmer volumio[4262]: info: Starting Streaming Daemon Dec 15 15:30:02 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 15 15:30:02 volumio-kinderzimmer sudo[4578]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 15 15:30:02 volumio-kinderzimmer sudo[4578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:30:02 volumio-kinderzimmer sudo[4578]: pam_unix(sudo:session): session closed for user root Dec 15 15:30:02 volumio-kinderzimmer volumio[4262]: error: Cannot start Volumio Streaming Daemon Dec 15 15:30:02 volumio-kinderzimmer volumio[4262]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 15 15:30:02 volumio-kinderzimmer volumio[4262]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 15 15:30:02 volumio-kinderzimmer volumio[4262]: SPOTIFY: SETTING SPOTIFY VOLUME 12 Dec 15 15:30:02 volumio-kinderzimmer volumio[4262]: info: Sending Spotify command with payload to local API: /player/volume Dec 15 15:30:02 volumio-kinderzimmer volumio[4262]: error: MyVolumio Custom Token format not valid, refreshing it Dec 15 15:30:03 volumio-kinderzimmer volumio[4262]: info: MyVolumio login type: Token Dec 15 15:30:04 volumio-kinderzimmer volumio[4262]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 15 15:30:04 volumio-kinderzimmer volumio[4262]: info: MyVolumio token set successfully Dec 15 15:30:04 volumio-kinderzimmer volumio[4262]: info: MYVOLUMIO: Adding device Dec 15 15:30:04 volumio-kinderzimmer volumio[4262]: info: MYVOLUMIO: Evaluating Server Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: MyVolumio Plan changed: premium Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: Removing browser output: myVolumio user plan is not superstar Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: Removing audio output: Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: MYVOLUMIO: Adding device Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: MYVOLUMIO: Evaluating Server Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: Remote config written successfully Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: Starting Tunnel 1 Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: Starting Tunnel Connection Checker Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: Completed starting MyVolumio Plugin Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: MYVolumio Device enabled Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: MyVolumio status changed Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: Streaming services startup Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: Starting Streaming Daemon Dec 15 15:30:05 volumio-kinderzimmer sudo[4627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 15 15:30:05 volumio-kinderzimmer sudo[4627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: Setting Geolocation for MyVolumio to eu10 Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:05 volumio-kinderzimmer sudo[4627]: pam_unix(sudo:session): session closed for user root Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: error: Cannot start Volumio Streaming Daemon Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: error: [MyVolumio PluginManager] Cache data is invalid! Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: Setting Geolocation for MyVolumio to eu10 Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: Successfully Added MyVolumio device Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: Preload queue cleared Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::ClearQueue Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::stop Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::clearPlayQueue Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::saveQueue Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioPushQueue Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::addQueueItems Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::addQueueItems Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: Preload queue cleared Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: Adding Item to queue: spotify:album:0k1ayoWNLZxATYmLPQnhf7 Dec 15 15:30:05 volumio-kinderzimmer volumio[4262]: info: Exploding uri spotify:album:0k1ayoWNLZxATYmLPQnhf7 in service spop Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: SPOTIFY: EXPLODING URI:spotify:album:0k1ayoWNLZxATYmLPQnhf7 Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioPushQueue Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::saveQueue Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::updateTrackBlock Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrackBlock Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::volumioPlay Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::play index 0 Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::stop Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::play index undefined Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: info: CoreStateMachine::startPlaybackTimer Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: info: CorePlayQueue::getTrack 0 Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: info: [1765809006218] ControllerSpotify::clearAddPlayTrack Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: info: Sending Spotify command with payload to local API: /player/play Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Dec 15 15:30:06 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 15 15:30:07 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 15 15:30:07 volumio-kinderzimmer sudo[4634]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 15 15:30:07 volumio-kinderzimmer sudo[4634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:30:07 volumio-kinderzimmer sudo[4634]: pam_unix(sudo:session): session closed for user root Dec 15 15:30:07 volumio-kinderzimmer volumio[4262]: info: MRS: MultiRoom plugin initialized Dec 15 15:30:07 volumio-kinderzimmer volumio[4262]: info: MRS: STOPPING SNAPCLIENT Dec 15 15:30:07 volumio-kinderzimmer volumio[4262]: info: MRS: Snap server stop Dec 15 15:30:07 volumio-kinderzimmer volumio[4262]: info: MRS: STOPPING volumioStreaming Dec 15 15:30:07 volumio-kinderzimmer sudo[4665]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 15 15:30:07 volumio-kinderzimmer sudo[4665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:30:07 volumio-kinderzimmer sudo[4667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 15 15:30:07 volumio-kinderzimmer sudo[4667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:30:07 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 15 15:30:07 volumio-kinderzimmer sudo[4670]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 15 15:30:07 volumio-kinderzimmer sudo[4670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:30:07 volumio-kinderzimmer sudo[4672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 15 15:30:07 volumio-kinderzimmer sudo[4672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:30:07 volumio-kinderzimmer sudo[4672]: pam_unix(sudo:session): session closed for user root Dec 15 15:30:07 volumio-kinderzimmer sudo[4665]: pam_unix(sudo:session): session closed for user root Dec 15 15:30:07 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 15 15:30:07 volumio-kinderzimmer sudo[4667]: pam_unix(sudo:session): session closed for user root Dec 15 15:30:07 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 15 15:30:07 volumio-kinderzimmer sudo[4670]: pam_unix(sudo:session): session closed for user root Dec 15 15:30:07 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Preparing to generate the ALSA configuration file Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Updating MyVolumio device info Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Updating MyVolumio device info Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Successfully Added MyVolumio device Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Reading ALSA contributions from plugins. Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: MRS: Removed streaming files Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: MRS: volumioStreaming STOPPED Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: MRS: SNAPSERVER STOPPED Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: MRS: SNAPCLIENT STOPPED Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Asound.conf file unchanged, so no further update is needed Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Output device has changed, restarting MPD Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Output device has changed, restarting Shairport Sync Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:08 volumio-kinderzimmer sudo[4678]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 15 15:30:08 volumio-kinderzimmer sudo[4678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:30:08 volumio-kinderzimmer sudo[4678]: pam_unix(sudo:session): session closed for user root Dec 15 15:30:08 volumio-kinderzimmer sudo[4680]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 15 15:30:08 volumio-kinderzimmer sudo[4680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:30:08 volumio-kinderzimmer systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: ------------------------------------ BT MESSAGE: [FUNC] onStart Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: MRS: Pushing multiroomSync output for this device Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: MRS: Pushing multiroomSync output Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Adding audio output: Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Adding audio output: Dec 15 15:30:08 volumio-kinderzimmer systemd[1]: mpd.service: Deactivated successfully. Dec 15 15:30:08 volumio-kinderzimmer systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 15 15:30:08 volumio-kinderzimmer systemd[1]: mpd.service: Consumed 1.668s CPU time. Dec 15 15:30:08 volumio-kinderzimmer systemd[1]: mpd.socket: Deactivated successfully. Dec 15 15:30:08 volumio-kinderzimmer systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 15 15:30:08 volumio-kinderzimmer systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Adding METAVOLUMIO REST API Endpoints Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Preparing CD Folders Dec 15 15:30:08 volumio-kinderzimmer systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Adding CD REST API Endpoints Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Starting UDEV Watcher for CD Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: Detecting CD presence with UDEV Dec 15 15:30:08 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 15 15:30:08 volumio-kinderzimmer systemd[1]: Starting mpd.service - Music Player Daemon... Dec 15 15:30:08 volumio-kinderzimmer bluetoothd[962]: Path / reserved for Adv Monitor app :1.75 Dec 15 15:30:08 volumio-kinderzimmer bluetoothd[962]: Adv Monitor app :1.75 disconnected from D-Bus Dec 15 15:30:08 volumio-kinderzimmer sudo[4695]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 15 15:30:08 volumio-kinderzimmer sudo[4695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 15 15:30:08 volumio-kinderzimmer sudo[4695]: pam_unix(sudo:session): session closed for user root Dec 15 15:30:09 volumio-kinderzimmer mpd[4700]: 2025-12-15T15:30:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 15 15:30:09 volumio-kinderzimmer systemd[1]: Started mpd.service - Music Player Daemon. Dec 15 15:30:09 volumio-kinderzimmer sudo[4680]: pam_unix(sudo:session): session closed for user root Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: warn: [cd-plugin] cdspeedctl: device or media not ready Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: Adding inputs REST Endpoints Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: Scanning Audio Inputs Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: Checking against Known Cards name Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: Adding Server instance for streaming Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: error: Hi Res Audio Failed Login: Missing Login Data Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 15 15:30:13 volumio-kinderzimmer go-librespot[4539]: time="2025-12-15T15:30:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed requesting login5 endpoint: faield unmarshalling LoginResponse: proto:\u00a0cannot parse invalid wire-format data" Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 15 15:30:13 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:30:13 volumio-kinderzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 15 15:30:13 volumio-kinderzimmer sudo[4712]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 15 15:30:13 volumio-kinderzimmer sudo[4712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: Adding TIDAL REST API Endpoints Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: Stopping AccessToken refresher cron for QOBUZ Dec 15 15:30:13 volumio-kinderzimmer sudo[4719]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 15 15:30:13 volumio-kinderzimmer sudo[4712]: pam_unix(sudo:session): session closed for user root Dec 15 15:30:13 volumio-kinderzimmer sudo[4719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:30:13 volumio-kinderzimmer sudo[4719]: pam_unix(sudo:session): session closed for user root Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: AccessToken refresher cron started for QOBUZ Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: Adding QOBUZ REST API Endpoints Dec 15 15:30:13 volumio-kinderzimmer sudo[4722]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 15 15:30:13 volumio-kinderzimmer sudo[4722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: MRS: Getting audio outputs on start Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: MRS: Requesting all other devices output Dec 15 15:30:13 volumio-kinderzimmer systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 15 15:30:13 volumio-kinderzimmer sudo[4722]: pam_unix(sudo:session): session closed for user root Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: MRS: Adding multiroomSync output Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: Adding audio output: Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: MRS: Pushing multiroomSync output Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: Successfully Updated MyVolumio device Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: info: Successfully Updated MyVolumio device Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: Error: socket hang up Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: at connResetException (node:internal/errors:720:14) Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: at Socket.socketOnEnd (node:_http_client:519:23) Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: at Socket.emit (node:events:526:35) Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: at endReadableNT (node:internal/streams/readable:1376:12) Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: code: 'ECONNRESET', Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: response: undefined Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: } Dec 15 15:30:13 volumio-kinderzimmer volumio[4262]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 15:30:13 volumio-kinderzimmer sudo[4740]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-15 15:29' Dec 15 15:30:13 volumio-kinderzimmer sudo[4740]: 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="ec1ce6b3390e13e82b9cc0453f957c4f5298419e" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Nov 17 16:56:11 UTC 2025" VOLUMIO_VERSION="4.071" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="eeb7136fb9e09f0ec773ced3a67e6728"