May 29 05:46:04 volumio2 ntpd[892]: CLOCK: time stepped by 67.994132 May 29 05:46:04 volumio2 ntpd[892]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 29 05:46:05 volumio2 sudo[1201]: pam_unix(sudo:session): session closed for user root May 29 05:46:06 volumio2 volumio[1038]: info: Loading plugin "outputs"... May 29 05:46:06 volumio2 volumio[1038]: info: Loading plugin "albumart"... May 29 05:46:06 volumio2 volumio[1038]: info: Plugin example_plugin is not enabled May 29 05:46:06 volumio2 volumio[1038]: info: Loading plugin "inputs"... May 29 05:46:06 volumio2 volumio[1038]: info: Loading plugin "updater_comm"... May 29 05:46:06 volumio2 volumio[1038]: info: Plugin mpdemulation is not enabled May 29 05:46:06 volumio2 volumio[1038]: info: Loading plugin "rest_api"... May 29 05:46:06 volumio2 volumio[1038]: info: Loading plugin "websocket"... May 29 05:46:06 volumio2 volumio[1038]: info: Starting Socket.io Server version 1.7.4 May 29 05:46:06 volumio2 volumio[1038]: info: Loading i18n strings for locale de May 29 05:46:06 volumio2 volumio[1038]: Updating browse sources language May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::initPlayerControls May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 05:46:06 volumio2 volumio[1038]: Express server listening on port 3000 May 29 05:46:06 volumio2 volumio[1038]: [Metrics] WebUI: 7s 993.30ms May 29 05:46:06 volumio2 volumio[1038]: info: CoreStateMachine::resetVolumioState May 29 05:46:06 volumio2 volumio[1038]: info: CoreStateMachine::getcurrentVolume May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::volumioRetrievevolume May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 05:46:06 volumio2 volumio[1038]: info: Volumio Network Manager: Network status updated: 1 May 29 05:46:06 volumio2 volumio[1038]: 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 29 05:46:06 volumio2 volumio[1038]: 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 29 05:46:06 volumio2 volumio[1038]: info: Reloading queue from file May 29 05:46:06 volumio2 volumio[1038]: 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: 3 May 29 05:46:06 volumio2 volumio[1038]: info: VolumeController:: Volume=30 Mute =false May 29 05:46:06 volumio2 volumio[1038]: info: CoreStateMachine::pushState May 29 05:46:06 volumio2 volumio[1038]: info: CorePlayQueue::getTrack 0 May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::volumioPushState May 29 05:46:06 volumio2 volumio[1038]: info: CoreStateMachine::updateTrackBlock May 29 05:46:06 volumio2 volumio[1038]: info: CorePlayQueue::getTrackBlock May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::volumioRetrievevolume May 29 05:46:06 volumio2 volumio[1038]: info: CoreStateMachine::setRepeat null single undefined May 29 05:46:06 volumio2 volumio[1038]: info: CoreStateMachine::pushState May 29 05:46:06 volumio2 volumio[1038]: info: CorePlayQueue::getTrack 0 May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::volumioPushState May 29 05:46:06 volumio2 volumio[1038]: info: CoreStateMachine::setRandom null May 29 05:46:06 volumio2 volumio[1038]: info: CoreStateMachine::pushState May 29 05:46:06 volumio2 volumio[1038]: info: CorePlayQueue::getTrack 0 May 29 05:46:06 volumio2 volumio[1038]: info: CoreCommandRouter::volumioPushState May 29 05:46:06 volumio2 volumio[1038]: info: Setting Device type: Raspberry PI May 29 05:46:06 volumio2 volumio[1038]: 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: 4 May 29 05:46:06 volumio2 volumio[1038]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 29 05:46:06 volumio2 volumio[1038]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 May 29 05:46:06 volumio2 volumio[1038]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 29 05:46:07 volumio2 volumio[1038]: 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: 5 May 29 05:46:07 volumio2 volumio[1038]: 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: 6 May 29 05:46:07 volumio2 volumio[1038]: 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: 7 May 29 05:46:07 volumio2 volumio[1038]: 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: 8 May 29 05:46:07 volumio2 volumio[1038]: 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 29 05:46:07 volumio2 volumio[1038]: 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: 10 May 29 05:46:07 volumio2 volumio[1038]: 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: 11 May 29 05:46:07 volumio2 volumio[1038]: 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: 12 May 29 05:46:07 volumio2 volumio[1038]: info: VolumeController:: Volume=30 Mute =false May 29 05:46:07 volumio2 volumio[1038]: info: CoreStateMachine::pushState May 29 05:46:07 volumio2 volumio[1038]: info: CorePlayQueue::getTrack 0 May 29 05:46:07 volumio2 volumio[1038]: info: CoreCommandRouter::volumioPushState May 29 05:46:07 volumio2 volumio[1038]: 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: 13 May 29 05:46:07 volumio2 volumio[1038]: info: Discovery: adding 88a70b4e-690d-4fe8-82ce-2fc2103e44c0 May 29 05:46:07 volumio2 volumio[1038]: info: Discovery: Found device Volumio2 May 29 05:46:07 volumio2 volumio[1038]: info: CoreCommandRouter::volumioGetState May 29 05:46:07 volumio2 volumio[1038]: info: CorePlayQueue::getTrack 0 May 29 05:46:07 volumio2 volumio[1038]: info: Discovery: this is already registered, 88a70b4e-690d-4fe8-82ce-2fc2103e44c0 May 29 05:46:07 volumio2 volumio[1038]: info: Discovery: Found device Volumio2 May 29 05:46:07 volumio2 volumio[1038]: info: CoreCommandRouter::volumioGetState May 29 05:46:07 volumio2 volumio[1038]: info: CorePlayQueue::getTrack 0 May 29 05:46:07 volumio2 volumio[1038]: info: Discovery: this is already registered, 88a70b4e-690d-4fe8-82ce-2fc2103e44c0 May 29 05:46:07 volumio2 volumio[1038]: info: Discovery: Found device Volumio2 May 29 05:46:07 volumio2 volumio[1038]: info: CoreCommandRouter::volumioGetState May 29 05:46:07 volumio2 volumio[1038]: info: CorePlayQueue::getTrack 0 May 29 05:46:07 volumio2 volumio[1228]: Forking 3 albumart workers May 29 05:46:07 volumio2 volumio[1038]: 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: 14 May 29 05:46:07 volumio2 volumio[1038]: 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: 15 May 29 05:46:07 volumio2 volumio[1038]: 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: 16 May 29 05:46:07 volumio2 volumio[1038]: 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: 17 May 29 05:46:07 volumio2 volumio[1038]: 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: 18 May 29 05:46:07 volumio2 volumio[1038]: 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: 19 May 29 05:46:07 volumio2 volumio[1038]: 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: 20 May 29 05:46:07 volumio2 volumio[1038]: 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: 21 May 29 05:46:07 volumio2 volumio[1038]: info: Completed loading Core Plugins May 29 05:46:07 volumio2 volumio[1038]: info: Preparing to generate the ALSA configuration file May 29 05:46:07 volumio2 volumio[1038]: info: Discovery: adding c715a924-aa45-4077-84c2-9415dae33bc4 May 29 05:46:07 volumio2 volumio[1038]: info: Discovery: Found device Volumio-Kueche May 29 05:46:07 volumio2 volumio[1038]: info: Discovery: Connecting to remote: 192.168.178.200 May 29 05:46:07 volumio2 volumio[1038]: 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: 22 May 29 05:46:07 volumio2 volumio[1038]: info: CoreCommandRouter::volumioGetState May 29 05:46:07 volumio2 volumio[1038]: info: CorePlayQueue::getTrack 0 May 29 05:46:07 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 29 05:46:07 volumio2 volumio[1038]: info: Listing playlists May 29 05:46:07 volumio2 volumio[1038]: verbose: New Socket.io Connection to 192.168.178.127:3000 from 192.168.178.200 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 23 May 29 05:46:07 volumio2 volumio[1038]: info: Discovery: Connected to remote: 192.168.178.200 May 29 05:46:07 volumio2 volumio[1038]: info: Asound.conf file unchanged, so no further update is needed May 29 05:46:07 volumio2 volumio[1038]: info: Output device has changed, restarting MPD May 29 05:46:07 volumio2 volumio[1038]: info: Output device has changed, restarting Shairport Sync May 29 05:46:07 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:07 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 05:46:07 volumio2 sudo[1287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 05:46:07 volumio2 sudo[1287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 05:46:07 volumio2 sudo[1287]: pam_unix(sudo:session): session closed for user root May 29 05:46:07 volumio2 sudo[1288]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 05:46:07 volumio2 sudo[1288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 05:46:07 volumio2 volumio[1038]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 05:46:07 volumio2 volumio[1038]: info: ___________ START PLUGINS ___________ May 29 05:46:08 volumio2 volumio[1038]: info: ControllerMpd::onStart: Initializing MPD May 29 05:46:08 volumio2 volumio[1038]: info: Creating MPD Configuration file May 29 05:46:08 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 05:46:08 volumio2 volumio[1038]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 05:46:08 volumio2 volumio[1038]: info: [1748493968060] CoreMusicLibrary::Adding element Medienserver May 29 05:46:08 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 05:46:08 volumio2 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 29 05:46:08 volumio2 sudo[1296]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 05:46:08 volumio2 sudo[1296]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 05:46:08 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 29 05:46:08 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... May 29 05:46:08 volumio2 setdatetime-helper.sh[1297]: Time is not synchronized. Attempting to sync... May 29 05:46:08 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:08 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 05:46:08 volumio2 sudo[1299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 05:46:08 volumio2 sudo[1296]: pam_unix(sudo:session): session closed for user root May 29 05:46:08 volumio2 sudo[1299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 05:46:08 volumio2 sudo[1304]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 05:46:08 volumio2 sudo[1304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 05:46:08 volumio2 sudo[1314]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 29 05:46:08 volumio2 sudo[1304]: pam_unix(sudo:session): session closed for user root May 29 05:46:08 volumio2 sudo[1317]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 29 May 2025 04:46:08 GMT#015' May 29 05:46:08 volumio2 sudo[1317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 05:46:08 volumio2 setdatetime-helper.sh[1318]: Thu May 29 05:46:08 +01 2025 May 29 05:46:08 volumio2 systemd-journald[340]: Time jumped backwards, rotating. May 29 05:46:08 volumio2 volumio[1038]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 05:46:08 volumio2 sudo[1317]: pam_unix(sudo:session): session closed for user root May 29 05:46:08 volumio2 setdatetime-helper.sh[1297]: Time synchronized successfully. May 29 05:46:08 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:08 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 05:46:08 volumio2 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 29 05:46:08 volumio2 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 29 05:46:08 volumio2 dhcpcd[715]: wlan0: using IPv4LL address 169.254.232.234 May 29 05:46:08 volumio2 avahi-daemon[641]: Registering new address record for 169.254.232.234 on wlan0.IPv4. May 29 05:46:08 volumio2 dhcpcd[715]: wlan0: adding route to 169.254.0.0/16 May 29 05:46:08 volumio2 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 29 05:46:08 volumio2 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 29 05:46:08 volumio2 systemd[1]: welcome.service: Deactivated successfully. May 29 05:46:08 volumio2 systemd[1]: Stopped welcome.service - Show a welcome message on console. May 29 05:46:08 volumio2 systemd[1]: Stopping welcome.service - Show a welcome message on console... May 29 05:46:08 volumio2 volumio[1038]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 05:46:08 volumio2 volumio[1038]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 05:46:08 volumio2 volumio[1038]: info: [1748493968130] CoreMusicLibrary::Adding element Last_100 May 29 05:46:08 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 05:46:08 volumio2 systemd[1]: Starting welcome.service - Show a welcome message on console... May 29 05:46:08 volumio2 welcome[1341]: Resolved ip:[3] 192.168.178.127 192.168.211.1 169.254.232.234 May 29 05:46:08 volumio2 systemd[1]: mpd.service: Deactivated successfully. May 29 05:46:08 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. May 29 05:46:08 volumio2 systemd[1]: mpd.socket: Deactivated successfully. May 29 05:46:08 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 29 05:46:08 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 29 05:46:08 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 29 05:46:08 volumio2 volumio[1038]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 05:46:08 volumio2 volumio[1038]: info: [1748493968178] CoreMusicLibrary::Adding element Webradio May 29 05:46:08 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 05:46:08 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... May 29 05:46:08 volumio2 systemd[1]: Finished welcome.service - Show a welcome message on console. May 29 05:46:08 volumio2 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 29 05:46:08 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 05:46:08 volumio2 volumio[1038]: info: Initializing BBC Radios May 29 05:46:08 volumio2 sudo[1347]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 05:46:08 volumio2 sudo[1347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 05:46:08 volumio2 sudo[1356]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 29 05:46:08 volumio2 sudo[1347]: pam_unix(sudo:session): session closed for user root May 29 05:46:08 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 05:46:08 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 05:46:08 volumio2 volumio[1038]: info: Creating Spotify config file May 29 05:46:08 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:08 volumio2 volumio[1038]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 05:46:08 volumio2 volumio[1038]: info: [1748493968486] CoreMusicLibrary::Adding element YouTube2 May 29 05:46:08 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 05:46:08 volumio2 volumio[1038]: Cannot find translation for source YouTube2 May 29 05:46:08 volumio2 volumio[1038]: info: Volumio Calling Home May 29 05:46:09 volumio2 ntpd[892]: IO: Listen normally on 5 wlan0 169.254.232.234:123 May 29 05:46:09 volumio2 ntpd[892]: IO: new interface(s) found: waking up resolver May 29 05:46:10 volumio2 volumio[1256]: Starting albumart workers May 29 05:46:10 volumio2 volumio[1038]: info: MPD Permissions set May 29 05:46:10 volumio2 volumio[1038]: info: MPD Permissions set May 29 05:46:10 volumio2 volumio[1255]: Starting albumart workers May 29 05:46:10 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 29 05:46:10 volumio2 volumio[1038]: info: CoreCommandRouter::volumioGetState May 29 05:46:10 volumio2 volumio[1038]: info: CorePlayQueue::getTrack 0 May 29 05:46:10 volumio2 volumio[1038]: info: Volumio called home May 29 05:46:10 volumio2 volumio[1038]: info: Spotify config file written May 29 05:46:11 volumio2 volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 24 May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:11 volumio2 sudo[1371]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 05:46:11 volumio2 sudo[1371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:11 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 29 05:46:11 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 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 05:46:11 volumio2 volumio[1038]: info: No need to fix Spotify hosts May 29 05:46:11 volumio2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 29 05:46:11 volumio2 go-librespot[1387]: go-librespot daemon starting... May 29 05:46:11 volumio2 sudo[1371]: pam_unix(sudo:session): session closed for user root May 29 05:46:11 volumio2 volumio[1038]: info: Starting Shairport Sync May 29 05:46:11 volumio2 volumio[1038]: info: Starting Shairport Sync May 29 05:46:11 volumio2 volumio[1038]: info: Starting Shairport Sync May 29 05:46:11 volumio2 sudo[1400]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 05:46:11 volumio2 sudo[1400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 05:46:11 volumio2 sudo[1400]: pam_unix(sudo:session): session closed for user root May 29 05:46:11 volumio2 sudo[1402]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 05:46:11 volumio2 sudo[1402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 05:46:11 volumio2 sudo[1402]: pam_unix(sudo:session): session closed for user root May 29 05:46:11 volumio2 sudo[1405]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 05:46:11 volumio2 sudo[1405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 05:46:11 volumio2 sudo[1408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 05:46:11 volumio2 sudo[1408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 05:46:11 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 29 05:46:11 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. May 29 05:46:11 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 29 05:46:11 volumio2 systemd[1]: shairport-sync.service: Consumed 1.676s CPU time. May 29 05:46:11 volumio2 sudo[1413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 05:46:11 volumio2 sudo[1413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 05:46:11 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 29 05:46:11 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 29 05:46:11 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. May 29 05:46:11 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 29 05:46:11 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 29 05:46:11 volumio2 sudo[1413]: pam_unix(sudo:session): session closed for user root May 29 05:46:11 volumio2 sudo[1408]: pam_unix(sudo:session): session closed for user root May 29 05:46:11 volumio2 sudo[1405]: pam_unix(sudo:session): session closed for user root May 29 05:46:11 volumio2 volumio[1038]: info: CoreCommandRouter::volumioGetState May 29 05:46:11 volumio2 volumio[1038]: info: CorePlayQueue::getTrack 0 May 29 05:46:11 volumio2 go-librespot[1397]: time="2025-05-29T05:46:11+01:00" level=info msg="running go-librespot 0.2.0" May 29 05:46:11 volumio2 go-librespot[1397]: time="2025-05-29T05:46:11+01:00" level=debug msg="app state loaded" May 29 05:46:11 volumio2 go-librespot[1397]: time="2025-05-29T05:46:11+01:00" level=debug msg="stored credentials not found" May 29 05:46:11 volumio2 volumio[1038]: info: Shairport-Sync Started May 29 05:46:11 volumio2 go-librespot[1397]: time="2025-05-29T05:46:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" May 29 05:46:11 volumio2 volumio[1038]: Error adding Membership: Error: addMembership EINVAL May 29 05:46:11 volumio2 volumio[1038]: info: Shairport-Sync Started May 29 05:46:11 volumio2 sudo[1437]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 29 05:46:11 volumio2 sudo[1437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 05:46:12 volumio2 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 29 05:46:12 volumio2 sudo[1437]: pam_unix(sudo:session): session closed for user root May 29 05:46:12 volumio2 volumio[1038]: info: Shairport-Sync Started May 29 05:46:12 volumio2 volumio[1257]: Starting albumart workers May 29 05:46:12 volumio2 volumio[1038]: info: Upmpdcli Daemon Started May 29 05:46:12 volumio2 go-librespot[1397]: time="2025-05-29T05:46:12+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 29 05:46:12 volumio2 go-librespot[1397]: time="2025-05-29T05:46:12+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 29 05:46:12 volumio2 go-librespot[1397]: time="2025-05-29T05:46:12+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 29 05:46:12 volumio2 go-librespot[1397]: time="2025-05-29T05:46:12+01:00" level=info msg="zeroconf server listening on port 40785" May 29 05:46:12 volumio2 mpd[1357]: 2025-05-29T05:46:12 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 29 05:46:12 volumio2 systemd[1]: Started mpd.service - Music Player Daemon. May 29 05:46:12 volumio2 sudo[1299]: pam_unix(sudo:session): session closed for user root May 29 05:46:12 volumio2 sudo[1288]: pam_unix(sudo:session): session closed for user root May 29 05:46:13 volumio2 volumio[1038]: info: Completed starting Core Plugins May 29 05:46:13 volumio2 volumio[1038]: info: ------------------------------------------- May 29 05:46:13 volumio2 volumio[1038]: info: ----- MyVolumio plugins startup ---- May 29 05:46:13 volumio2 volumio[1038]: info: ------------------------------------------- May 29 05:46:13 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Fetching plans data.... May 29 05:46:13 volumio2 volumio[1038]: error: MPD error: The expression evaluated to a falsy value: May 29 05:46:13 volumio2 volumio[1038]: assert.ok(self.idling) May 29 05:46:13 volumio2 volumio[1038]: error: The expression evaluated to a falsy value: May 29 05:46:13 volumio2 volumio[1038]: assert.ok(self.idling) May 29 05:46:13 volumio2 volumio[1038]: error: updateQueue error: null May 29 05:46:13 volumio2 volumio[1038]: info: MPD running with PID1357 May 29 05:46:13 volumio2 volumio[1038]: ,establishing connection May 29 05:46:13 volumio2 volumio[1038]: error: updateQueue error: null May 29 05:46:14 volumio2 volumio[1038]: info: go-librespot daemon successfully initialized May 29 05:46:16 volumio2 volumio-remote-updater[652]: [2025-05-29 05:46:16] [connect] Successful connection May 29 05:46:16 volumio2 volumio-remote-updater[652]: [2025-05-29 05:46:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1748493976 101 May 29 05:46:16 volumio2 volumio[1038]: 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: 25 May 29 05:46:17 volumio2 systemd[1]: systemd-fsckd.service: Deactivated successfully. May 29 05:46:17 volumio2 volumio[1038]: info: Initializing connection to go-librespot Websocket May 29 05:46:17 volumio2 go-librespot[1397]: time="2025-05-29T05:46:17+01:00" level=debug msg="new websocket client" May 29 05:46:17 volumio2 volumio[1038]: info: Connection to go-librespot Websocket established May 29 05:46:18 volumio2 volumio[1038]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 29 05:46:19 volumio2 systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 29 05:46:20 volumio2 volumio[1038]: info: Getting Spotify volume May 29 05:46:20 volumio2 volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 May 29 05:46:20 volumio2 volumio[1038]: info: CoreCommandRouter::volumioGetState May 29 05:46:20 volumio2 volumio[1038]: info: CorePlayQueue::getTrack 0 May 29 05:46:20 volumio2 volumio[1038]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 May 29 05:46:20 volumio2 volumio[1038]: SPOTIFY: SPOTIFY VOLUME undefined May 29 05:46:20 volumio2 volumio[1038]: SPOTIFY: VOLUMIO VOLUME 30 May 29 05:46:20 volumio2 volumio[1038]: info: Aligning Spotify Volume to Volumio Volume May 29 05:46:20 volumio2 volumio[1038]: info: CoreCommandRouter::volumioGetState May 29 05:46:20 volumio2 volumio[1038]: info: CorePlayQueue::getTrack 0 May 29 05:46:20 volumio2 volumio[1038]: info: Setting Spotify Volume from Volumio: 30 May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 29 05:46:21 volumio2 sudo[1507]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 05:46:21 volumio2 sudo[1505]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 05:46:21 volumio2 sudo[1505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 05:46:21 volumio2 volumio[1038]: info: Adding plugin bluetooth to MyMusic Plugins May 29 05:46:21 volumio2 sudo[1507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 05:46:21 volumio2 volumio[1038]: info: Adding plugin multiroom to MyMusic Plugins May 29 05:46:21 volumio2 sudo[1505]: pam_unix(sudo:session): session closed for user root May 29 05:46:21 volumio2 volumio[1038]: info: Adding plugin metavolumio to MyMusic Plugins May 29 05:46:21 volumio2 volumio[1038]: info: Adding plugin cd_controller to MyMusic Plugins May 29 05:46:21 volumio2 sudo[1507]: pam_unix(sudo:session): session closed for user root May 29 05:46:21 volumio2 volumio[1038]: info: Adding plugin smart_inputs to MyMusic Plugins May 29 05:46:21 volumio2 volumio[1038]: info: Adding plugin tidalconnect to MyMusic Plugins May 29 05:46:21 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 29 05:46:21 volumio2 upmpdcli[1510]: writing RSA key May 29 05:46:22 volumio2 volumio[1038]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 29 05:46:22 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 29 05:46:22 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 05:46:22 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 05:46:22 volumio2 volumio[1038]: info: Starting MyVolumio Remote Streaming Endpoints May 29 05:46:22 volumio2 volumio[1038]: info: MyVolumio login type: Token May 29 05:46:22 volumio2 volumio[1038]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 29 05:46:22 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 29 05:46:24 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 29 05:46:24 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 29 05:46:24 volumio2 volumio[1038]: info: Streaming services startup May 29 05:46:24 volumio2 volumio[1038]: info: Starting Streaming Daemon May 29 05:46:24 volumio2 volumio[1038]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 29 05:46:24 volumio2 sudo[1515]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 29 05:46:24 volumio2 sudo[1515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 05:46:24 volumio2 volumio[1038]: SPOTIFY: SETTING SPOTIFY VOLUME 30 May 29 05:46:24 volumio2 volumio[1038]: info: Sending Spotify command with payload to local API: /player/volume May 29 05:46:24 volumio2 sudo[1515]: pam_unix(sudo:session): session closed for user root May 29 05:46:24 volumio2 volumio[1038]: error: Cannot start Volumio Streaming Daemon May 29 05:46:24 volumio2 volumio[1038]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 29 05:46:24 volumio2 volumio[1038]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 29 05:46:24 volumio2 volumio[1038]: 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: 26 May 29 05:46:24 volumio2 volumio[1038]: info: CoreCommandRouter::volumioGetVisibleSources May 29 05:46:24 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 05:46:24 volumio2 volumio[1038]: info: CoreCommandRouter::volumioGetState May 29 05:46:24 volumio2 volumio[1038]: info: CorePlayQueue::getTrack 0 May 29 05:46:24 volumio2 volumio[1038]: error: MyVolumio Custom Token format not valid, refreshing it May 29 05:46:25 volumio2 sudo[1523]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 05:46:25 volumio2 sudo[1523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 05:46:25 volumio2 sudo[1525]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 05:46:25 volumio2 sudo[1525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 05:46:25 volumio2 sudo[1523]: pam_unix(sudo:session): session closed for user root May 29 05:46:25 volumio2 sudo[1525]: pam_unix(sudo:session): session closed for user root May 29 05:46:25 volumio2 volumio[1038]: 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: 26 May 29 05:46:25 volumio2 volumio[1038]: info: CoreCommandRouter::volumioGetState May 29 05:46:25 volumio2 volumio[1038]: info: CorePlayQueue::getTrack 0 May 29 05:46:25 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 29 05:46:25 volumio2 volumio[1038]: info: Listing playlists May 29 05:46:25 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 29 05:46:25 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 29 05:46:25 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 29 05:46:25 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 05:46:25 volumio2 volumio[1038]: info: MyVolumio login type: Token May 29 05:46:26 volumio2 volumio[1038]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 29 05:46:26 volumio2 volumio[1038]: info: MyVolumio token set successfully May 29 05:46:26 volumio2 volumio[1038]: info: MYVOLUMIO: Adding device May 29 05:46:26 volumio2 volumio[1038]: info: MYVOLUMIO: Evaluating Server May 29 05:46:26 volumio2 volumio[1038]: info: MyVolumio status changed May 29 05:46:26 volumio2 volumio[1038]: info: Streaming services startup May 29 05:46:26 volumio2 volumio[1038]: info: Starting Streaming Daemon May 29 05:46:26 volumio2 volumio[1038]: info: Removing browser output: myVolumio user plan is not superstar May 29 05:46:26 volumio2 volumio[1038]: info: Removing audio output: May 29 05:46:26 volumio2 volumio[1038]: info: Stoppping Tunnel 1 May 29 05:46:26 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 05:46:27 volumio2 volumio[1038]: info: Received Get System Info May 29 05:46:27 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 05:46:27 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 05:46:27 volumio2 sudo[1547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 29 05:46:27 volumio2 sudo[1547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 05:46:27 volumio2 volumio[1038]: info: Discovery: Getting this device information May 29 05:46:27 volumio2 volumio[1038]: info: CoreCommandRouter::volumioGetState May 29 05:46:27 volumio2 volumio[1038]: info: CorePlayQueue::getTrack 0 May 29 05:46:27 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 05:46:27 volumio2 sudo[1549]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 29 05:46:27 volumio2 sudo[1549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 05:46:27 volumio2 sudo[1547]: pam_unix(sudo:session): session closed for user root May 29 05:46:27 volumio2 volumio[1038]: error: Cannot start Volumio Streaming Daemon May 29 05:46:27 volumio2 volumio[1038]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 29 05:46:27 volumio2 volumio[1038]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 29 05:46:27 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 29 05:46:27 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 29 05:46:27 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 29 05:46:27 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 29 05:46:27 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 29 05:46:27 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 29 05:46:27 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 29 05:46:27 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 29 05:46:27 volumio2 sudo[1549]: pam_unix(sudo:session): session closed for user root May 29 05:46:27 volumio2 volumio[1038]: info: Remote SSH Stopped May 29 05:46:27 volumio2 volumio[1038]: info: Setting Geolocation for MyVolumio to eu6 May 29 05:46:27 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 05:46:27 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 05:46:27 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 05:46:27 volumio2 volumio[1038]: info: Successfully Added MyVolumio device May 29 05:46:27 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 05:46:27 volumio2 volumio[1038]: info: Received Get System Info May 29 05:46:27 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 05:46:27 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 05:46:27 volumio2 volumio[1038]: info: Discovery: Getting this device information May 29 05:46:27 volumio2 volumio[1038]: info: CoreCommandRouter::volumioGetState May 29 05:46:27 volumio2 volumio[1038]: info: CorePlayQueue::getTrack 0 May 29 05:46:27 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 05:46:27 volumio2 volumio[1038]: info: CoreCommandRouter::volumioGetVisibleSources May 29 05:46:27 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 05:46:27 volumio2 volumio[1038]: info: CoreCommandRouter::volumioGetQueue May 29 05:46:27 volumio2 volumio[1038]: info: CoreStateMachine::getQueue May 29 05:46:27 volumio2 volumio[1038]: info: CorePlayQueue::getQueue May 29 05:46:28 volumio2 volumio[1038]: info: Updating MyVolumio device info May 29 05:46:28 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 05:46:28 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 05:46:28 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 05:46:28 volumio2 volumio[1038]: info: Successfully Updated MyVolumio device May 29 05:46:30 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 29 05:46:30 volumio2 volumio[1038]: info: Preload queue cleared May 29 05:46:31 volumio2 volumio[1038]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 29 05:46:31 volumio2 volumio[1038]: info: READ May 29 05:46:31 volumio2 volumio[1038]: Use XMLElement.setAttribute instead May 29 05:46:31 volumio2 volumio[1038]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 05:46:31 volumio2 volumio[1038]: TypeError: children[i].attr(...).value is not a function May 29 05:46:31 volumio2 volumio[1038]: at /volumio/app/plugins/music_service/webradio/index.js:286:47 May 29 05:46:31 volumio2 volumio[1038]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 29 05:46:31 volumio2 volumio[1038]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 05:46:33 volumio2 sudo[1580]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-29 05:45' May 29 05:46:33 volumio2 sudo[1580]: 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="Wed May 28 19:10:18 UTC 2025" VOLUMIO_VERSION="4.003" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1bd40b20372a0e1ffe6ebc6d3df45570"