Jun 25 09:33:01 raspbmusic volumio[921]: info: Loading plugin "outputs"... Jun 25 09:33:01 raspbmusic volumio[921]: info: Loading plugin "albumart"... Jun 25 09:33:01 raspbmusic volumio[921]: info: Plugin example_plugin is not enabled Jun 25 09:33:01 raspbmusic volumio[921]: info: Loading plugin "inputs"... Jun 25 09:33:01 raspbmusic volumio[921]: info: Loading plugin "updater_comm"... Jun 25 09:33:01 raspbmusic volumio[921]: info: Plugin mpdemulation is not enabled Jun 25 09:33:01 raspbmusic volumio[921]: info: Loading plugin "rest_api"... Jun 25 09:33:01 raspbmusic volumio[921]: info: Loading plugin "websocket"... Jun 25 09:33:01 raspbmusic volumio[921]: info: Starting Socket.io Server version 1.7.4 Jun 25 09:33:01 raspbmusic systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jun 25 09:33:01 raspbmusic volumio[921]: info: Loading plugin "Systeminfo"... Jun 25 09:33:02 raspbmusic volumio[1133]: Forking 3 albumart workers Jun 25 09:33:02 raspbmusic volumio[921]: info: Loading i18n strings for locale en Jun 25 09:33:02 raspbmusic volumio[921]: Updating browse sources language Jun 25 09:33:03 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreCommandRouter::initPlayerControls Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 09:33:04 raspbmusic volumio[921]: Express server listening on port 3000 Jun 25 09:33:04 raspbmusic volumio[921]: [Metrics] WebUI: 25s 534.34ms Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreStateMachine::resetVolumioState Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreStateMachine::getcurrentVolume Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreCommandRouter::volumioRetrievevolume Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:04 raspbmusic sudo[1195]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 25 09:33:04 raspbmusic sudo[1195]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 25 09:33:04 raspbmusic sudo[1195]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:04 raspbmusic sudo[1197]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 25 09:33:04 raspbmusic volumio[921]: info: Volumio Network Manager: Network status updated: 1 Jun 25 09:33:04 raspbmusic sudo[1197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 25 09:33:04 raspbmusic sudo[1197]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:04 raspbmusic volumio[921]: verbose: New Socket.io Connection to 192.168.33.60 from 192.168.35.145 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 1 Jun 25 09:33:04 raspbmusic volumio[921]: verbose: New Socket.io Connection to 192.168.33.60 from 192.168.33.178 UA: Mozilla/5.0 (X11; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 2 Jun 25 09:33:04 raspbmusic volumio[921]: info: VolumeController:: Volume=100 Mute =false Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:33:04 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:33:04 raspbmusic volumio[921]: info: CoreStateMachine::updateTrackBlock Jun 25 09:33:05 raspbmusic volumio[921]: info: CorePlayQueue::getTrackBlock Jun 25 09:33:05 raspbmusic volumio[921]: info: CoreCommandRouter::volumioRetrievevolume Jun 25 09:33:05 raspbmusic volumio[921]: info: Reloading queue from file Jun 25 09:33:05 raspbmusic volumio[921]: info: CoreStateMachine::setRepeat null single undefined Jun 25 09:33:05 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:33:05 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:05 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 25 09:33:05 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:33:05 raspbmusic volumio[921]: info: CoreStateMachine::setRandom null Jun 25 09:33:05 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:33:05 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:05 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:33:05 raspbmusic volumio[921]: info: Setting Device type: Raspberry PI Jun 25 09:33:05 raspbmusic volumio[921]: verbose: New Socket.io Connection to 192.168.33.60 from 192.168.33.178 UA: Mozilla/5.0 (X11; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 3 Jun 25 09:33:05 raspbmusic volumio[921]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 25 09:33:05 raspbmusic volumio[921]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a52082 Jun 25 09:33:05 raspbmusic volumio[921]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 25 09:33:05 raspbmusic volumio[921]: verbose: New Socket.io Connection to 192.168.33.60 from 192.168.35.145 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 4 Jun 25 09:33:05 raspbmusic volumio[921]: info: Discovery: adding 0fa37725-88f6-4cf7-b733-8df35af2de48 Jun 25 09:33:05 raspbmusic volumio[921]: info: Discovery: Found device raspbmusic Jun 25 09:33:05 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:33:05 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:05 raspbmusic sudo[1213]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 25 09:33:05 raspbmusic sudo[1213]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 25 09:33:05 raspbmusic volumio[921]: verbose: New Socket.io Connection to 192.168.33.60 from 192.168.33.178 UA: Mozilla/5.0 (X11; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 5 Jun 25 09:33:05 raspbmusic volumio[921]: info: VolumeController:: Volume=100 Mute =false Jun 25 09:33:05 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:33:05 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:05 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:33:05 raspbmusic volumio[921]: info: Discovery: this is already registered, 0fa37725-88f6-4cf7-b733-8df35af2de48 Jun 25 09:33:05 raspbmusic volumio[921]: info: Discovery: Found device raspbmusic Jun 25 09:33:05 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:33:05 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:05 raspbmusic volumio[921]: verbose: New Socket.io Connection to 192.168.33.60 from 192.168.35.145 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 6 Jun 25 09:33:05 raspbmusic systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 25 09:33:05 raspbmusic sudo[1213]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:06 raspbmusic volumio[921]: verbose: New Socket.io Connection to 192.168.33.60 from 192.168.33.178 UA: Mozilla/5.0 (X11; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 7 Jun 25 09:33:06 raspbmusic volumio[921]: info: Completed loading Core Plugins Jun 25 09:33:06 raspbmusic volumio[921]: info: Preparing to generate the ALSA configuration file Jun 25 09:33:06 raspbmusic volumio[921]: verbose: New Socket.io Connection to 192.168.33.60 from 192.168.35.145 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 8 Jun 25 09:33:06 raspbmusic volumio[921]: info: Upmpdcli Daemon Started Jun 25 09:33:06 raspbmusic volumio[921]: verbose: New Socket.io Connection to 192.168.33.60 from 192.168.33.178 UA: Mozilla/5.0 (X11; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 9 Jun 25 09:33:06 raspbmusic volumio[921]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Jun 25 09:33:06 raspbmusic volumio[921]: verbose: New Socket.io Connection to 192.168.33.60 from 192.168.35.145 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 10 Jun 25 09:33:06 raspbmusic volumio[921]: info: Asound.conf file unchanged, so no further update is needed Jun 25 09:33:06 raspbmusic volumio[921]: info: Output device has changed, restarting MPD Jun 25 09:33:06 raspbmusic volumio[921]: info: Output device has changed, restarting Shairport Sync Jun 25 09:33:06 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:06 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:06 raspbmusic sudo[1227]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 25 09:33:06 raspbmusic sudo[1227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 25 09:33:06 raspbmusic sudo[1227]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:06 raspbmusic sudo[1229]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 25 09:33:06 raspbmusic sudo[1229]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 25 09:33:06 raspbmusic volumio[921]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 25 09:33:06 raspbmusic volumio[921]: info: ___________ START PLUGINS ___________ Jun 25 09:33:06 raspbmusic volumio[921]: info: ControllerMpd::onStart: Initializing MPD Jun 25 09:33:06 raspbmusic volumio[921]: info: Creating MPD Configuration file Jun 25 09:33:06 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 09:33:06 raspbmusic volumio[921]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 25 09:33:06 raspbmusic sudo[1240]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 25 09:33:06 raspbmusic sudo[1240]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 25 09:33:06 raspbmusic volumio[921]: info: [1750833186768] CoreMusicLibrary::Adding element Media Servers Jun 25 09:33:06 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 25 09:33:06 raspbmusic systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 25 09:33:06 raspbmusic systemd[1]: Starting mpd.service - Music Player Daemon... Jun 25 09:33:06 raspbmusic systemd[1]: mpd.service: Deactivated successfully. Jun 25 09:33:06 raspbmusic systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 25 09:33:06 raspbmusic systemd[1]: mpd.socket: Deactivated successfully. Jun 25 09:33:06 raspbmusic systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 25 09:33:06 raspbmusic systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 25 09:33:06 raspbmusic systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 25 09:33:06 raspbmusic sudo[1237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 25 09:33:06 raspbmusic sudo[1237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 25 09:33:06 raspbmusic systemd[1]: Starting mpd.service - Music Player Daemon... Jun 25 09:33:06 raspbmusic sudo[1237]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:06 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:07 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:07 raspbmusic sudo[1244]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 25 09:33:07 raspbmusic sudo[1244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 25 09:33:07 raspbmusic volumio[921]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 25 09:33:07 raspbmusic sudo[1250]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 25 09:33:07 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:07 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:07 raspbmusic sudo[1244]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:07 raspbmusic volumio[1146]: Starting albumart workers Jun 25 09:33:07 raspbmusic volumio[921]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 25 09:33:07 raspbmusic volumio[921]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 25 09:33:07 raspbmusic volumio[921]: info: [1750833187285] CoreMusicLibrary::Adding element Last_100 Jun 25 09:33:07 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 25 09:33:07 raspbmusic volumio[921]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 25 09:33:07 raspbmusic volumio[921]: info: [1750833187288] CoreMusicLibrary::Adding element Webradio Jun 25 09:33:07 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 25 09:33:07 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 25 09:33:07 raspbmusic volumio[921]: info: Initializing BBC Radios Jun 25 09:33:07 raspbmusic systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 25 09:33:07 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 25 09:33:07 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:07 raspbmusic volumio[921]: info: Creating Spotify config file Jun 25 09:33:08 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:08 raspbmusic volumio[921]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 25 09:33:08 raspbmusic volumio[921]: info: [1750833188089] CoreMusicLibrary::Adding element YouTube2 Jun 25 09:33:08 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 25 09:33:08 raspbmusic volumio[921]: Cannot find translation for source YouTube2 Jun 25 09:33:08 raspbmusic volumio[1147]: Starting albumart workers Jun 25 09:33:08 raspbmusic volumio[1148]: Starting albumart workers Jun 25 09:33:08 raspbmusic volumio[921]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 25 09:33:08 raspbmusic volumio[921]: info: [1750833188637] CoreMusicLibrary::Adding element YouTube Music Jun 25 09:33:08 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 25 09:33:08 raspbmusic volumio[921]: Cannot find translation for source YouTube2 Jun 25 09:33:08 raspbmusic volumio[921]: Cannot find translation for source YouTube Music Jun 25 09:33:08 raspbmusic volumio[921]: info: Volumio Calling Home Jun 25 09:33:10 raspbmusic volumio-remote-updater[561]: [2025-06-25 09:33:10] [connect] Successful connection Jun 25 09:33:12 raspbmusic volumio[921]: info: MPD Permissions set Jun 25 09:33:12 raspbmusic volumio[921]: info: MPD Permissions set Jun 25 09:33:12 raspbmusic volumio-remote-updater[561]: [2025-06-25 09:33:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1750833190 101 Jun 25 09:33:12 raspbmusic volumio[921]: 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: 11 Jun 25 09:33:12 raspbmusic volumio[921]: info: Spotify config file written Jun 25 09:33:12 raspbmusic sudo[1296]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 25 09:33:12 raspbmusic sudo[1296]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 25 09:33:12 raspbmusic 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. Jun 25 09:33:12 raspbmusic 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. Jun 25 09:33:12 raspbmusic systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 25 09:33:12 raspbmusic go-librespot[1298]: go-librespot daemon starting... Jun 25 09:33:12 raspbmusic sudo[1296]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:13 raspbmusic volumio[921]: verbose: New Socket.io Connection to 192.168.33.60 from 192.168.33.178 UA: Mozilla/5.0 (X11; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 12 Jun 25 09:33:13 raspbmusic volumio[921]: info: Volumio called home Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:13 raspbmusic volumio[921]: info: No need to fix Spotify hosts Jun 25 09:33:13 raspbmusic go-librespot[1299]: time="2025-06-25T09:33:13+03:00" level=info msg="running go-librespot 0.2.0" Jun 25 09:33:13 raspbmusic go-librespot[1299]: time="2025-06-25T09:33:13+03:00" level=debug msg="app state loaded" Jun 25 09:33:13 raspbmusic go-librespot[1299]: time="2025-06-25T09:33:13+03:00" level=debug msg="stored credentials not found" Jun 25 09:33:13 raspbmusic go-librespot[1299]: time="2025-06-25T09:33:13+03:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 25 09:33:14 raspbmusic volumio[921]: verbose: New Socket.io Connection to 192.168.33.60 from 192.168.35.145 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 13 Jun 25 09:33:14 raspbmusic go-librespot[1299]: time="2025-06-25T09:33:14+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jun 25 09:33:14 raspbmusic go-librespot[1299]: time="2025-06-25T09:33:14+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 25 09:33:14 raspbmusic go-librespot[1299]: time="2025-06-25T09:33:14+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 25 09:33:14 raspbmusic go-librespot[1299]: time="2025-06-25T09:33:14+03:00" level=info msg="zeroconf server listening on port 36343" Jun 25 09:33:14 raspbmusic volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jun 25 09:33:14 raspbmusic volumio[921]: verbose: New Socket.io Connection to 192.168.33.60 from 192.168.33.178 UA: Mozilla/5.0 (X11; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 15 Jun 25 09:33:14 raspbmusic volumio[921]: info: Starting Shairport Sync Jun 25 09:33:14 raspbmusic volumio[921]: info: Starting Shairport Sync Jun 25 09:33:14 raspbmusic sudo[1333]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 25 09:33:14 raspbmusic sudo[1333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 25 09:33:14 raspbmusic volumio[921]: info: Starting Shairport Sync Jun 25 09:33:14 raspbmusic systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 25 09:33:14 raspbmusic systemd[1]: shairport-sync.service: Deactivated successfully. Jun 25 09:33:14 raspbmusic systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 25 09:33:14 raspbmusic systemd[1]: shairport-sync.service: Consumed 2.320s CPU time. Jun 25 09:33:14 raspbmusic sudo[1338]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 25 09:33:14 raspbmusic sudo[1336]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 25 09:33:14 raspbmusic sudo[1336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 25 09:33:14 raspbmusic sudo[1338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 25 09:33:14 raspbmusic systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 25 09:33:14 raspbmusic sudo[1333]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:14 raspbmusic systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 25 09:33:14 raspbmusic systemd[1]: shairport-sync.service: Deactivated successfully. Jun 25 09:33:14 raspbmusic systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 25 09:33:14 raspbmusic systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 25 09:33:14 raspbmusic sudo[1336]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:14 raspbmusic systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 25 09:33:14 raspbmusic systemd[1]: shairport-sync.service: Deactivated successfully. Jun 25 09:33:14 raspbmusic systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 25 09:33:14 raspbmusic systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 25 09:33:14 raspbmusic sudo[1338]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:15 raspbmusic volumio[921]: info: Shairport-Sync Started Jun 25 09:33:15 raspbmusic volumio[921]: Error adding Membership: Error: addMembership EINVAL Jun 25 09:33:15 raspbmusic volumio[921]: info: Shairport-Sync Started Jun 25 09:33:15 raspbmusic mpd[1252]: 2025-06-25T09:33:15 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 25 09:33:15 raspbmusic volumio[921]: verbose: New Socket.io Connection to 192.168.33.60 from 192.168.35.145 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 16 Jun 25 09:33:15 raspbmusic volumio[921]: info: Shairport-Sync Started Jun 25 09:33:15 raspbmusic systemd[1]: Started mpd.service - Music Player Daemon. Jun 25 09:33:15 raspbmusic sudo[1229]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:15 raspbmusic sudo[1240]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:15 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 25 09:33:15 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:33:15 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:15 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetVisibleSources Jun 25 09:33:15 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 25 09:33:15 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:33:15 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:15 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:33:15 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:15 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 25 09:33:15 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 25 09:33:15 raspbmusic volumio[921]: info: Received Get System Info Jun 25 09:33:15 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 09:33:15 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 09:33:15 raspbmusic volumio[921]: info: Discovery: Getting this device information Jun 25 09:33:15 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:33:15 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:15 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 09:33:15 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:33:16 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:16 raspbmusic volumio[921]: info: Listing playlists Jun 25 09:33:16 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetQueue Jun 25 09:33:16 raspbmusic volumio[921]: info: CoreStateMachine::getQueue Jun 25 09:33:16 raspbmusic volumio[921]: info: CorePlayQueue::getQueue Jun 25 09:33:16 raspbmusic volumio[921]: error: MPD error: The expression evaluated to a falsy value: Jun 25 09:33:16 raspbmusic volumio[921]: assert.ok(self.idling) Jun 25 09:33:16 raspbmusic volumio[921]: error: The expression evaluated to a falsy value: Jun 25 09:33:16 raspbmusic volumio[921]: assert.ok(self.idling) Jun 25 09:33:16 raspbmusic volumio[921]: info: MPD running with PID1252 Jun 25 09:33:16 raspbmusic volumio[921]: ,establishing connection Jun 25 09:33:16 raspbmusic volumio[921]: error: updateQueue error: null Jun 25 09:33:17 raspbmusic volumio[921]: error: updateQueue error: null Jun 25 09:33:17 raspbmusic volumio[921]: info: go-librespot daemon successfully initialized Jun 25 09:33:17 raspbmusic volumio[921]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jun 25 09:33:17 raspbmusic volumio[921]: info: CoreCommandRouter::volumioRetrievevolume Jun 25 09:33:18 raspbmusic volumio[921]: verbose: New Socket.io Connection to 192.168.33.60 from 192.168.33.178 UA: Mozilla/5.0 (X11; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 17 Jun 25 09:33:18 raspbmusic volumio[921]: info: VolumeController:: Volume=100 Mute =false Jun 25 09:33:18 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:33:18 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:18 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:33:18 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:18 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:33:18 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 25 09:33:18 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetVisibleSources Jun 25 09:33:18 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 25 09:33:18 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:33:18 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:18 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:33:18 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:18 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 25 09:33:18 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 25 09:33:18 raspbmusic volumio[921]: info: Received Get System Info Jun 25 09:33:18 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 09:33:18 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 09:33:18 raspbmusic volumio[921]: info: Discovery: Getting this device information Jun 25 09:33:18 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:33:18 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:18 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 09:33:18 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:33:18 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:18 raspbmusic volumio[921]: info: Listing playlists Jun 25 09:33:18 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetQueue Jun 25 09:33:18 raspbmusic volumio[921]: info: CoreStateMachine::getQueue Jun 25 09:33:18 raspbmusic volumio[921]: info: CorePlayQueue::getQueue Jun 25 09:33:20 raspbmusic volumio[921]: info: Initializing connection to go-librespot Websocket Jun 25 09:33:20 raspbmusic volumio[921]: info: Completed starting Core Plugins Jun 25 09:33:20 raspbmusic volumio[921]: info: ------------------------------------------- Jun 25 09:33:20 raspbmusic volumio[921]: info: ----- MyVolumio plugins startup ---- Jun 25 09:33:20 raspbmusic volumio[921]: info: ------------------------------------------- Jun 25 09:33:20 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 25 09:33:20 raspbmusic go-librespot[1299]: time="2025-06-25T09:33:20+03:00" level=debug msg="new websocket client" Jun 25 09:33:20 raspbmusic volumio[921]: info: Connection to go-librespot Websocket established Jun 25 09:33:21 raspbmusic volumio[921]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 25 09:33:23 raspbmusic volumio[921]: info: Getting Spotify volume Jun 25 09:33:23 raspbmusic volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Jun 25 09:33:24 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:33:24 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:24 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:33:24 raspbmusic volumio[921]: SPOTIFY: SPOTIFY VOLUME undefined Jun 25 09:33:24 raspbmusic volumio[921]: SPOTIFY: VOLUMIO VOLUME 100 Jun 25 09:33:24 raspbmusic volumio[921]: info: Aligning Spotify Volume to Volumio Volume Jun 25 09:33:24 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:33:24 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:24 raspbmusic volumio[921]: info: Setting Spotify Volume from Volumio: 100 Jun 25 09:33:25 raspbmusic volumio[921]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Jun 25 09:33:25 raspbmusic volumio[921]: info: Sending Spotify command with payload to local API: /player/volume Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 25 09:33:28 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 25 09:33:29 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 25 09:33:29 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 25 09:33:29 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 25 09:33:29 raspbmusic volumio[921]: info: Adding plugin bluetooth to MyMusic Plugins Jun 25 09:33:29 raspbmusic volumio[921]: info: Adding plugin multiroom to MyMusic Plugins Jun 25 09:33:29 raspbmusic volumio[921]: info: Adding plugin metavolumio to MyMusic Plugins Jun 25 09:33:29 raspbmusic volumio[921]: info: Adding plugin cd_controller to MyMusic Plugins Jun 25 09:33:29 raspbmusic volumio[921]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 25 09:33:29 raspbmusic volumio[921]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 25 09:33:29 raspbmusic volumio[921]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 25 09:33:29 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 25 09:33:31 raspbmusic volumio[921]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 25 09:33:31 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 25 09:33:31 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:31 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:31 raspbmusic volumio[921]: info: Starting MyVolumio Remote Streaming Endpoints Jun 25 09:33:31 raspbmusic volumio[921]: info: MyVolumio login type: Token Jun 25 09:33:31 raspbmusic volumio[921]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 25 09:33:31 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 25 09:33:34 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 25 09:33:34 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 25 09:33:34 raspbmusic volumio[921]: info: Streaming services startup Jun 25 09:33:34 raspbmusic volumio[921]: info: Starting Streaming Daemon Jun 25 09:33:34 raspbmusic volumio[921]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 25 09:33:34 raspbmusic sudo[1403]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 25 09:33:34 raspbmusic sudo[1403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 25 09:33:35 raspbmusic sudo[1403]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:35 raspbmusic volumio[921]: error: Cannot start Volumio Streaming Daemon Jun 25 09:33:35 raspbmusic volumio[921]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 25 09:33:35 raspbmusic volumio[921]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 25 09:33:36 raspbmusic volumio[921]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 25 09:33:38 raspbmusic volumio[921]: info: MyVolumio token set successfully Jun 25 09:33:38 raspbmusic volumio[921]: info: MYVOLUMIO: Adding device Jun 25 09:33:38 raspbmusic volumio[921]: info: MYVOLUMIO: Evaluating Server Jun 25 09:33:39 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 25 09:33:39 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:39 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 25 09:33:39 raspbmusic volumio[921]: info: MyVolumio status changed Jun 25 09:33:39 raspbmusic volumio[921]: info: Streaming services startup Jun 25 09:33:39 raspbmusic volumio[921]: info: Starting Streaming Daemon Jun 25 09:33:39 raspbmusic volumio[921]: info: Removing browser output: myVolumio user plan is not superstar Jun 25 09:33:39 raspbmusic volumio[921]: info: Removing audio output: Jun 25 09:33:39 raspbmusic volumio[921]: info: Stoppping Tunnel 1 Jun 25 09:33:39 raspbmusic sudo[1427]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 25 09:33:39 raspbmusic sudo[1427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 25 09:33:39 raspbmusic sudo[1429]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 25 09:33:39 raspbmusic systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 25 09:33:39 raspbmusic sudo[1429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 25 09:33:39 raspbmusic sudo[1427]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:39 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 25 09:33:39 raspbmusic volumio-remote-updater[561]: Test mode enabled Jun 25 09:33:39 raspbmusic volumio-remote-updater[561]: Alpha mode disabled Jun 25 09:33:39 raspbmusic volumio-remote-updater[561]: Alpha legacy test mode disabled Jun 25 09:33:39 raspbmusic dbus-daemon[543]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.19' (uid=0 pid=1433 comm="timedatectl show --property=NTPSynchronized --valu") Jun 25 09:33:39 raspbmusic volumio[921]: info: Setting Geolocation for MyVolumio to eu6 Jun 25 09:33:39 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:39 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:39 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:39 raspbmusic systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 25 09:33:39 raspbmusic 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. Jun 25 09:33:39 raspbmusic 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. Jun 25 09:33:39 raspbmusic volumio[921]: error: Cannot start Volumio Streaming Daemon Jun 25 09:33:39 raspbmusic volumio[921]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 25 09:33:39 raspbmusic volumio[921]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 25 09:33:39 raspbmusic 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. Jun 25 09:33:39 raspbmusic 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. Jun 25 09:33:39 raspbmusic 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. Jun 25 09:33:39 raspbmusic 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. Jun 25 09:33:39 raspbmusic 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. Jun 25 09:33:39 raspbmusic 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. Jun 25 09:33:39 raspbmusic sudo[1429]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:39 raspbmusic volumio[921]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 25 09:33:39 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 25 09:33:39 raspbmusic volumio[921]: info: Remote SSH Stopped Jun 25 09:33:39 raspbmusic dbus-daemon[543]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 25 09:33:39 raspbmusic systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 25 09:33:39 raspbmusic setdatetime-helper.sh[1431]: Time is already synchronized. Jun 25 09:33:39 raspbmusic systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 25 09:33:39 raspbmusic systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 25 09:33:39 raspbmusic volumio[921]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 25 09:33:40 raspbmusic volumio[921]: info: Updating MyVolumio device info Jun 25 09:33:40 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:40 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:40 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:40 raspbmusic volumio[921]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 25 09:33:43 raspbmusic volumio[921]: info: MYVOLUMIO: Adding device Jun 25 09:33:43 raspbmusic volumio[921]: info: MYVOLUMIO: Evaluating Server Jun 25 09:33:45 raspbmusic volumio[921]: info: Setting Geolocation for MyVolumio to eu4 Jun 25 09:33:45 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:45 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:45 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:45 raspbmusic volumio[921]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 25 09:33:46 raspbmusic volumio[921]: info: Updating MyVolumio device info Jun 25 09:33:46 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:46 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:46 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:46 raspbmusic volumio[921]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 25 09:33:46 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 25 09:33:47 raspbmusic volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Jun 25 09:33:47 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:33:47 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:51 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:51 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:51 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 25 09:33:51 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 25 09:33:51 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 25 09:33:51 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetBrowseSources Jun 25 09:33:51 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 25 09:33:54 raspbmusic upmpdcli[1470]: writing RSA key Jun 25 09:33:54 raspbmusic volumio[921]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 25 09:33:54 raspbmusic volumio[921]: info: Completed starting MyVolumio Plugin Jun 25 09:33:54 raspbmusic volumio[921]: [Metrics] CommandRouter: 75s 46.10ms Jun 25 09:33:54 raspbmusic volumio[921]: info: CoreCommandRouter::volumiosetStartupVolume Jun 25 09:33:54 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 09:33:54 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 09:33:54 raspbmusic volumio[921]: info: CoreCommandRouter::Close All Modals sent Jun 25 09:33:54 raspbmusic volumio[921]: info: CoreCommandRouter::Close All Modals sent Jun 25 09:33:55 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 25 09:33:55 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 25 09:33:55 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 25 09:33:55 raspbmusic volumio[921]: info: Version has changed, forcing UI Reload Jun 25 09:33:55 raspbmusic volumio[921]: info: CoreCommandRouter::Reload Ui Jun 25 09:33:59 raspbmusic sudo[1497]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 25 09:33:59 raspbmusic sudo[1495]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 25 09:33:59 raspbmusic sudo[1495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 25 09:33:59 raspbmusic sudo[1497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 25 09:33:59 raspbmusic sudo[1495]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:59 raspbmusic sudo[1497]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:59 raspbmusic sudo[1504]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 25 09:33:59 raspbmusic sudo[1504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 25 09:33:59 raspbmusic sudo[1504]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:59 raspbmusic sudo[1501]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 25 09:33:59 raspbmusic sudo[1501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 25 09:33:59 raspbmusic volumio[921]: verbose: New Socket.io Connection to 192.168.33.60 from 192.168.33.178 UA: Mozilla/5.0 (X11; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 18 Jun 25 09:33:59 raspbmusic sudo[1501]: pam_unix(sudo:session): session closed for user root Jun 25 09:33:59 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 25 09:33:59 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 25 09:33:59 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetVisibleSources Jun 25 09:33:59 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 25 09:33:59 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:33:59 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:59 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 25 09:33:59 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 25 09:33:59 raspbmusic volumio[921]: info: Received Get System Info Jun 25 09:33:59 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 09:33:59 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 09:33:59 raspbmusic volumio[921]: info: Discovery: Getting this device information Jun 25 09:33:59 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:33:59 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:59 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 09:33:59 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:33:59 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:33:59 raspbmusic volumio[921]: info: Listing playlists Jun 25 09:33:59 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 25 09:33:59 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 25 09:34:00 raspbmusic volumio[921]: verbose: New Socket.io Connection to 192.168.33.60 from 192.168.35.145 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 19 Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:34:00 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetVisibleSources Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:34:00 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 25 09:34:00 raspbmusic volumio[921]: info: Received Get System Info Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 09:34:00 raspbmusic volumio[921]: info: Discovery: Getting this device information Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:34:00 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:34:00 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:00 raspbmusic volumio[921]: info: Listing playlists Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jun 25 09:34:00 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 25 09:34:00 raspbmusic volumio[921]: info: Received Get System Info Jun 25 09:34:01 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 09:34:01 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 09:34:01 raspbmusic volumio[921]: info: Discovery: Getting this device information Jun 25 09:34:01 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:34:01 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:01 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 09:34:01 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 25 09:34:01 raspbmusic volumio[921]: info: Received Get System Version Jun 25 09:34:01 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 25 09:34:01 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 25 09:34:01 raspbmusic volumio[921]: info: BOOT COMPLETED Jun 25 09:34:02 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 25 09:34:03 raspbmusic volumio[921]: info: Received Get System Info Jun 25 09:34:03 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 09:34:03 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 09:34:03 raspbmusic volumio[921]: info: Discovery: Getting this device information Jun 25 09:34:03 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:34:03 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:03 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 09:34:03 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 25 09:34:03 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 25 09:34:03 raspbmusic volumio[921]: info: Received Get System Info Jun 25 09:34:03 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 09:34:03 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 09:34:03 raspbmusic volumio[921]: info: Discovery: Getting this device information Jun 25 09:34:03 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:34:03 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:03 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 09:34:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 25 09:34:04 raspbmusic volumio[921]: info: Received Get System Info Jun 25 09:34:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 09:34:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 09:34:04 raspbmusic volumio[921]: info: Discovery: Getting this device information Jun 25 09:34:04 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:34:04 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 09:34:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 25 09:34:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 25 09:34:04 raspbmusic volumio[921]: info: Received Get System Info Jun 25 09:34:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 09:34:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 09:34:04 raspbmusic volumio[921]: info: Discovery: Getting this device information Jun 25 09:34:04 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:34:04 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:04 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 09:34:05 raspbmusic volumio[921]: info: Received Get System Info Jun 25 09:34:05 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 09:34:05 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 09:34:05 raspbmusic volumio[921]: info: Discovery: Getting this device information Jun 25 09:34:05 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:34:05 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:05 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 09:34:09 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 25 09:34:09 raspbmusic systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 25 09:34:10 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 25 09:34:17 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 25 09:34:17 raspbmusic volumio[921]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 25 09:34:17 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 25 09:34:17 raspbmusic volumio[921]: info: Received Get System Version Jun 25 09:34:17 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 25 09:34:17 raspbmusic volumio[921]: info: Received Get System Info Jun 25 09:34:17 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 09:34:17 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 09:34:17 raspbmusic volumio[921]: info: Discovery: Getting this device information Jun 25 09:34:17 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:34:17 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:17 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 09:34:18 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 25 09:34:19 raspbmusic volumio[921]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 25 09:34:19 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 25 09:34:19 raspbmusic volumio[921]: info: Received Get System Version Jun 25 09:34:19 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 25 09:34:19 raspbmusic volumio[921]: info: Received Get System Info Jun 25 09:34:19 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 09:34:19 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 09:34:19 raspbmusic volumio[921]: info: Discovery: Getting this device information Jun 25 09:34:19 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:34:19 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:19 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 09:34:42 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPlay Jun 25 09:34:42 raspbmusic volumio[921]: info: CoreStateMachine::play index undefined Jun 25 09:34:42 raspbmusic volumio[921]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 25 09:34:42 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:42 raspbmusic volumio[921]: info: CoreStateMachine::startPlaybackTimer Jun 25 09:34:42 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:42 raspbmusic volumio[921]: info: [1750833282878] ControllerWebradio::clearAddPlayTrack Jun 25 09:34:42 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand stop Jun 25 09:34:42 raspbmusic volumio[921]: info: sendMpdCommand stop took 13 milliseconds Jun 25 09:34:42 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand clear Jun 25 09:34:42 raspbmusic volumio[921]: info: Jun 25 09:34:42 raspbmusic volumio[921]: ---------------------------- MPD announces system playlist update Jun 25 09:34:42 raspbmusic volumio[921]: info: Ignoring MPD Status Update Jun 25 09:34:42 raspbmusic volumio[921]: info: sendMpdCommand clear took 29 milliseconds Jun 25 09:34:42 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand load "https://drumandbassfm.stream.laut.fm/drumandbass_fm?ref=radiodns" Jun 25 09:34:42 raspbmusic volumio[921]: info: Jun 25 09:34:42 raspbmusic volumio[921]: ---------------------------- MPD announces system playlist update Jun 25 09:34:42 raspbmusic volumio[921]: info: Ignoring MPD Status Update Jun 25 09:34:42 raspbmusic volumio[921]: info: Jun 25 09:34:42 raspbmusic volumio[921]: ---------------------------- MPD announces system playlist update Jun 25 09:34:42 raspbmusic volumio[921]: info: Ignoring MPD Status Update Jun 25 09:34:42 raspbmusic volumio[921]: error: updateQueue error: null Jun 25 09:34:43 raspbmusic volumio[921]: info: ------------------------------ 66ms Jun 25 09:34:43 raspbmusic volumio[921]: error: updateQueue error: null Jun 25 09:34:43 raspbmusic volumio[921]: error: updateQueue error: null Jun 25 09:34:43 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand add "https://drumandbassfm.stream.laut.fm/drumandbass_fm?ref=radiodns" Jun 25 09:34:43 raspbmusic volumio[921]: info: ------------------------------ 1009ms Jun 25 09:34:43 raspbmusic volumio[921]: info: ------------------------------ 1003ms Jun 25 09:34:44 raspbmusic volumio[921]: info: Jun 25 09:34:44 raspbmusic volumio[921]: ---------------------------- MPD announces system playlist update Jun 25 09:34:44 raspbmusic volumio[921]: info: Ignoring MPD Status Update Jun 25 09:34:44 raspbmusic volumio[921]: info: sendMpdCommand add "https://drumandbassfm.stream.laut.fm/drumandbass_fm?ref=radiodns" took 33 milliseconds Jun 25 09:34:44 raspbmusic volumio[921]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 25 09:34:44 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand play Jun 25 09:34:44 raspbmusic volumio[921]: info: Jun 25 09:34:44 raspbmusic volumio[921]: ---------------------------- MPD announces system playlist update Jun 25 09:34:44 raspbmusic volumio[921]: info: Ignoring MPD Status Update Jun 25 09:34:44 raspbmusic volumio[921]: info: Jun 25 09:34:44 raspbmusic volumio[921]: ---------------------------- MPD announces system playlist update Jun 25 09:34:44 raspbmusic volumio[921]: info: Ignoring MPD Status Update Jun 25 09:34:44 raspbmusic volumio[921]: info: ------------------------------ 47ms Jun 25 09:34:44 raspbmusic volumio[921]: info: sendMpdCommand play took 46 milliseconds Jun 25 09:34:44 raspbmusic volumio[921]: info: ------------------------------ 45ms Jun 25 09:34:44 raspbmusic volumio[921]: info: ------------------------------ 43ms Jun 25 09:34:44 raspbmusic volumio[921]: info: Jun 25 09:34:44 raspbmusic volumio[921]: ---------------------------- MPD announces state update: player Jun 25 09:34:44 raspbmusic volumio[921]: info: ControllerMpd::getState Jun 25 09:34:44 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jun 25 09:34:44 raspbmusic volumio[921]: info: Jun 25 09:34:44 raspbmusic volumio[921]: ---------------------------- MPD announces state update: player Jun 25 09:34:44 raspbmusic volumio[921]: info: ControllerMpd::getState Jun 25 09:34:44 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jun 25 09:34:44 raspbmusic volumio[921]: info: Jun 25 09:34:44 raspbmusic volumio[921]: ---------------------------- MPD announces state update: player Jun 25 09:34:44 raspbmusic volumio[921]: info: Jun 25 09:34:44 raspbmusic volumio[921]: ---------------------------- MPD announces state update: player Jun 25 09:34:44 raspbmusic volumio[921]: info: ControllerMpd::getState Jun 25 09:34:44 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jun 25 09:34:44 raspbmusic volumio[921]: info: sendMpdCommand status took 104 milliseconds Jun 25 09:34:44 raspbmusic volumio[921]: info: ControllerMpd::getState Jun 25 09:34:44 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jun 25 09:34:44 raspbmusic volumio[921]: verbose: ControllerMpd::parseState Jun 25 09:34:44 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 09:34:45 raspbmusic volumio[921]: info: Jun 25 09:34:45 raspbmusic volumio[921]: ---------------------------- MPD announces state update: player Jun 25 09:34:45 raspbmusic volumio[921]: info: ControllerMpd::getState Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jun 25 09:34:45 raspbmusic volumio[921]: info: Jun 25 09:34:45 raspbmusic volumio[921]: ---------------------------- MPD announces state update: player Jun 25 09:34:45 raspbmusic volumio[921]: info: ControllerMpd::getState Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jun 25 09:34:45 raspbmusic volumio[921]: info: Jun 25 09:34:45 raspbmusic volumio[921]: ---------------------------- MPD announces system playlist update Jun 25 09:34:45 raspbmusic volumio[921]: info: Ignoring MPD Status Update Jun 25 09:34:45 raspbmusic volumio[921]: info: Jun 25 09:34:45 raspbmusic volumio[921]: ---------------------------- MPD announces state update: player Jun 25 09:34:45 raspbmusic volumio[921]: info: Jun 25 09:34:45 raspbmusic volumio[921]: ---------------------------- MPD announces system playlist update Jun 25 09:34:45 raspbmusic volumio[921]: info: Ignoring MPD Status Update Jun 25 09:34:45 raspbmusic volumio[921]: info: Jun 25 09:34:45 raspbmusic volumio[921]: ---------------------------- MPD announces state update: player Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand status took 160 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: info: ControllerMpd::getState Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand status took 80 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: info: ControllerMpd::getState Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand status took 62 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand playlistinfo took 59 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand status took 19 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand status took 21 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseState Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseState Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseState Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseTrackInfo Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseState Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseState Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 09:34:45 raspbmusic volumio[921]: info: ControllerMpd::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::servicePushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:45 raspbmusic volumio[921]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fred V & Grafix feat. Reija Lee - Just A Thought (Vocal Mix)","artist":"Drumandbass FM","album":null,"uri":"https://drumandbassfm.stream.laut.fm/drumandbass_fm?ref=radiodns","trackType":""} Jun 25 09:34:45 raspbmusic volumio[921]: verbose: CURRENT POSITION 0 Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState stateService play Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jun 25 09:34:45 raspbmusic volumio[921]: info: ------------------------------ 199ms Jun 25 09:34:45 raspbmusic volumio[921]: info: Jun 25 09:34:45 raspbmusic volumio[921]: ---------------------------- MPD announces system playlist update Jun 25 09:34:45 raspbmusic volumio[921]: info: Ignoring MPD Status Update Jun 25 09:34:45 raspbmusic volumio[921]: info: Jun 25 09:34:45 raspbmusic volumio[921]: ---------------------------- MPD announces state update: player Jun 25 09:34:45 raspbmusic volumio[921]: info: ControllerMpd::getState Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jun 25 09:34:45 raspbmusic volumio[921]: info: Jun 25 09:34:45 raspbmusic volumio[921]: ---------------------------- MPD announces system playlist update Jun 25 09:34:45 raspbmusic volumio[921]: info: Ignoring MPD Status Update Jun 25 09:34:45 raspbmusic volumio[921]: info: Jun 25 09:34:45 raspbmusic volumio[921]: ---------------------------- MPD announces state update: player Jun 25 09:34:45 raspbmusic volumio[921]: info: ControllerMpd::getState Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jun 25 09:34:45 raspbmusic volumio[921]: info: ------------------------------ 66ms Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand status took 53 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: info: ------------------------------ 59ms Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand status took 53 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand playlistinfo took 48 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand playlistinfo took 47 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand playlistinfo took 46 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand playlistinfo took 44 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand playlistinfo took 43 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: info: ------------------------------ 31ms Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand status took 21 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: info: ------------------------------ 20ms Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand status took 19 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseState Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseState Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseTrackInfo Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseTrackInfo Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseTrackInfo Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseTrackInfo Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseTrackInfo Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseState Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseState Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 09:34:45 raspbmusic volumio[921]: info: ControllerMpd::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::servicePushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:45 raspbmusic volumio[921]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fred V & Grafix feat. Reija Lee - Just A Thought (Vocal Mix)","artist":"Drumandbass FM","album":null,"uri":"https://drumandbassfm.stream.laut.fm/drumandbass_fm?ref=radiodns","trackType":""} Jun 25 09:34:45 raspbmusic volumio[921]: verbose: CURRENT POSITION 0 Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState stateService play Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState currentStatus play Jun 25 09:34:45 raspbmusic volumio[921]: info: Received an update from plugin. extracting info from payload Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:34:45 raspbmusic volumio[921]: info: ControllerMpd::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::servicePushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:45 raspbmusic volumio[921]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fred V & Grafix feat. Reija Lee - Just A Thought (Vocal Mix)","artist":"Drumandbass FM","album":null,"uri":"https://drumandbassfm.stream.laut.fm/drumandbass_fm?ref=radiodns","trackType":""} Jun 25 09:34:45 raspbmusic volumio[921]: verbose: CURRENT POSITION 0 Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState stateService play Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState currentStatus play Jun 25 09:34:45 raspbmusic volumio[921]: info: Received an update from plugin. extracting info from payload Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:34:45 raspbmusic volumio[921]: info: ControllerMpd::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::servicePushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:45 raspbmusic volumio[921]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fred V & Grafix feat. Reija Lee - Just A Thought (Vocal Mix)","artist":"Drumandbass FM","album":null,"uri":"https://drumandbassfm.stream.laut.fm/drumandbass_fm?ref=radiodns","trackType":""} Jun 25 09:34:45 raspbmusic volumio[921]: verbose: CURRENT POSITION 0 Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState stateService play Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState currentStatus play Jun 25 09:34:45 raspbmusic volumio[921]: info: Received an update from plugin. extracting info from payload Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:34:45 raspbmusic volumio[921]: info: ControllerMpd::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::servicePushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:45 raspbmusic volumio[921]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fred V & Grafix feat. Reija Lee - Just A Thought (Vocal Mix)","artist":"Drumandbass FM","album":null,"uri":"https://drumandbassfm.stream.laut.fm/drumandbass_fm?ref=radiodns","trackType":""} Jun 25 09:34:45 raspbmusic volumio[921]: verbose: CURRENT POSITION 0 Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState stateService play Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState currentStatus play Jun 25 09:34:45 raspbmusic volumio[921]: info: Received an update from plugin. extracting info from payload Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:34:45 raspbmusic volumio[921]: info: ControllerMpd::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::servicePushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:45 raspbmusic volumio[921]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fred V & Grafix feat. Reija Lee - Just A Thought (Vocal Mix)","artist":"Drumandbass FM","album":null,"uri":"https://drumandbassfm.stream.laut.fm/drumandbass_fm?ref=radiodns","trackType":""} Jun 25 09:34:45 raspbmusic volumio[921]: verbose: CURRENT POSITION 0 Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState stateService play Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState currentStatus play Jun 25 09:34:45 raspbmusic volumio[921]: info: Received an update from plugin. extracting info from payload Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:34:45 raspbmusic volumio[921]: info: ------------------------------ 605ms Jun 25 09:34:45 raspbmusic volumio[921]: info: ------------------------------ 547ms Jun 25 09:34:45 raspbmusic volumio[921]: info: ------------------------------ 543ms Jun 25 09:34:45 raspbmusic volumio[921]: info: ------------------------------ 460ms Jun 25 09:34:45 raspbmusic volumio[921]: info: ------------------------------ 459ms Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand playlistinfo took 393 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand playlistinfo took 393 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand playlistinfo took 390 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: info: sendMpdCommand playlistinfo took 390 milliseconds Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseTrackInfo Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseTrackInfo Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseTrackInfo Jun 25 09:34:45 raspbmusic volumio[921]: verbose: ControllerMpd::parseTrackInfo Jun 25 09:34:45 raspbmusic volumio[921]: info: ControllerMpd::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::servicePushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:45 raspbmusic volumio[921]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1112,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fred V & Grafix feat. Reija Lee - Just A Thought (Vocal Mix)","artist":"Drumandbass FM","album":null,"uri":"https://drumandbassfm.stream.laut.fm/drumandbass_fm?ref=radiodns","trackType":""} Jun 25 09:34:45 raspbmusic volumio[921]: verbose: CURRENT POSITION 0 Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState stateService play Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState currentStatus play Jun 25 09:34:45 raspbmusic volumio[921]: info: Received an update from plugin. extracting info from payload Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:34:45 raspbmusic volumio[921]: info: ControllerMpd::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::servicePushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:45 raspbmusic volumio[921]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1112,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fred V & Grafix feat. Reija Lee - Just A Thought (Vocal Mix)","artist":"Drumandbass FM","album":null,"uri":"https://drumandbassfm.stream.laut.fm/drumandbass_fm?ref=radiodns","trackType":""} Jun 25 09:34:45 raspbmusic volumio[921]: verbose: CURRENT POSITION 0 Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState stateService play Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState currentStatus play Jun 25 09:34:45 raspbmusic volumio[921]: info: Received an update from plugin. extracting info from payload Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:34:45 raspbmusic volumio[921]: info: ControllerMpd::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::servicePushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:45 raspbmusic volumio[921]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1112,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fred V & Grafix feat. Reija Lee - Just A Thought (Vocal Mix)","artist":"Drumandbass FM","album":null,"uri":"https://drumandbassfm.stream.laut.fm/drumandbass_fm?ref=radiodns","trackType":""} Jun 25 09:34:45 raspbmusic volumio[921]: verbose: CURRENT POSITION 0 Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState stateService play Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState currentStatus play Jun 25 09:34:45 raspbmusic volumio[921]: info: Received an update from plugin. extracting info from payload Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:34:45 raspbmusic volumio[921]: info: ControllerMpd::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::servicePushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CorePlayQueue::getTrack 0 Jun 25 09:34:45 raspbmusic volumio[921]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1112,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fred V & Grafix feat. Reija Lee - Just A Thought (Vocal Mix)","artist":"Drumandbass FM","album":null,"uri":"https://drumandbassfm.stream.laut.fm/drumandbass_fm?ref=radiodns","trackType":""} Jun 25 09:34:45 raspbmusic volumio[921]: verbose: CURRENT POSITION 0 Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState stateService play Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::syncState currentStatus play Jun 25 09:34:45 raspbmusic volumio[921]: info: Received an update from plugin. extracting info from payload Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreStateMachine::pushState Jun 25 09:34:45 raspbmusic volumio[921]: info: CoreCommandRouter::volumioPushState Jun 25 09:34:45 raspbmusic volumio[921]: info: ------------------------------ 794ms Jun 25 09:34:45 raspbmusic volumio[921]: info: ------------------------------ 794ms Jun 25 09:34:45 raspbmusic volumio[921]: info: ------------------------------ 744ms Jun 25 09:34:45 raspbmusic volumio[921]: info: ------------------------------ 742ms Jun 25 09:34:45 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:34:45 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:34:45 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:34:45 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:34:45 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:34:45 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:34:45 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:34:45 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:34:45 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:34:45 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:34:45 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:34:45 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:34:45 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:34:45 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:34:45 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:34:45 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:34:45 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:34:45 raspbmusic volumio[921]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 25 09:34:46 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetState Jun 25 09:34:47 raspbmusic volumio[921]: info: CoreCommandRouter::volumioGetQueue Jun 25 09:34:47 raspbmusic volumio[921]: info: CoreStateMachine::getQueue Jun 25 09:34:47 raspbmusic volumio[921]: info: CorePlayQueue::getQueue Jun 25 09:34:52 raspbmusic volumio[921]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 25 09:34:53 raspbmusic volumio[921]: Use XMLElement.setAttribute instead Jun 25 09:34:53 raspbmusic volumio[921]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 25 09:34:53 raspbmusic volumio[921]: TypeError: children[i].attr(...).value is not a function Jun 25 09:34:53 raspbmusic volumio[921]: at /volumio/app/plugins/music_service/webradio/index.js:428:46 Jun 25 09:34:53 raspbmusic volumio[921]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 25 09:34:53 raspbmusic volumio[921]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 25 09:34:55 raspbmusic sudo[1662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-25 09:33' Jun 25 09:34:55 raspbmusic sudo[1662]: 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="cee9bb5b0ee50e4898478097a64b5c6c77d5a212" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="b0310d60a07948a2a8a2e8db322034758c86aad6" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 21 15:01:23 UTC 2025" VOLUMIO_VERSION="4.013" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6df2d5f0b86e6f2c0cd4dc68c66de48c"