May 30 06:11:00 volumio2 volumio[1028]: info: Loading plugin "youtube2"... May 30 06:11:01 volumio2 sudo[1191]: pam_unix(sudo:session): session closed for user root May 30 06:11:33 volumio2 ntpd[871]: CLOCK: time stepped by 30.855989 May 30 06:11:33 volumio2 ntpd[871]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 30 06:11:33 volumio2 volumio[1028]: info: Loading plugin "outputs"... May 30 06:11:33 volumio2 volumio[1028]: info: Loading plugin "albumart"... May 30 06:11:33 volumio2 volumio[1028]: info: Plugin example_plugin is not enabled May 30 06:11:33 volumio2 volumio[1028]: info: Loading plugin "inputs"... May 30 06:11:33 volumio2 volumio[1028]: info: Loading plugin "updater_comm"... May 30 06:11:33 volumio2 volumio[1028]: info: Plugin mpdemulation is not enabled May 30 06:11:33 volumio2 volumio[1028]: info: Loading plugin "rest_api"... May 30 06:11:33 volumio2 volumio[1028]: info: Loading plugin "websocket"... May 30 06:11:33 volumio2 volumio[1028]: info: Starting Socket.io Server version 1.7.4 May 30 06:11:33 volumio2 volumio[1028]: info: Loading i18n strings for locale de May 30 06:11:33 volumio2 volumio[1028]: Updating browse sources language May 30 06:11:33 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::initPlayerControls May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 06:11:34 volumio2 volumio[1028]: Express server listening on port 3000 May 30 06:11:34 volumio2 volumio[1028]: [Metrics] WebUI: 7s 766.75ms May 30 06:11:34 volumio2 volumio[1028]: info: CoreStateMachine::resetVolumioState May 30 06:11:34 volumio2 volumio[1028]: info: CoreStateMachine::getcurrentVolume May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::volumioRetrievevolume May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:11:34 volumio2 volumio[1028]: info: Volumio Network Manager: Network status updated: 1 May 30 06:11:34 volumio2 volumio[1028]: verbose: New Socket.io Connection to 192.168.178.127 from 192.168.178.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 1 May 30 06:11:34 volumio2 volumio[1028]: verbose: New Socket.io Connection to 192.168.178.127 from 192.168.178.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 2 May 30 06:11:34 volumio2 volumio[1028]: info: Reloading queue from file May 30 06:11:34 volumio2 volumio-remote-updater[619]: [2025-05-30 06:11:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1748581858 101 May 30 06:11:34 volumio2 volumio[1028]: 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 May 30 06:11:34 volumio2 volumio[1028]: info: VolumeController:: Volume=86 Mute =false May 30 06:11:34 volumio2 volumio[1028]: info: CoreStateMachine::pushState May 30 06:11:34 volumio2 volumio[1028]: info: CorePlayQueue::getTrack 0 May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::volumioPushState May 30 06:11:34 volumio2 volumio[1028]: info: CoreStateMachine::updateTrackBlock May 30 06:11:34 volumio2 volumio[1028]: info: CorePlayQueue::getTrackBlock May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::volumioRetrievevolume May 30 06:11:34 volumio2 volumio[1028]: info: CoreStateMachine::setRepeat null single undefined May 30 06:11:34 volumio2 volumio[1028]: info: CoreStateMachine::pushState May 30 06:11:34 volumio2 volumio[1028]: info: CorePlayQueue::getTrack 0 May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::volumioPushState May 30 06:11:34 volumio2 volumio[1028]: info: CoreStateMachine::setRandom null May 30 06:11:34 volumio2 volumio[1028]: info: CoreStateMachine::pushState May 30 06:11:34 volumio2 volumio[1028]: info: CorePlayQueue::getTrack 0 May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::volumioPushState May 30 06:11:34 volumio2 volumio[1028]: info: Setting Device type: Raspberry PI May 30 06:11:34 volumio2 volumio[1028]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 30 06:11:34 volumio2 volumio[1028]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 May 30 06:11:34 volumio2 volumio[1028]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 30 06:11:34 volumio2 volumio[1254]: Forking 3 albumart workers May 30 06:11:34 volumio2 volumio[1028]: info: VolumeController:: Volume=86 Mute =false May 30 06:11:34 volumio2 volumio[1028]: info: CoreStateMachine::pushState May 30 06:11:34 volumio2 volumio[1028]: info: CorePlayQueue::getTrack 0 May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::volumioPushState May 30 06:11:34 volumio2 volumio[1028]: info: Discovery: adding 5127eb5c-968d-47ab-aff3-56f25f7d782c May 30 06:11:34 volumio2 volumio[1028]: info: Discovery: Found device Volumio2 May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::volumioGetState May 30 06:11:34 volumio2 volumio[1028]: info: CorePlayQueue::getTrack 0 May 30 06:11:34 volumio2 volumio[1028]: info: Discovery: this is already registered, 5127eb5c-968d-47ab-aff3-56f25f7d782c May 30 06:11:34 volumio2 volumio[1028]: info: Discovery: Found device Volumio2 May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::volumioGetState May 30 06:11:34 volumio2 volumio[1028]: info: CorePlayQueue::getTrack 0 May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::volumioGetState May 30 06:11:34 volumio2 volumio[1028]: info: CorePlayQueue::getTrack 0 May 30 06:11:34 volumio2 dhcpcd[681]: wlan0: using IPv4LL address 169.254.232.234 May 30 06:11:34 volumio2 avahi-daemon[597]: Registering new address record for 169.254.232.234 on wlan0.IPv4. May 30 06:11:34 volumio2 dhcpcd[681]: wlan0: adding route to 169.254.0.0/16 May 30 06:11:34 volumio2 volumio[1028]: info: Discovery: this is already registered, 5127eb5c-968d-47ab-aff3-56f25f7d782c May 30 06:11:34 volumio2 volumio[1028]: info: Discovery: Found device Volumio2 May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::volumioGetState May 30 06:11:34 volumio2 volumio[1028]: info: CorePlayQueue::getTrack 0 May 30 06:11:34 volumio2 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 30 06:11:34 volumio2 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 30 06:11:34 volumio2 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 30 06:11:34 volumio2 systemd[1]: welcome.service: Deactivated successfully. May 30 06:11:34 volumio2 systemd[1]: Stopped welcome.service - Show a welcome message on console. May 30 06:11:34 volumio2 systemd[1]: Stopping welcome.service - Show a welcome message on console... May 30 06:11:34 volumio2 systemd[1]: Starting welcome.service - Show a welcome message on console... May 30 06:11:34 volumio2 setdatetime-helper.sh[1321]: Time is not synchronized. Attempting to sync... May 30 06:11:34 volumio2 welcome[1331]: Resolved ip:[3] 192.168.178.127 192.168.211.1 169.254.232.234 May 30 06:11:34 volumio2 volumio[1028]: info: Completed loading Core Plugins May 30 06:11:34 volumio2 volumio[1028]: info: Preparing to generate the ALSA configuration file May 30 06:11:34 volumio2 systemd[1]: Finished welcome.service - Show a welcome message on console. May 30 06:11:34 volumio2 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::volumioGetQueue May 30 06:11:34 volumio2 volumio[1028]: info: CoreStateMachine::getQueue May 30 06:11:34 volumio2 volumio[1028]: info: CorePlayQueue::getQueue May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel May 30 06:11:34 volumio2 volumio[1028]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction May 30 06:11:34 volumio2 volumio[1028]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs May 30 06:11:34 volumio2 volumio[1028]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection May 30 06:11:34 volumio2 volumio[1028]: info: Asound.conf file unchanged, so no further update is needed May 30 06:11:34 volumio2 volumio[1028]: info: Output device has changed, restarting MPD May 30 06:11:34 volumio2 volumio[1028]: info: Output device has changed, restarting Shairport Sync May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:11:34 volumio2 sudo[1349]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Fri, 30 May 2025 05:11:34 GMT#015' May 30 06:11:34 volumio2 sudo[1349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 06:11:34 volumio2 setdatetime-helper.sh[1350]: Fri May 30 06:11:34 +01 2025 May 30 06:11:34 volumio2 setdatetime-helper.sh[1321]: Time synchronized successfully. May 30 06:11:34 volumio2 sudo[1349]: pam_unix(sudo:session): session closed for user root May 30 06:11:34 volumio2 systemd-journald[341]: Time jumped backwards, rotating. May 30 06:11:34 volumio2 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 30 06:11:34 volumio2 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 30 06:11:34 volumio2 sudo[1345]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 06:11:34 volumio2 sudo[1345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 06:11:34 volumio2 sudo[1342]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 06:11:34 volumio2 sudo[1342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 06:11:34 volumio2 sudo[1342]: pam_unix(sudo:session): session closed for user root May 30 06:11:34 volumio2 volumio[1028]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 30 06:11:34 volumio2 volumio[1028]: info: ___________ START PLUGINS ___________ May 30 06:11:34 volumio2 volumio[1028]: info: ControllerMpd::onStart: Initializing MPD May 30 06:11:34 volumio2 volumio[1028]: info: Creating MPD Configuration file May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 06:11:34 volumio2 volumio[1028]: info: [1748581894132] CoreMusicLibrary::Adding element Medienserver May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 06:11:34 volumio2 sudo[1360]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 06:11:34 volumio2 sudo[1360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 06:11:34 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 30 06:11:34 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:11:34 volumio2 sudo[1357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 06:11:34 volumio2 systemd[1]: mpd.service: Deactivated successfully. May 30 06:11:34 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. May 30 06:11:34 volumio2 systemd[1]: mpd.socket: Deactivated successfully. May 30 06:11:34 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 30 06:11:34 volumio2 sudo[1357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 06:11:34 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 30 06:11:34 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 30 06:11:34 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... May 30 06:11:34 volumio2 sudo[1357]: pam_unix(sudo:session): session closed for user root May 30 06:11:34 volumio2 volumio[1028]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:11:34 volumio2 sudo[1370]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 30 06:11:34 volumio2 sudo[1370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 06:11:34 volumio2 sudo[1375]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 30 06:11:34 volumio2 sudo[1370]: pam_unix(sudo:session): session closed for user root May 30 06:11:34 volumio2 volumio[1028]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 06:11:34 volumio2 volumio[1028]: info: [1748581894419] CoreMusicLibrary::Adding element Last_100 May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 06:11:34 volumio2 volumio[1028]: info: [1748581894438] CoreMusicLibrary::Adding element Webradio May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 06:11:34 volumio2 volumio[1028]: info: Initializing BBC Radios May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:11:34 volumio2 volumio[1028]: info: Creating Spotify config file May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 06:11:34 volumio2 volumio[1028]: info: [1748581894706] CoreMusicLibrary::Adding element YouTube2 May 30 06:11:34 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 06:11:34 volumio2 volumio[1028]: Cannot find translation for source YouTube2 May 30 06:11:34 volumio2 volumio[1028]: info: Volumio Calling Home May 30 06:11:35 volumio2 ntpd[871]: IO: Listen normally on 5 wlan0 169.254.232.234:123 May 30 06:11:35 volumio2 ntpd[871]: IO: new interface(s) found: waking up resolver May 30 06:11:36 volumio2 volumio[1028]: info: MPD Permissions set May 30 06:11:36 volumio2 volumio[1028]: info: MPD Permissions set May 30 06:11:36 volumio2 volumio[1028]: info: Spotify config file written May 30 06:11:36 volumio2 sudo[1396]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 30 06:11:36 volumio2 sudo[1396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 06:11:36 volumio2 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 30 06:11:36 volumio2 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 30 06:11:36 volumio2 volumio[1028]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 30 06:11:36 volumio2 volumio[1028]: info: Volumio called home May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:36 volumio2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 30 06:11:36 volumio2 go-librespot[1398]: go-librespot daemon starting... May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:36 volumio2 sudo[1396]: pam_unix(sudo:session): session closed for user root May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:11:36 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:11:36 volumio2 volumio[1028]: info: No need to fix Spotify hosts May 30 06:11:37 volumio2 go-librespot[1399]: time="2025-05-30T06:11:37+01:00" level=info msg="running go-librespot 0.2.0" May 30 06:11:37 volumio2 go-librespot[1399]: time="2025-05-30T06:11:37+01:00" level=debug msg="app state loaded" May 30 06:11:37 volumio2 go-librespot[1399]: time="2025-05-30T06:11:37+01:00" level=debug msg="stored credentials not found" May 30 06:11:37 volumio2 go-librespot[1399]: time="2025-05-30T06:11:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" May 30 06:11:37 volumio2 volumio[1028]: info: Starting Shairport Sync May 30 06:11:37 volumio2 volumio[1028]: info: Starting Shairport Sync May 30 06:11:37 volumio2 volumio[1028]: info: Starting Shairport Sync May 30 06:11:37 volumio2 go-librespot[1399]: time="2025-05-30T06:11: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-gae2.spotify.com:80]" May 30 06:11:37 volumio2 go-librespot[1399]: time="2025-05-30T06:11:37+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]" May 30 06:11:37 volumio2 go-librespot[1399]: time="2025-05-30T06:11:37+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]" May 30 06:11:37 volumio2 go-librespot[1399]: time="2025-05-30T06:11:37+01:00" level=info msg="zeroconf server listening on port 38537" May 30 06:11:37 volumio2 sudo[1418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 06:11:37 volumio2 sudo[1418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 06:11:37 volumio2 sudo[1420]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 06:11:37 volumio2 sudo[1420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 06:11:37 volumio2 sudo[1422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 06:11:37 volumio2 sudo[1422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 06:11:37 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 30 06:11:37 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. May 30 06:11:37 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 06:11:37 volumio2 systemd[1]: shairport-sync.service: Consumed 1.671s CPU time. May 30 06:11:37 volumio2 volumio[1282]: Starting albumart workers May 30 06:11:37 volumio2 volumio[1028]: info: CoreCommandRouter::volumioGetState May 30 06:11:37 volumio2 volumio[1028]: info: CorePlayQueue::getTrack 0 May 30 06:11:37 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 06:11:37 volumio2 sudo[1420]: pam_unix(sudo:session): session closed for user root May 30 06:11:37 volumio2 volumio[1028]: info: Shairport-Sync Started May 30 06:11:37 volumio2 volumio[1028]: Error adding Membership: Error: addMembership EINVAL May 30 06:11:37 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 30 06:11:37 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. May 30 06:11:37 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 06:11:37 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 06:11:37 volumio2 sudo[1418]: pam_unix(sudo:session): session closed for user root May 30 06:11:37 volumio2 volumio[1028]: info: Shairport-Sync Started May 30 06:11:37 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 30 06:11:37 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. May 30 06:11:37 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 06:11:37 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 06:11:37 volumio2 sudo[1422]: pam_unix(sudo:session): session closed for user root May 30 06:11:37 volumio2 volumio[1028]: info: Shairport-Sync Started May 30 06:11:37 volumio2 volumio[1283]: Starting albumart workers May 30 06:11:38 volumio2 sudo[1468]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 06:11:38 volumio2 sudo[1468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 06:11:38 volumio2 sudo[1468]: pam_unix(sudo:session): session closed for user root May 30 06:11:38 volumio2 sudo[1467]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 06:11:38 volumio2 volumio[1281]: Starting albumart workers May 30 06:11:38 volumio2 sudo[1467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 06:11:38 volumio2 sudo[1467]: pam_unix(sudo:session): session closed for user root May 30 06:11:38 volumio2 sudo[1477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 30 06:11:38 volumio2 sudo[1477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 06:11:38 volumio2 mpd[1376]: 2025-05-30T06:11:38 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 30 06:11:38 volumio2 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 30 06:11:38 volumio2 sudo[1477]: pam_unix(sudo:session): session closed for user root May 30 06:11:38 volumio2 volumio[1028]: info: Upmpdcli Daemon Started May 30 06:11:39 volumio2 systemd[1]: Started mpd.service - Music Player Daemon. May 30 06:11:39 volumio2 sudo[1360]: pam_unix(sudo:session): session closed for user root May 30 06:11:39 volumio2 sudo[1345]: pam_unix(sudo:session): session closed for user root May 30 06:11:39 volumio2 volumio[1028]: info: Completed starting Core Plugins May 30 06:11:39 volumio2 volumio[1028]: info: ------------------------------------------- May 30 06:11:39 volumio2 volumio[1028]: info: ----- MyVolumio plugins startup ---- May 30 06:11:39 volumio2 volumio[1028]: info: ------------------------------------------- May 30 06:11:39 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Fetching plans data.... May 30 06:11:39 volumio2 volumio[1028]: error: MPD error: The expression evaluated to a falsy value: May 30 06:11:39 volumio2 volumio[1028]: assert.ok(self.idling) May 30 06:11:39 volumio2 volumio[1028]: error: The expression evaluated to a falsy value: May 30 06:11:39 volumio2 volumio[1028]: assert.ok(self.idling) May 30 06:11:39 volumio2 volumio[1028]: info: MPD running with PID1376 May 30 06:11:39 volumio2 volumio[1028]: ,establishing connection May 30 06:11:39 volumio2 volumio[1028]: error: MPD error: The expression evaluated to a falsy value: May 30 06:11:39 volumio2 volumio[1028]: assert.ok(self.idling) May 30 06:11:39 volumio2 volumio[1028]: error: The expression evaluated to a falsy value: May 30 06:11:39 volumio2 volumio[1028]: assert.ok(self.idling) May 30 06:11:39 volumio2 volumio[1028]: error: updateQueue error: null May 30 06:11:40 volumio2 volumio[1028]: info: go-librespot daemon successfully initialized May 30 06:11:43 volumio2 volumio[1028]: info: Initializing connection to go-librespot Websocket May 30 06:11:43 volumio2 go-librespot[1399]: time="2025-05-30T06:11:43+01:00" level=debug msg="new websocket client" May 30 06:11:43 volumio2 volumio[1028]: info: Connection to go-librespot Websocket established May 30 06:11:44 volumio2 systemd[1]: systemd-fsckd.service: Deactivated successfully. May 30 06:11:45 volumio2 volumio[1028]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 30 06:11:46 volumio2 systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 30 06:11:46 volumio2 volumio[1028]: info: Getting Spotify volume May 30 06:11:48 volumio2 volumio[1028]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 30 06:11:48 volumio2 upmpdcli[1536]: writing RSA key May 30 06:12:03 volumio2 volumio[1028]: error: MyVolumio Plugin failed to start in a timely fashion May 30 06:12:03 volumio2 volumio[1028]: [Metrics] CommandRouter: 37s 290.27ms May 30 06:12:03 volumio2 volumio[1028]: info: CoreCommandRouter::volumiosetStartupVolume May 30 06:12:03 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:12:03 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:12:03 volumio2 volumio[1028]: info: CoreCommandRouter::Close All Modals sent May 30 06:12:03 volumio2 volumio[1028]: info: CoreCommandRouter::Close All Modals sent May 30 06:12:03 volumio2 systemd[1]: systemd-timedated.service: Deactivated successfully. May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 30 06:12:07 volumio2 volumio[1028]: info: Adding plugin bluetooth to MyMusic Plugins May 30 06:12:07 volumio2 volumio[1028]: info: Adding plugin multiroom to MyMusic Plugins May 30 06:12:07 volumio2 volumio[1028]: info: Adding plugin metavolumio to MyMusic Plugins May 30 06:12:07 volumio2 volumio[1028]: info: Adding plugin cd_controller to MyMusic Plugins May 30 06:12:07 volumio2 volumio[1028]: info: Adding plugin smart_inputs to MyMusic Plugins May 30 06:12:07 volumio2 volumio[1028]: info: Adding plugin tidalconnect to MyMusic Plugins May 30 06:12:07 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 30 06:12:08 volumio2 volumio[1028]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 30 06:12:08 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 30 06:12:08 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:12:08 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:12:08 volumio2 volumio[1028]: info: Starting MyVolumio Remote Streaming Endpoints May 30 06:12:08 volumio2 volumio[1028]: info: MyVolumio login type: Token May 30 06:12:08 volumio2 volumio[1028]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 30 06:12:08 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 30 06:12:09 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 30 06:12:09 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 30 06:12:09 volumio2 volumio[1028]: info: Streaming services startup May 30 06:12:09 volumio2 volumio[1028]: info: Starting Streaming Daemon May 30 06:12:09 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 30 06:12:09 volumio2 sudo[1574]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 30 06:12:09 volumio2 sudo[1574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 06:12:09 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 30 06:12:09 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 30 06:12:09 volumio2 sudo[1574]: pam_unix(sudo:session): session closed for user root May 30 06:12:09 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 30 06:12:09 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 06:12:09 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:12:09 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 30 06:12:10 volumio2 volumio[1028]: error: Cannot start Volumio Streaming Daemon May 30 06:12:10 volumio2 volumio[1028]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 30 06:12:10 volumio2 volumio[1028]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 30 06:12:10 volumio2 volumio[1028]: info: BOOT COMPLETED May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 30 06:12:12 volumio2 volumio[1028]: info: Adding plugin bluetooth to MyMusic Plugins May 30 06:12:12 volumio2 volumio[1028]: info: Adding plugin multiroom to MyMusic Plugins May 30 06:12:12 volumio2 volumio[1028]: info: Adding plugin metavolumio to MyMusic Plugins May 30 06:12:12 volumio2 volumio[1028]: info: Adding plugin cd_controller to MyMusic Plugins May 30 06:12:12 volumio2 volumio[1028]: info: Adding plugin smart_inputs to MyMusic Plugins May 30 06:12:12 volumio2 volumio[1028]: info: Adding plugin tidalconnect to MyMusic Plugins May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 30 06:12:12 volumio2 volumio[1028]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 30 06:12:12 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:12:12 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:12:12 volumio2 volumio[1028]: info: Starting MyVolumio Remote Streaming Endpoints May 30 06:12:13 volumio2 volumio[1028]: info: CoreCommandRouter::volumioGetState May 30 06:12:13 volumio2 volumio[1028]: info: CorePlayQueue::getTrack 0 May 30 06:12:14 volumio2 volumio[1028]: Cannot compose Albumart path May 30 06:12:14 volumio2 volumio[1028]: Cannot compose Albumart path May 30 06:12:18 volumio2 volumio[1028]: Cannot compose Albumart path May 30 06:12:19 volumio2 volumio[1028]: Cannot compose Albumart path May 30 06:12:20 volumio2 volumio[1028]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 30 06:12:20 volumio2 volumio[1028]: SPOTIFY: SPOTIFY VOLUME undefined May 30 06:12:20 volumio2 volumio[1028]: SPOTIFY: VOLUMIO VOLUME 86 May 30 06:12:20 volumio2 volumio[1028]: info: Aligning Spotify Volume to Volumio Volume May 30 06:12:20 volumio2 volumio[1028]: info: CoreCommandRouter::volumioGetState May 30 06:12:20 volumio2 volumio[1028]: info: CorePlayQueue::getTrack 0 May 30 06:12:20 volumio2 volumio[1028]: info: Setting Spotify Volume from Volumio: 86 May 30 06:12:20 volumio2 volumio-remote-updater[619]: No test mode May 30 06:12:20 volumio2 volumio-remote-updater[619]: Alpha test mode enabled May 30 06:12:20 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 30 06:12:20 volumio2 volumio[1028]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 30 06:12:20 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 30 06:12:20 volumio2 volumio[1028]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 30 06:12:21 volumio2 volumio[1028]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 30 06:12:21 volumio2 volumio[1028]: info: MyVolumio token set successfully May 30 06:12:21 volumio2 volumio[1028]: info: MYVOLUMIO: Adding device May 30 06:12:21 volumio2 volumio[1028]: info: MYVOLUMIO: Evaluating Server May 30 06:12:21 volumio2 volumio[1028]: info: CoreCommandRouter::volumioGetState May 30 06:12:21 volumio2 volumio[1028]: info: CorePlayQueue::getTrack 0 May 30 06:12:21 volumio2 volumio[1028]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 30 06:12:21 volumio2 volumio[1028]: SPOTIFY: SETTING SPOTIFY VOLUME 86 May 30 06:12:21 volumio2 volumio[1028]: info: Sending Spotify command with payload to local API: /player/volume May 30 06:12:21 volumio2 volumio[1028]: info: MyVolumio status changed May 30 06:12:21 volumio2 volumio[1028]: info: Streaming services startup May 30 06:12:21 volumio2 volumio[1028]: info: Starting Streaming Daemon May 30 06:12:21 volumio2 volumio[1028]: info: Removing browser output: myVolumio user plan is not superstar May 30 06:12:21 volumio2 volumio[1028]: info: Removing audio output: May 30 06:12:21 volumio2 volumio[1028]: info: Stoppping Tunnel 1 May 30 06:12:21 volumio2 sudo[1618]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 30 06:12:21 volumio2 sudo[1618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 06:12:21 volumio2 sudo[1620]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 30 06:12:21 volumio2 sudo[1618]: pam_unix(sudo:session): session closed for user root May 30 06:12:21 volumio2 sudo[1620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 06:12:21 volumio2 volumio[1028]: error: Cannot start Volumio Streaming Daemon May 30 06:12:21 volumio2 volumio[1028]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 30 06:12:21 volumio2 volumio[1028]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 30 06:12:21 volumio2 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 30 06:12:21 volumio2 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 30 06:12:21 volumio2 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 30 06:12:21 volumio2 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 30 06:12:21 volumio2 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 30 06:12:21 volumio2 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 30 06:12:22 volumio2 sudo[1620]: pam_unix(sudo:session): session closed for user root May 30 06:12:22 volumio2 volumio[1028]: info: Remote SSH Stopped May 30 06:12:22 volumio2 volumio[1028]: info: Setting Geolocation for MyVolumio to eu7 May 30 06:12:22 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:12:22 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:12:22 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:12:22 volumio2 volumio[1028]: info: Successfully Added MyVolumio device May 30 06:12:23 volumio2 volumio[1028]: info: Updating MyVolumio device info May 30 06:12:23 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:12:23 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:12:23 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:12:23 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 30 06:12:23 volumio2 volumio[1028]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 30 06:12:23 volumio2 volumio[1028]: info: Successfully Updated MyVolumio device May 30 06:12:23 volumio2 volumio[1028]: info: CoreCommandRouter::volumioGetState May 30 06:12:23 volumio2 volumio[1028]: info: CorePlayQueue::getTrack 0 May 30 06:12:27 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 30 06:12:27 volumio2 volumio[1028]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 30 06:12:27 volumio2 volumio[1028]: info: CoreCommandRouter::volumioGetState May 30 06:12:27 volumio2 volumio[1028]: info: CorePlayQueue::getTrack 0 May 30 06:12:28 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:12:28 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:12:28 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 30 06:12:28 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 30 06:12:28 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 30 06:12:28 volumio2 volumio[1028]: info: CoreCommandRouter::volumioGetBrowseSources May 30 06:12:28 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 06:12:29 volumio2 sudo[1639]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 06:12:29 volumio2 sudo[1639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 06:12:29 volumio2 sudo[1641]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 06:12:29 volumio2 sudo[1639]: pam_unix(sudo:session): session closed for user root May 30 06:12:29 volumio2 sudo[1641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 06:12:30 volumio2 sudo[1641]: pam_unix(sudo:session): session closed for user root May 30 06:12:30 volumio2 volumio[1028]: verbose: New Socket.io Connection to 192.168.178.127 from 192.168.178.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 9 May 30 06:12:30 volumio2 volumio[1028]: info: CoreCommandRouter::volumioGetState May 30 06:12:30 volumio2 volumio[1028]: info: CorePlayQueue::getTrack 0 May 30 06:12:30 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 30 06:12:30 volumio2 volumio[1028]: info: Listing playlists May 30 06:12:30 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 30 06:12:30 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 30 06:12:30 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 30 06:12:30 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 30 06:12:31 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 30 06:12:31 volumio2 volumio[1028]: info: Received Get System Info May 30 06:12:31 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 06:12:31 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 06:12:31 volumio2 volumio[1028]: info: Discovery: Getting this device information May 30 06:12:31 volumio2 volumio[1028]: info: CoreCommandRouter::volumioGetState May 30 06:12:31 volumio2 volumio[1028]: info: CorePlayQueue::getTrack 0 May 30 06:12:31 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 06:12:32 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:12:32 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:12:32 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 30 06:12:32 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 30 06:12:32 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 30 06:12:32 volumio2 volumio[1028]: info: CoreCommandRouter::volumioGetBrowseSources May 30 06:12:32 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 06:12:32 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 30 06:12:32 volumio2 volumio[1028]: info: Received Get System Info May 30 06:12:32 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 06:12:32 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 06:12:32 volumio2 volumio[1028]: info: Discovery: Getting this device information May 30 06:12:32 volumio2 volumio[1028]: info: CoreCommandRouter::volumioGetState May 30 06:12:32 volumio2 volumio[1028]: info: CorePlayQueue::getTrack 0 May 30 06:12:32 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 06:12:33 volumio2 volumio[1028]: info: CoreCommandRouter::volumioGetQueue May 30 06:12:33 volumio2 volumio[1028]: info: CoreStateMachine::getQueue May 30 06:12:33 volumio2 volumio[1028]: info: CorePlayQueue::getQueue May 30 06:12:33 volumio2 volumio[1028]: info: CoreCommandRouter::volumioGetVisibleSources May 30 06:12:33 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 06:12:34 volumio2 volumio[1028]: info: CoreCommandRouter::volumioClearQueue May 30 06:12:34 volumio2 volumio[1028]: info: CoreStateMachine::ClearQueue May 30 06:12:34 volumio2 volumio[1028]: info: CoreStateMachine::stop May 30 06:12:34 volumio2 volumio[1028]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 06:12:34 volumio2 volumio[1028]: info: CorePlayQueue::clearPlayQueue May 30 06:12:34 volumio2 volumio[1028]: info: CorePlayQueue::saveQueue May 30 06:12:34 volumio2 volumio[1028]: info: CoreCommandRouter::volumioPushState May 30 06:12:34 volumio2 volumio[1028]: info: CoreCommandRouter::volumioPushQueue May 30 06:12:34 volumio2 volumio[1028]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 30 06:12:38 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 30 06:12:38 volumio2 volumio[1028]: info: Preload queue cleared May 30 06:12:39 volumio2 volumio[1028]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 30 06:12:40 volumio2 volumio[1028]: info: READ May 30 06:12:40 volumio2 volumio[1028]: Use XMLElement.setAttribute instead May 30 06:12:40 volumio2 volumio[1028]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 06:12:40 volumio2 volumio[1028]: TypeError: children[i].attr(...).value is not a function May 30 06:12:40 volumio2 volumio[1028]: at /volumio/app/plugins/music_service/webradio/index.js:286:47 May 30 06:12:40 volumio2 volumio[1028]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 30 06:12:40 volumio2 volumio[1028]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 06:12:41 volumio2 sudo[1672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-30 06:11' May 30 06:12:41 volumio2 sudo[1672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="1da895bfdbb6eb90d965df32a25a3568df7c84ed" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu May 29 13:12:59 UTC 2025" VOLUMIO_VERSION="4.004" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2df63d74196e499fa1e34fb765df1cca"