Jun 14 23:10:00 volumiozonder wpa_supplicant[1207]: RRM: Ignoring radio measurement request: Not RRM network Jun 14 23:10:01 volumiozonder volumio[13688]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:10:01 volumiozonder volumio[13688]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:10:01 volumiozonder volumio[13688]: info: Discovery: Getting this device information Jun 14 23:10:01 volumiozonder volumio[13688]: info: CoreCommandRouter::volumioGetState Jun 14 23:10:01 volumiozonder volumio[13688]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:10:06 volumiozonder wpa_supplicant[1207]: RRM: Ignoring radio measurement request: Not RRM network Jun 14 23:10:12 volumiozonder wpa_supplicant[1207]: RRM: Ignoring radio measurement request: Not RRM network Jun 14 23:10:13 volumiozonder volumio[13688]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 14 23:10:14 volumiozonder volumio[13688]: Use XMLElement.setAttribute instead Jun 14 23:10:14 volumiozonder volumio[13688]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 23:10:14 volumiozonder volumio[13688]: TypeError: children[i].attr(...).value is not a function Jun 14 23:10:14 volumiozonder volumio[13688]: at /volumio/app/plugins/music_service/webradio/index.js:428:46 Jun 14 23:10:14 volumiozonder volumio[13688]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 14 23:10:14 volumiozonder volumio[13688]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 23:10:14 volumiozonder sudo[15359]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-14 23:09' Jun 14 23:10:14 volumiozonder sudo[15359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:10:14 volumiozonder sudo[15359]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:14 volumiozonder volumio-remote-updater[918]: [2025-06-14 23:10:14] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 14 23:10:14 volumiozonder volumio-remote-updater[918]: [2025-06-14 23:10:14] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 14 23:10:14 volumiozonder systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 14 23:10:14 volumiozonder systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 14 23:10:14 volumiozonder systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 14 23:10:14 volumiozonder systemd[1]: volumio.service: Consumed 14.417s CPU time. Jun 14 23:10:14 volumiozonder systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 14 23:10:14 volumiozonder dbus-daemon[907]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.165' (uid=0 pid=15387 comm="timedatectl show --property=NTPSynchronized --valu") Jun 14 23:10:14 volumiozonder systemd[1]: dynamicswap.service: Deactivated successfully. Jun 14 23:10:14 volumiozonder systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 14 23:10:14 volumiozonder systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11. Jun 14 23:10:14 volumiozonder dbus-daemon[907]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 14 23:10:14 volumiozonder systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 14 23:10:14 volumiozonder setdatetime-helper.sh[15378]: Time is already synchronized. Jun 14 23:10:14 volumiozonder systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 14 23:10:14 volumiozonder systemd[1]: Stopped volumio.service - Volumio Backend Module. Jun 14 23:10:14 volumiozonder systemd[1]: volumio.service: Consumed 14.417s CPU time. Jun 14 23:10:14 volumiozonder systemd[1]: Started volumio.service - Volumio Backend Module. Jun 14 23:10:14 volumiozonder systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 14 23:10:14 volumiozonder systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 14 23:10:14 volumiozonder systemd[1]: dynamicswap.service: Deactivated successfully. Jun 14 23:10:15 volumiozonder volumio[15397]: info: ------------------------------------------- Jun 14 23:10:15 volumiozonder volumio[15397]: info: ----- Volumio3 ---- Jun 14 23:10:15 volumiozonder volumio[15397]: info: ------------------------------------------- Jun 14 23:10:15 volumiozonder volumio[15397]: info: ----- System startup ---- Jun 14 23:10:15 volumiozonder volumio[15397]: info: ------------------------------------------- Jun 14 23:10:15 volumiozonder volumio[15397]: info: MYVOLUMIO Environment detected Jun 14 23:10:15 volumiozonder volumio[15397]: info: Plugin folders cleanup Jun 14 23:10:15 volumiozonder volumio[15397]: info: Scanning into folder /volumio/app/plugins/ Jun 14 23:10:15 volumiozonder volumio[15397]: info: Scanning category audio_interface Jun 14 23:10:15 volumiozonder volumio[15397]: info: Scanning category miscellanea Jun 14 23:10:15 volumiozonder volumio[15397]: info: Scanning category music_service Jun 14 23:10:15 volumiozonder volumio[15397]: info: Scanning category plugins.json Jun 14 23:10:15 volumiozonder volumio[15397]: info: Scanning category system_controller Jun 14 23:10:15 volumiozonder volumio[15397]: info: Scanning category user_interface Jun 14 23:10:15 volumiozonder volumio[15397]: info: Scanning into folder /data/plugins/ Jun 14 23:10:15 volumiozonder volumio[15397]: info: Scanning category music_service Jun 14 23:10:15 volumiozonder volumio[15397]: info: Scanning category system_controller Jun 14 23:10:15 volumiozonder volumio[15397]: info: Scanning category user_interface Jun 14 23:10:15 volumiozonder volumio[15397]: info: Plugin folders cleanup completed Jun 14 23:10:15 volumiozonder volumio[15397]: info: ------------------------------------------- Jun 14 23:10:15 volumiozonder volumio[15397]: info: ----- Core plugins startup ---- Jun 14 23:10:15 volumiozonder volumio[15397]: info: ------------------------------------------- Jun 14 23:10:15 volumiozonder volumio[15397]: info: Loading plugins from folder /volumio/app/plugins/ Jun 14 23:10:15 volumiozonder volumio[15397]: info: Adding plugin upnp to MyMusic Plugins Jun 14 23:10:15 volumiozonder volumio[15397]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 14 23:10:15 volumiozonder volumio[15397]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 14 23:10:15 volumiozonder volumio[15397]: info: Loading plugins from folder /data/plugins/ Jun 14 23:10:15 volumiozonder volumio[15397]: info: Loading plugin "system"... Jun 14 23:10:15 volumiozonder volumio[15397]: info: Loading plugin "appearance"... Jun 14 23:10:15 volumiozonder volumio[15397]: info: Loading plugin "network"... Jun 14 23:10:15 volumiozonder volumio[15397]: info: Refreshing Cached IP Addresses Jun 14 23:10:15 volumiozonder sudo[15425]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 14 23:10:15 volumiozonder sudo[15425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:10:15 volumiozonder sudo[15427]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 14 23:10:15 volumiozonder sudo[15427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:10:15 volumiozonder sudo[15425]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:15 volumiozonder sudo[15427]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:15 volumiozonder volumio[15397]: info: Loading plugin "services"... Jun 14 23:10:15 volumiozonder volumio[15397]: info: Loading plugin "alsa_controller"... Jun 14 23:10:15 volumiozonder sudo[15436]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 14 23:10:15 volumiozonder sudo[15436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:10:15 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:10:15 volumiozonder volumio[15397]: info: Loading plugin "wizard"... Jun 14 23:10:15 volumiozonder volumio[15397]: info: Loading plugin "networkfs"... Jun 14 23:10:15 volumiozonder volumio[15397]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Jun 14 23:10:15 volumiozonder volumio[15397]: info: Starting Udev Watcher for removable devices Jun 14 23:10:15 volumiozonder volumio[15397]: info: Ignoring mount for partition: boot Jun 14 23:10:15 volumiozonder volumio[15397]: info: Ignoring mount for partition: volumio Jun 14 23:10:15 volumiozonder volumio[15397]: info: Ignoring mount for partition: volumio_data Jun 14 23:10:15 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 14 23:10:15 volumiozonder volumio[15397]: info: Loading plugin "volumio_command_line_client"... Jun 14 23:10:15 volumiozonder volumio[15397]: info: Loading plugin "upnp"... Jun 14 23:10:15 volumiozonder volumio[15397]: info: [1749935415937] Starting Upmpd Daemon Jun 14 23:10:15 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 14 23:10:15 volumiozonder volumio[15397]: info: Loading plugin "my_music"... Jun 14 23:10:15 volumiozonder volumio[15397]: info: Loading plugin "mpd"... Jun 14 23:10:16 volumiozonder volumio[15397]: info: Loading plugin "upnp_browser"... Jun 14 23:10:16 volumiozonder volumio[15397]: info: Loading plugin "alarm-clock"... Jun 14 23:10:16 volumiozonder volumio[15397]: info: Loading plugin "airplay_emulation"... Jun 14 23:10:16 volumiozonder volumio[15397]: info: Starting Shairport Sync Jun 14 23:10:16 volumiozonder volumio[15397]: info: Loading plugin "last_100"... Jun 14 23:10:16 volumiozonder volumio[15397]: info: Loading plugin "webradio"... Jun 14 23:10:16 volumiozonder volumio[15397]: info: Loading plugin "i2s_dacs"... Jun 14 23:10:16 volumiozonder volumio[15397]: info: I2S DAC not set, start Auto-detection Jun 14 23:10:16 volumiozonder volumio[15397]: info: Loading plugin "volumiodiscovery"... Jun 14 23:10:16 volumiozonder volumio[15397]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 14 23:10:16 volumiozonder volumio[15397]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 23:10:16 volumiozonder volumio[15397]: *** WARNING *** For more information see Jun 14 23:10:16 volumiozonder volumio[15397]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 14 23:10:16 volumiozonder volumio[15397]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 23:10:16 volumiozonder node[15397]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 14 23:10:16 volumiozonder volumio[15397]: *** WARNING *** For more information see Jun 14 23:10:16 volumiozonder node[15397]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 23:10:16 volumiozonder node[15397]: *** WARNING *** For more information see Jun 14 23:10:16 volumiozonder node[15397]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 14 23:10:16 volumiozonder node[15397]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 23:10:16 volumiozonder node[15397]: *** WARNING *** For more information see Jun 14 23:10:16 volumiozonder volumio[15397]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 14 23:10:16 volumiozonder volumio[15397]: info: Discovery: Started advertising with name: VolumioZonder Jun 14 23:10:16 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 14 23:10:16 volumiozonder volumio[15397]: info: Loading plugin "autostart"... Jun 14 23:10:16 volumiozonder volumio[15397]: info: Applying required configuration parameters for plugin autostart Jun 14 23:10:16 volumiozonder volumio[15397]: info: AutoStart - onVolumioStart - read config.json Jun 14 23:10:16 volumiozonder volumio[15397]: info: Loading plugin "outputs"... Jun 14 23:10:16 volumiozonder volumio[15397]: info: Loading plugin "albumart"... Jun 14 23:10:16 volumiozonder volumio[15397]: info: Plugin example_plugin is not enabled Jun 14 23:10:16 volumiozonder volumio[15397]: info: Loading plugin "inputs"... Jun 14 23:10:16 volumiozonder volumio[15397]: info: Loading plugin "updater_comm"... Jun 14 23:10:16 volumiozonder volumio[15397]: info: Plugin mpdemulation is not enabled Jun 14 23:10:16 volumiozonder volumio[15397]: info: Loading plugin "rest_api"... Jun 14 23:10:16 volumiozonder volumio[15397]: info: Loading plugin "websocket"... Jun 14 23:10:16 volumiozonder volumio[15397]: info: Starting Socket.io Server version 1.7.4 Jun 14 23:10:16 volumiozonder volumio[15397]: info: Loading plugin "motherearthradio"... Jun 14 23:10:16 volumiozonder volumio[15397]: info: Applying required configuration parameters for plugin motherearthradio Jun 14 23:10:16 volumiozonder volumio[15397]: info: [1749935416732] [MotherEarth] API delay: 5 Jun 14 23:10:16 volumiozonder volumio[15397]: info: Loading plugin "radio_paradise"... Jun 14 23:10:16 volumiozonder volumio[15460]: Forking 3 albumart workers Jun 14 23:10:16 volumiozonder volumio[15397]: info: Applying required configuration parameters for plugin radio_paradise Jun 14 23:10:16 volumiozonder volumio[15397]: info: [1749935416786] [RadioParadise] API delay: 5 Jun 14 23:10:16 volumiozonder volumio[15397]: info: Loading plugin "Systeminfo"... Jun 14 23:10:16 volumiozonder volumio[15397]: info: Loading i18n strings for locale nl Jun 14 23:10:16 volumiozonder volumio[15397]: Updating browse sources language Jun 14 23:10:16 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:10:16 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 23:10:16 volumiozonder volumio[15397]: info: CoreCommandRouter::initPlayerControls Jun 14 23:10:16 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:16 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:16 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:16 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:16 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:16 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:16 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:16 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:16 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 23:10:16 volumiozonder volumio[15397]: Express server listening on port 3000 Jun 14 23:10:16 volumiozonder volumio[15397]: [Metrics] WebUI: 1s 763.69ms Jun 14 23:10:16 volumiozonder volumio[15397]: info: CoreStateMachine::resetVolumioState Jun 14 23:10:16 volumiozonder volumio[15397]: info: CoreStateMachine::getcurrentVolume Jun 14 23:10:16 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioRetrievevolume Jun 14 23:10:16 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: Volumio Network Manager: Network status updated: 2 Jun 14 23:10:17 volumiozonder volumio[15397]: verbose: New Socket.io Connection to 192.168.68.70:3000 from 192.168.68.57 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Jun 14 23:10:17 volumiozonder volumio[15397]: verbose: New Socket.io Connection to 192.168.68.70 from 192.168.68.57 UA: Mozilla/5.0 (Linux; Android 15; SM-S938B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.61 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 14 23:10:17 volumiozonder volumio[15397]: info: Reloading queue from file Jun 14 23:10:17 volumiozonder volumio[15397]: info: VolumeController:: Volume=100 Mute =false Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreStateMachine::pushState Jun 14 23:10:17 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioPushState Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreStateMachine::updateTrackBlock Jun 14 23:10:17 volumiozonder volumio[15397]: info: CorePlayQueue::getTrackBlock Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioRetrievevolume Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreStateMachine::setRepeat null single undefined Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreStateMachine::pushState Jun 14 23:10:17 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioPushState Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreStateMachine::setRandom null Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreStateMachine::pushState Jun 14 23:10:17 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioPushState Jun 14 23:10:17 volumiozonder volumio[15397]: info: Setting Device type: Raspberry PI Jun 14 23:10:17 volumiozonder volumio[15397]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 14 23:10:17 volumiozonder volumio[15397]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Jun 14 23:10:17 volumiozonder volumio[15397]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 14 23:10:17 volumiozonder volumio[15397]: info: Completed loading Core Plugins Jun 14 23:10:17 volumiozonder volumio[15397]: info: Preparing to generate the ALSA configuration file Jun 14 23:10:17 volumiozonder volumio[15397]: info: VolumeController:: Volume=100 Mute =false Jun 14 23:10:17 volumiozonder volumio[15471]: Starting albumart workers Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreStateMachine::pushState Jun 14 23:10:17 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioPushState Jun 14 23:10:17 volumiozonder volumio[15472]: Starting albumart workers Jun 14 23:10:17 volumiozonder volumio[15397]: info: Asound.conf file unchanged, so no further update is needed Jun 14 23:10:17 volumiozonder volumio[15397]: info: Output device has changed, restarting MPD Jun 14 23:10:17 volumiozonder volumio[15397]: info: Output device has changed, restarting Shairport Sync Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:10:17 volumiozonder sudo[15518]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 14 23:10:17 volumiozonder sudo[15518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:10:17 volumiozonder sudo[15520]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 14 23:10:17 volumiozonder sudo[15518]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:17 volumiozonder sudo[15520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:10:17 volumiozonder volumio[15397]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 14 23:10:17 volumiozonder volumio[15397]: info: ___________ START PLUGINS ___________ Jun 14 23:10:17 volumiozonder volumio[15397]: info: ControllerMpd::onStart: Initializing MPD Jun 14 23:10:17 volumiozonder volumio[15397]: info: Creating MPD Configuration file Jun 14 23:10:17 volumiozonder systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:10:17 volumiozonder volumio[15397]: info: [1749935417221] CoreMusicLibrary::Adding element Media Servers Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:10:17 volumiozonder sudo[15528]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 14 23:10:17 volumiozonder sudo[15528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:10:17 volumiozonder sudo[15528]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:17 volumiozonder sudo[15530]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 14 23:10:17 volumiozonder sudo[15530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 14 23:10:17 volumiozonder systemd[1]: mpd.service: Deactivated successfully. Jun 14 23:10:17 volumiozonder systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 14 23:10:17 volumiozonder systemd[1]: mpd.service: Consumed 3.732s CPU time. Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:10:17 volumiozonder volumio[15397]: info: [1749935417289] CoreMusicLibrary::Adding element Last_100 Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:10:17 volumiozonder systemd[1]: mpd.socket: Deactivated successfully. Jun 14 23:10:17 volumiozonder systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 14 23:10:17 volumiozonder systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:10:17 volumiozonder volumio[15397]: info: [1749935417290] CoreMusicLibrary::Adding element Webradio Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:10:17 volumiozonder volumio[15470]: Starting albumart workers Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:10:17 volumiozonder volumio[15397]: info: Initializing BBC Radios Jun 14 23:10:17 volumiozonder systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 14 23:10:17 volumiozonder systemd[1]: Starting mpd.service - Music Player Daemon... Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: AutoStart - onStart Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:10:17 volumiozonder volumio[15397]: info: [1749935417378] CoreMusicLibrary::Adding element Mother Earth Radio Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:10:17 volumiozonder volumio[15397]: Cannot find translation for source Mother Earth Radio Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:10:17 volumiozonder volumio[15397]: info: [1749935417389] CoreMusicLibrary::Adding element Radio Paradise Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:10:17 volumiozonder volumio[15397]: Cannot find translation for source Mother Earth Radio Jun 14 23:10:17 volumiozonder volumio[15397]: Cannot find translation for source Radio Paradise Jun 14 23:10:17 volumiozonder volumio[15397]: info: Volumio Calling Home Jun 14 23:10:17 volumiozonder sudo[15546]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 14 23:10:17 volumiozonder sudo[15546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 14 23:10:17 volumiozonder sudo[15546]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:17 volumiozonder volumio[15397]: info: MPD Permissions set Jun 14 23:10:17 volumiozonder volumio[15397]: info: MPD Permissions set Jun 14 23:10:17 volumiozonder volumio[15397]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:17 volumiozonder volumio[15397]: info: Volumio called home Jun 14 23:10:17 volumiozonder volumio[15397]: info: Starting Shairport Sync Jun 14 23:10:17 volumiozonder volumio[15397]: info: Starting Shairport Sync Jun 14 23:10:17 volumiozonder volumio[15397]: info: Starting Shairport Sync Jun 14 23:10:17 volumiozonder sudo[15594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 14 23:10:17 volumiozonder sudo[15594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:10:17 volumiozonder sudo[15599]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 14 23:10:17 volumiozonder sudo[15599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:10:17 volumiozonder sudo[15596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 14 23:10:17 volumiozonder volumio[15397]: info: Discovery: adding dd8534c6-3a17-426a-ab31-aa30780df5c7 Jun 14 23:10:17 volumiozonder volumio[15397]: info: Discovery: Found device VolumioZonder Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioGetState Jun 14 23:10:17 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:17 volumiozonder sudo[15596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:10:17 volumiozonder systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 14 23:10:17 volumiozonder systemd[1]: shairport-sync.service: Deactivated successfully. Jun 14 23:10:17 volumiozonder systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 14 23:10:17 volumiozonder systemd[1]: shairport-sync.service: Consumed 1.960s CPU time. Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:10:17 volumiozonder volumio[15397]: info: Discovery: Getting this device information Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioGetState Jun 14 23:10:17 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioGetState Jun 14 23:10:17 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:17 volumiozonder systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 14 23:10:17 volumiozonder sudo[15594]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:17 volumiozonder volumio[15397]: info: Shairport-Sync Started Jun 14 23:10:17 volumiozonder systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 14 23:10:17 volumiozonder volumio[15397]: Error adding Membership: Error: addMembership EINVAL Jun 14 23:10:17 volumiozonder sudo[15599]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:17 volumiozonder systemd[1]: shairport-sync.service: Deactivated successfully. Jun 14 23:10:17 volumiozonder systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 14 23:10:17 volumiozonder volumio[15397]: info: Shairport-Sync Started Jun 14 23:10:17 volumiozonder systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 14 23:10:17 volumiozonder sudo[15596]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:17 volumiozonder volumio[15397]: info: Shairport-Sync Started Jun 14 23:10:17 volumiozonder volumio[15397]: verbose: New Socket.io Connection to 192.168.68.70 from 192.168.68.57 UA: Mozilla/5.0 (Linux; Android 15; SM-S938B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.61 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 14 23:10:17 volumiozonder volumio[15397]: info: Discovery: this is already registered, dd8534c6-3a17-426a-ab31-aa30780df5c7 Jun 14 23:10:17 volumiozonder volumio[15397]: info: Discovery: Found device VolumioZonder Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioGetState Jun 14 23:10:17 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:17 volumiozonder volumio[15397]: verbose: New Socket.io Connection to 192.168.68.70:3000 from 192.168.68.57 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jun 14 23:10:17 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioGetState Jun 14 23:10:17 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:18 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 14 23:10:18 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 14 23:10:18 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioGetState Jun 14 23:10:18 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:18 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 14 23:10:18 volumiozonder volumio[15397]: info: Listing playlists Jun 14 23:10:18 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioGetVisibleSources Jun 14 23:10:18 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:10:18 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioGetQueue Jun 14 23:10:18 volumiozonder volumio[15397]: info: CoreStateMachine::getQueue Jun 14 23:10:18 volumiozonder volumio[15397]: info: CorePlayQueue::getQueue Jun 14 23:10:18 volumiozonder mpd[15559]: 2025-06-14T23:10:18 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 14 23:10:18 volumiozonder systemd[1]: Started mpd.service - Music Player Daemon. Jun 14 23:10:18 volumiozonder sudo[15530]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:18 volumiozonder sudo[15520]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:18 volumiozonder volumio[15397]: info: Completed starting Core Plugins Jun 14 23:10:18 volumiozonder volumio[15397]: info: ------------------------------------------- Jun 14 23:10:18 volumiozonder volumio[15397]: info: ----- MyVolumio plugins startup ---- Jun 14 23:10:18 volumiozonder volumio[15397]: info: ------------------------------------------- Jun 14 23:10:18 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 14 23:10:18 volumiozonder volumio[15397]: error: updateQueue error: null Jun 14 23:10:18 volumiozonder volumio[15397]: info: MPD running with PID15559 Jun 14 23:10:18 volumiozonder volumio[15397]: ,establishing connection Jun 14 23:10:18 volumiozonder volumio[15397]: error: MPD error: The expression evaluated to a falsy value: Jun 14 23:10:18 volumiozonder volumio[15397]: assert.ok(self.idling) Jun 14 23:10:18 volumiozonder volumio[15397]: error: The expression evaluated to a falsy value: Jun 14 23:10:18 volumiozonder volumio[15397]: assert.ok(self.idling) Jun 14 23:10:18 volumiozonder volumio[15397]: error: updateQueue error: null Jun 14 23:10:18 volumiozonder wpa_supplicant[1207]: RRM: Ignoring radio measurement request: Not RRM network Jun 14 23:10:19 volumiozonder sudo[15436]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:19 volumiozonder volumio-remote-updater[918]: [2025-06-14 23:10:19] [connect] Successful connection Jun 14 23:10:19 volumiozonder volumio-remote-updater[918]: [2025-06-14 23:10:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1749935419 101 Jun 14 23:10:19 volumiozonder volumio[15397]: 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: 6 Jun 14 23:10:20 volumiozonder volumio[15397]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Jun 14 23:10:23 volumiozonder volumio[15397]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 14 23:10:24 volumiozonder wpa_supplicant[1207]: RRM: Ignoring radio measurement request: Not RRM network Jun 14 23:10:24 volumiozonder volumio[15397]: Cannot compose Albumart path Jun 14 23:10:24 volumiozonder volumio[15397]: Cannot compose Albumart path Jun 14 23:10:24 volumiozonder volumio[15397]: Cannot compose Albumart path Jun 14 23:10:24 volumiozonder volumio[15397]: Cannot compose Albumart path Jun 14 23:10:25 volumiozonder volumio[15397]: Cannot compose Albumart path Jun 14 23:10:25 volumiozonder volumio[15397]: Cannot compose Albumart path Jun 14 23:10:25 volumiozonder volumio[15397]: Cannot compose Albumart path Jun 14 23:10:25 volumiozonder volumio[15397]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Jun 14 23:10:25 volumiozonder sudo[15650]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 14 23:10:25 volumiozonder sudo[15650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:10:25 volumiozonder sudo[15652]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 14 23:10:25 volumiozonder sudo[15650]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:25 volumiozonder sudo[15652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:10:25 volumiozonder sudo[15652]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:25 volumiozonder sudo[15656]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 14 23:10:25 volumiozonder sudo[15656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:10:25 volumiozonder sudo[15656]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:25 volumiozonder volumio[15397]: info: Upmpdcli Daemon Started Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 14 23:10:26 volumiozonder volumio[15397]: info: Adding plugin bluetooth to MyMusic Plugins Jun 14 23:10:26 volumiozonder volumio[15397]: info: Adding plugin multiroom to MyMusic Plugins Jun 14 23:10:26 volumiozonder volumio[15397]: info: Adding plugin metavolumio to MyMusic Plugins Jun 14 23:10:26 volumiozonder volumio[15397]: info: Adding plugin cd_controller to MyMusic Plugins Jun 14 23:10:26 volumiozonder volumio[15397]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 14 23:10:26 volumiozonder volumio[15397]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 14 23:10:26 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:10:26 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:10:26 volumiozonder volumio[15397]: info: Starting MyVolumio Remote Streaming Endpoints Jun 14 23:10:26 volumiozonder volumio[15397]: info: MyVolumio login type: Token Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 14 23:10:26 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 14 23:10:27 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 14 23:10:27 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 14 23:10:27 volumiozonder volumio[15397]: info: Streaming services startup Jun 14 23:10:27 volumiozonder volumio[15397]: info: Starting Streaming Daemon Jun 14 23:10:27 volumiozonder sudo[15662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 14 23:10:27 volumiozonder sudo[15662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:10:27 volumiozonder volumio[15397]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 14 23:10:27 volumiozonder sudo[15662]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:27 volumiozonder volumio[15397]: error: Cannot start Volumio Streaming Daemon Jun 14 23:10:27 volumiozonder volumio[15397]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 14 23:10:27 volumiozonder volumio[15397]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 14 23:10:27 volumiozonder volumio[15397]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 14 23:10:28 volumiozonder volumio[15397]: info: MyVolumio token set successfully Jun 14 23:10:28 volumiozonder volumio[15397]: info: MYVOLUMIO: Adding device Jun 14 23:10:28 volumiozonder volumio[15397]: info: MYVOLUMIO: Evaluating Server Jun 14 23:10:28 volumiozonder volumio[15397]: info: MyVolumio status changed Jun 14 23:10:28 volumiozonder volumio[15397]: info: Streaming services startup Jun 14 23:10:28 volumiozonder volumio[15397]: info: Starting Streaming Daemon Jun 14 23:10:28 volumiozonder volumio[15397]: info: Removing browser output: myVolumio user plan is not superstar Jun 14 23:10:28 volumiozonder volumio[15397]: info: Removing audio output: Jun 14 23:10:28 volumiozonder volumio[15397]: info: Stoppping Tunnel 1 Jun 14 23:10:28 volumiozonder sudo[15689]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 14 23:10:28 volumiozonder sudo[15689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:10:28 volumiozonder sudo[15691]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 14 23:10:28 volumiozonder sudo[15691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:10:28 volumiozonder 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 14 23:10:28 volumiozonder 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 14 23:10:28 volumiozonder sudo[15689]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:28 volumiozonder volumio[15397]: error: Cannot start Volumio Streaming Daemon Jun 14 23:10:28 volumiozonder volumio[15397]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 14 23:10:28 volumiozonder volumio[15397]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 14 23:10:28 volumiozonder 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 14 23:10:28 volumiozonder 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 14 23:10:28 volumiozonder 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 14 23:10:28 volumiozonder 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 14 23:10:28 volumiozonder sudo[15691]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:28 volumiozonder volumio[15397]: info: Remote SSH Stopped Jun 14 23:10:28 volumiozonder volumio[15397]: info: Setting Geolocation for MyVolumio to eu2 Jun 14 23:10:28 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:10:28 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:10:28 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:10:28 volumiozonder volumio[15397]: info: Successfully Added MyVolumio device Jun 14 23:10:29 volumiozonder volumio[15397]: info: Updating MyVolumio device info Jun 14 23:10:29 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:10:29 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:10:29 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:10:30 volumiozonder volumio[15397]: info: Successfully Updated MyVolumio device Jun 14 23:10:30 volumiozonder wpa_supplicant[1207]: RRM: Ignoring radio measurement request: Not RRM network Jun 14 23:10:30 volumiozonder volumio[15397]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Jun 14 23:10:30 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 14 23:10:31 volumiozonder volumio[15397]: info: Preload queue cleared Jun 14 23:10:31 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:10:31 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:10:31 volumiozonder volumio[15397]: info: Discovery: Getting this device information Jun 14 23:10:31 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioGetState Jun 14 23:10:31 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:31 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:10:35 volumiozonder volumio[15397]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jun 14 23:10:36 volumiozonder wpa_supplicant[1207]: RRM: Ignoring radio measurement request: Not RRM network Jun 14 23:10:37 volumiozonder volumio[15397]: info: AutoStart - Plugin is starting Jun 14 23:10:37 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioGetQueue Jun 14 23:10:37 volumiozonder volumio[15397]: info: CoreStateMachine::getQueue Jun 14 23:10:37 volumiozonder volumio[15397]: info: CorePlayQueue::getQueue Jun 14 23:10:37 volumiozonder volumio[15397]: info: AutoStart - start playing Jun 14 23:10:37 volumiozonder volumio[15397]: info: AutoStart - start playing with no specific position Jun 14 23:10:37 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioPlay Jun 14 23:10:37 volumiozonder volumio[15397]: info: CoreStateMachine::play index 0 Jun 14 23:10:37 volumiozonder volumio[15397]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 23:10:37 volumiozonder volumio[15397]: info: CoreStateMachine::stop Jun 14 23:10:37 volumiozonder volumio[15397]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 23:10:37 volumiozonder volumio[15397]: info: CoreStateMachine::play index undefined Jun 14 23:10:37 volumiozonder volumio[15397]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 23:10:37 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:37 volumiozonder volumio[15397]: info: CoreStateMachine::startPlaybackTimer Jun 14 23:10:37 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:37 volumiozonder volumio[15397]: info: [1749935437379] ControllerWebradio::clearAddPlayTrack Jun 14 23:10:37 volumiozonder volumio[15397]: verbose: ControllerMpd::sendMpdCommand stop Jun 14 23:10:37 volumiozonder volumio[15397]: info: sendMpdCommand stop took 0 milliseconds Jun 14 23:10:37 volumiozonder volumio[15397]: verbose: ControllerMpd::sendMpdCommand clear Jun 14 23:10:37 volumiozonder volumio[15397]: info: sendMpdCommand clear took 0 milliseconds Jun 14 23:10:37 volumiozonder volumio[15397]: verbose: ControllerMpd::sendMpdCommand load "https://playerservices.streamtheworld.com/api/livestream-redirect/TLPSTR18.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0OTkzMzI0NiwiaXNzIjoidGlzcnYifQ.pNAAoH9OpmXQ9DSdrGROv13cHNkuVs45bHOEtFfJMAs" Jun 14 23:10:37 volumiozonder volumio[15397]: info: Jun 14 23:10:37 volumiozonder volumio[15397]: ---------------------------- MPD announces system playlist update Jun 14 23:10:37 volumiozonder volumio[15397]: info: Ignoring MPD Status Update Jun 14 23:10:37 volumiozonder volumio[15397]: info: Jun 14 23:10:37 volumiozonder volumio[15397]: ---------------------------- MPD announces system playlist update Jun 14 23:10:37 volumiozonder volumio[15397]: info: Ignoring MPD Status Update Jun 14 23:10:38 volumiozonder volumio[15397]: info: Jun 14 23:10:38 volumiozonder volumio[15397]: ---------------------------- MPD announces system playlist update Jun 14 23:10:38 volumiozonder volumio[15397]: info: Ignoring MPD Status Update Jun 14 23:10:38 volumiozonder volumio[15397]: verbose: ControllerMpd::sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/TLPSTR18.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0OTkzMzI0NiwiaXNzIjoidGlzcnYifQ.pNAAoH9OpmXQ9DSdrGROv13cHNkuVs45bHOEtFfJMAs" Jun 14 23:10:38 volumiozonder volumio[15397]: error: updateQueue error: null Jun 14 23:10:38 volumiozonder volumio[15397]: error: updateQueue error: null Jun 14 23:10:38 volumiozonder volumio[15397]: error: updateQueue error: null Jun 14 23:10:38 volumiozonder volumio[15397]: info: ------------------------------ 949ms Jun 14 23:10:38 volumiozonder volumio[15397]: info: ------------------------------ 948ms Jun 14 23:10:38 volumiozonder volumio[15397]: info: ------------------------------ 2ms Jun 14 23:10:38 volumiozonder volumio[15397]: info: sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/TLPSTR18.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0OTkzMzI0NiwiaXNzIjoidGlzcnYifQ.pNAAoH9OpmXQ9DSdrGROv13cHNkuVs45bHOEtFfJMAs" took 2 milliseconds Jun 14 23:10:38 volumiozonder volumio[15397]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 14 23:10:38 volumiozonder volumio[15397]: verbose: ControllerMpd::sendMpdCommand play Jun 14 23:10:38 volumiozonder volumio[15397]: info: Jun 14 23:10:38 volumiozonder volumio[15397]: ---------------------------- MPD announces system playlist update Jun 14 23:10:38 volumiozonder volumio[15397]: info: Ignoring MPD Status Update Jun 14 23:10:38 volumiozonder volumio[15397]: info: Jun 14 23:10:38 volumiozonder volumio[15397]: ---------------------------- MPD announces system playlist update Jun 14 23:10:38 volumiozonder volumio[15397]: info: Ignoring MPD Status Update Jun 14 23:10:38 volumiozonder volumio[15397]: info: Jun 14 23:10:38 volumiozonder volumio[15397]: ---------------------------- MPD announces system playlist update Jun 14 23:10:38 volumiozonder volumio[15397]: info: Ignoring MPD Status Update Jun 14 23:10:38 volumiozonder volumio[15397]: info: sendMpdCommand play took 2 milliseconds Jun 14 23:10:38 volumiozonder volumio[15397]: info: ------------------------------ 2ms Jun 14 23:10:38 volumiozonder volumio[15397]: info: ------------------------------ 1ms Jun 14 23:10:38 volumiozonder volumio[15397]: info: ------------------------------ 1ms Jun 14 23:10:39 volumiozonder volumio[15397]: info: Jun 14 23:10:39 volumiozonder volumio[15397]: ---------------------------- MPD announces state update: player Jun 14 23:10:39 volumiozonder volumio[15397]: info: ControllerMpd::getState Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::sendMpdCommand status Jun 14 23:10:39 volumiozonder volumio[15397]: info: Jun 14 23:10:39 volumiozonder volumio[15397]: ---------------------------- MPD announces state update: player Jun 14 23:10:39 volumiozonder volumio[15397]: info: ControllerMpd::getState Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::sendMpdCommand status Jun 14 23:10:39 volumiozonder volumio[15397]: info: Jun 14 23:10:39 volumiozonder volumio[15397]: ---------------------------- MPD announces state update: player Jun 14 23:10:39 volumiozonder volumio[15397]: info: ControllerMpd::getState Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::sendMpdCommand status Jun 14 23:10:39 volumiozonder volumio[15397]: info: Jun 14 23:10:39 volumiozonder volumio[15397]: ---------------------------- MPD announces state update: player Jun 14 23:10:39 volumiozonder volumio[15397]: info: sendMpdCommand status took 4 milliseconds Jun 14 23:10:39 volumiozonder volumio[15397]: info: ControllerMpd::getState Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::sendMpdCommand status Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::parseState Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 23:10:39 volumiozonder volumio[15397]: info: Jun 14 23:10:39 volumiozonder volumio[15397]: ---------------------------- MPD announces state update: player Jun 14 23:10:39 volumiozonder volumio[15397]: info: ControllerMpd::getState Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::sendMpdCommand status Jun 14 23:10:39 volumiozonder volumio[15397]: info: Jun 14 23:10:39 volumiozonder volumio[15397]: ---------------------------- MPD announces state update: player Jun 14 23:10:39 volumiozonder volumio[15397]: info: ControllerMpd::getState Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::sendMpdCommand status Jun 14 23:10:39 volumiozonder volumio[15397]: info: Jun 14 23:10:39 volumiozonder volumio[15397]: ---------------------------- MPD announces system playlist update Jun 14 23:10:39 volumiozonder volumio[15397]: info: Ignoring MPD Status Update Jun 14 23:10:39 volumiozonder volumio[15397]: info: sendMpdCommand status took 6 milliseconds Jun 14 23:10:39 volumiozonder volumio[15397]: info: sendMpdCommand status took 5 milliseconds Jun 14 23:10:39 volumiozonder volumio[15397]: info: sendMpdCommand status took 2 milliseconds Jun 14 23:10:39 volumiozonder volumio[15397]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 14 23:10:39 volumiozonder volumio[15397]: info: sendMpdCommand status took 2 milliseconds Jun 14 23:10:39 volumiozonder volumio[15397]: info: sendMpdCommand status took 2 milliseconds Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::parseState Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::parseState Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::parseState Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::parseTrackInfo Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::parseState Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::parseState Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 23:10:39 volumiozonder volumio[15397]: info: ControllerMpd::pushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreCommandRouter::servicePushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"First Class - Beach Baby","artist":"Radio 10 60&70's Hits","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/TLPSTR18.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0OTkzMzI0NiwiaXNzIjoidGlzcnYifQ.pNAAoH9OpmXQ9DSdrGROv13cHNkuVs45bHOEtFfJMAs","trackType":""} Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: CURRENT POSITION 0 Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::syncState stateService play Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::syncState currentStatus stop Jun 14 23:10:39 volumiozonder volumio[15397]: info: ------------------------------ 11ms Jun 14 23:10:39 volumiozonder volumio[15397]: info: Jun 14 23:10:39 volumiozonder volumio[15397]: ---------------------------- MPD announces system playlist update Jun 14 23:10:39 volumiozonder volumio[15397]: info: Ignoring MPD Status Update Jun 14 23:10:39 volumiozonder volumio[15397]: info: Jun 14 23:10:39 volumiozonder volumio[15397]: ---------------------------- MPD announces system playlist update Jun 14 23:10:39 volumiozonder volumio[15397]: info: Ignoring MPD Status Update Jun 14 23:10:39 volumiozonder volumio[15397]: info: ------------------------------ 5ms Jun 14 23:10:39 volumiozonder volumio[15397]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 14 23:10:39 volumiozonder volumio[15397]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 14 23:10:39 volumiozonder volumio[15397]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 14 23:10:39 volumiozonder volumio[15397]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 14 23:10:39 volumiozonder volumio[15397]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 14 23:10:39 volumiozonder volumio[15397]: info: ------------------------------ 2ms Jun 14 23:10:39 volumiozonder volumio[15397]: info: ------------------------------ 1ms Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::parseTrackInfo Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::parseTrackInfo Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::parseTrackInfo Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::parseTrackInfo Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: ControllerMpd::parseTrackInfo Jun 14 23:10:39 volumiozonder volumio[15397]: info: ControllerMpd::pushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreCommandRouter::servicePushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"First Class - Beach Baby","artist":"Radio 10 60&70's Hits","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/TLPSTR18.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0OTkzMzI0NiwiaXNzIjoidGlzcnYifQ.pNAAoH9OpmXQ9DSdrGROv13cHNkuVs45bHOEtFfJMAs","trackType":""} Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: CURRENT POSITION 0 Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::syncState stateService play Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::syncState currentStatus play Jun 14 23:10:39 volumiozonder volumio[15397]: info: Received an update from plugin. extracting info from payload Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::pushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioPushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::pushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioPushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: ControllerMpd::pushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreCommandRouter::servicePushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"First Class - Beach Baby","artist":"Radio 10 60&70's Hits","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/TLPSTR18.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0OTkzMzI0NiwiaXNzIjoidGlzcnYifQ.pNAAoH9OpmXQ9DSdrGROv13cHNkuVs45bHOEtFfJMAs","trackType":""} Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: CURRENT POSITION 0 Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::syncState stateService play Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::syncState currentStatus play Jun 14 23:10:39 volumiozonder volumio[15397]: info: Received an update from plugin. extracting info from payload Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::pushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioPushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::pushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioPushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: ControllerMpd::pushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreCommandRouter::servicePushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"First Class - Beach Baby","artist":"Radio 10 60&70's Hits","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/TLPSTR18.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0OTkzMzI0NiwiaXNzIjoidGlzcnYifQ.pNAAoH9OpmXQ9DSdrGROv13cHNkuVs45bHOEtFfJMAs","trackType":""} Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: CURRENT POSITION 0 Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::syncState stateService play Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::syncState currentStatus play Jun 14 23:10:39 volumiozonder volumio[15397]: info: Received an update from plugin. extracting info from payload Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::pushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioPushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::pushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioPushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: ControllerMpd::pushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreCommandRouter::servicePushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"First Class - Beach Baby","artist":"Radio 10 60&70's Hits","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/TLPSTR18.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0OTkzMzI0NiwiaXNzIjoidGlzcnYifQ.pNAAoH9OpmXQ9DSdrGROv13cHNkuVs45bHOEtFfJMAs","trackType":""} Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: CURRENT POSITION 0 Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::syncState stateService play Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::syncState currentStatus play Jun 14 23:10:39 volumiozonder volumio[15397]: info: Received an update from plugin. extracting info from payload Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::pushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioPushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::pushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioPushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: ControllerMpd::pushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreCommandRouter::servicePushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CorePlayQueue::getTrack 0 Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"First Class - Beach Baby","artist":"Radio 10 60&70's Hits","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/TLPSTR18.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0OTkzMzI0NiwiaXNzIjoidGlzcnYifQ.pNAAoH9OpmXQ9DSdrGROv13cHNkuVs45bHOEtFfJMAs","trackType":""} Jun 14 23:10:39 volumiozonder volumio[15397]: verbose: CURRENT POSITION 0 Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::syncState stateService play Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::syncState currentStatus play Jun 14 23:10:39 volumiozonder volumio[15397]: info: Received an update from plugin. extracting info from payload Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::pushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioPushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreStateMachine::pushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioPushState Jun 14 23:10:39 volumiozonder volumio[15397]: info: ------------------------------ 40ms Jun 14 23:10:39 volumiozonder volumio[15397]: info: ------------------------------ 39ms Jun 14 23:10:39 volumiozonder volumio[15397]: info: ------------------------------ 38ms Jun 14 23:10:39 volumiozonder volumio[15397]: info: ------------------------------ 36ms Jun 14 23:10:39 volumiozonder volumio[15397]: info: ------------------------------ 36ms Jun 14 23:10:41 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 14 23:10:41 volumiozonder volumio[15397]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 14 23:10:41 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioGetState Jun 14 23:10:42 volumiozonder wpa_supplicant[1207]: RRM: Ignoring radio measurement request: Not RRM network Jun 14 23:10:44 volumiozonder systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 14 23:10:46 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:46 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:46 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 14 23:10:46 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 14 23:10:46 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 14 23:10:46 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioGetBrowseSources Jun 14 23:10:46 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:10:47 volumiozonder volumio[15397]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 14 23:10:47 volumiozonder volumio[15397]: info: Completed starting MyVolumio Plugin Jun 14 23:10:47 volumiozonder volumio[15397]: [Metrics] CommandRouter: 31s 878.14ms Jun 14 23:10:47 volumiozonder volumio[15397]: info: CoreCommandRouter::volumiosetStartupVolume Jun 14 23:10:47 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:10:47 volumiozonder volumio[15397]: info: VolumeController:: Setting startup Volume 100 Jun 14 23:10:47 volumiozonder volumio[15397]: info: VolumeController::SetAlsaVolume100 Jun 14 23:10:47 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:10:47 volumiozonder volumio[15397]: info: CoreCommandRouter::Close All Modals sent Jun 14 23:10:47 volumiozonder volumio[15397]: info: CoreCommandRouter::Close All Modals sent Jun 14 23:10:47 volumiozonder volumio[15397]: info: CoreStateMachine::pushState Jun 14 23:10:47 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 23:10:47 volumiozonder volumio[15397]: info: CoreCommandRouter::volumioPushState Jun 14 23:10:47 volumiozonder volumio[15397]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jun 14 23:10:47 volumiozonder volumio[15397]: aplay: main:831: audio open error: Device or resource busy Jun 14 23:10:47 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:10:47 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:10:47 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 14 23:10:47 volumiozonder volumio-remote-updater[918]: Test mode enabled Jun 14 23:10:47 volumiozonder volumio-remote-updater[918]: Alpha mode disabled Jun 14 23:10:47 volumiozonder volumio-remote-updater[918]: Alpha legacy test mode disabled Jun 14 23:10:47 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 14 23:10:47 volumiozonder volumio[15397]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 14 23:10:47 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 14 23:10:48 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 14 23:10:48 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 14 23:10:48 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 14 23:10:48 volumiozonder wpa_supplicant[1207]: RRM: Ignoring radio measurement request: Not RRM network Jun 14 23:10:54 volumiozonder volumio[15397]: info: BOOT COMPLETED Jun 14 23:10:54 volumiozonder wpa_supplicant[1207]: RRM: Ignoring radio measurement request: Not RRM network Jun 14 23:10:58 volumiozonder volumio[15397]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 14 23:10:58 volumiozonder volumio[15397]: info: READ Jun 14 23:10:58 volumiozonder volumio[15397]: Use XMLElement.setAttribute instead Jun 14 23:10:58 volumiozonder volumio[15397]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 23:10:58 volumiozonder volumio[15397]: TypeError: children[i].attr(...).value is not a function Jun 14 23:10:58 volumiozonder volumio[15397]: at /volumio/app/plugins/music_service/webradio/index.js:286:47 Jun 14 23:10:58 volumiozonder volumio[15397]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 14 23:10:58 volumiozonder volumio[15397]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 23:10:59 volumiozonder sudo[15832]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-14 23:09' Jun 14 23:10:59 volumiozonder sudo[15832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:10:59 volumiozonder sudo[15832]: pam_unix(sudo:session): session closed for user root Jun 14 23:10:59 volumiozonder systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 14 23:10:59 volumiozonder volumio-remote-updater[918]: [2025-06-14 23:10:59] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 14 23:10:59 volumiozonder volumio-remote-updater[918]: [2025-06-14 23:10:59] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 14 23:10:59 volumiozonder systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 14 23:10:59 volumiozonder systemd[1]: volumio.service: Consumed 10.685s CPU time. Jun 14 23:10:59 volumiozonder systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 14 23:10:59 volumiozonder systemd[1]: dynamicswap.service: Deactivated successfully. Jun 14 23:10:59 volumiozonder systemd[1]: volumio.service: Scheduled restart job, restart counter is at 12. Jun 14 23:10:59 volumiozonder systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 14 23:10:59 volumiozonder systemd[1]: Stopped volumio.service - Volumio Backend Module. Jun 14 23:10:59 volumiozonder systemd[1]: volumio.service: Consumed 10.685s CPU time. Jun 14 23:10:59 volumiozonder systemd[1]: Started volumio.service - Volumio Backend Module. Jun 14 23:10:59 volumiozonder systemd[1]: dynamicswap.service: Deactivated successfully. Jun 14 23:11:00 volumiozonder volumio[15850]: info: ------------------------------------------- Jun 14 23:11:00 volumiozonder volumio[15850]: info: ----- Volumio3 ---- Jun 14 23:11:00 volumiozonder volumio[15850]: info: ------------------------------------------- Jun 14 23:11:00 volumiozonder volumio[15850]: info: ----- System startup ---- Jun 14 23:11:00 volumiozonder volumio[15850]: info: ------------------------------------------- Jun 14 23:11:00 volumiozonder volumio[15850]: info: MYVOLUMIO Environment detected Jun 14 23:11:00 volumiozonder volumio[15850]: info: Plugin folders cleanup Jun 14 23:11:00 volumiozonder volumio[15850]: info: Scanning into folder /volumio/app/plugins/ Jun 14 23:11:00 volumiozonder volumio[15850]: info: Scanning category audio_interface Jun 14 23:11:00 volumiozonder volumio[15850]: info: Scanning category miscellanea Jun 14 23:11:00 volumiozonder volumio[15850]: info: Scanning category music_service Jun 14 23:11:00 volumiozonder volumio[15850]: info: Scanning category plugins.json Jun 14 23:11:00 volumiozonder volumio[15850]: info: Scanning category system_controller Jun 14 23:11:00 volumiozonder volumio[15850]: info: Scanning category user_interface Jun 14 23:11:00 volumiozonder volumio[15850]: info: Scanning into folder /data/plugins/ Jun 14 23:11:00 volumiozonder volumio[15850]: info: Scanning category music_service Jun 14 23:11:00 volumiozonder volumio[15850]: info: Scanning category system_controller Jun 14 23:11:00 volumiozonder volumio[15850]: info: Scanning category user_interface Jun 14 23:11:00 volumiozonder volumio[15850]: info: Plugin folders cleanup completed Jun 14 23:11:00 volumiozonder volumio[15850]: info: ------------------------------------------- Jun 14 23:11:00 volumiozonder volumio[15850]: info: ----- Core plugins startup ---- Jun 14 23:11:00 volumiozonder volumio[15850]: info: ------------------------------------------- Jun 14 23:11:00 volumiozonder volumio[15850]: info: Loading plugins from folder /volumio/app/plugins/ Jun 14 23:11:00 volumiozonder volumio[15850]: info: Adding plugin upnp to MyMusic Plugins Jun 14 23:11:00 volumiozonder volumio[15850]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 14 23:11:00 volumiozonder volumio[15850]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 14 23:11:00 volumiozonder volumio[15850]: info: Loading plugins from folder /data/plugins/ Jun 14 23:11:00 volumiozonder volumio[15850]: info: Loading plugin "system"... Jun 14 23:11:00 volumiozonder volumio[15850]: info: Loading plugin "appearance"... Jun 14 23:11:00 volumiozonder wpa_supplicant[1207]: RRM: Ignoring radio measurement request: Not RRM network Jun 14 23:11:00 volumiozonder volumio[15850]: info: Loading plugin "network"... Jun 14 23:11:00 volumiozonder volumio[15850]: info: Refreshing Cached IP Addresses Jun 14 23:11:00 volumiozonder sudo[15878]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 14 23:11:00 volumiozonder sudo[15878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:00 volumiozonder sudo[15880]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 14 23:11:00 volumiozonder sudo[15878]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:00 volumiozonder sudo[15880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:00 volumiozonder sudo[15880]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:00 volumiozonder volumio[15850]: info: Loading plugin "services"... Jun 14 23:11:00 volumiozonder volumio[15850]: info: Loading plugin "alsa_controller"... Jun 14 23:11:00 volumiozonder sudo[15888]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 14 23:11:00 volumiozonder sudo[15888]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:00 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:11:00 volumiozonder volumio[15850]: info: Loading plugin "wizard"... Jun 14 23:11:00 volumiozonder volumio[15850]: info: Loading plugin "networkfs"... Jun 14 23:11:00 volumiozonder volumio[15850]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Jun 14 23:11:00 volumiozonder volumio[15850]: info: Starting Udev Watcher for removable devices Jun 14 23:11:00 volumiozonder volumio[15850]: info: Ignoring mount for partition: boot Jun 14 23:11:00 volumiozonder volumio[15850]: info: Ignoring mount for partition: volumio Jun 14 23:11:00 volumiozonder volumio[15850]: info: Ignoring mount for partition: volumio_data Jun 14 23:11:00 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 14 23:11:00 volumiozonder volumio[15850]: info: Loading plugin "volumio_command_line_client"... Jun 14 23:11:00 volumiozonder volumio[15850]: info: Loading plugin "upnp"... Jun 14 23:11:00 volumiozonder volumio[15850]: info: [1749935460705] Starting Upmpd Daemon Jun 14 23:11:00 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 14 23:11:00 volumiozonder volumio[15850]: info: Loading plugin "my_music"... Jun 14 23:11:00 volumiozonder volumio[15850]: info: Loading plugin "mpd"... Jun 14 23:11:00 volumiozonder volumio[15850]: info: Loading plugin "upnp_browser"... Jun 14 23:11:01 volumiozonder volumio[15850]: info: Loading plugin "alarm-clock"... Jun 14 23:11:01 volumiozonder volumio[15850]: info: Loading plugin "airplay_emulation"... Jun 14 23:11:01 volumiozonder volumio[15850]: info: Starting Shairport Sync Jun 14 23:11:01 volumiozonder volumio[15850]: info: Loading plugin "last_100"... Jun 14 23:11:01 volumiozonder volumio[15850]: info: Loading plugin "webradio"... Jun 14 23:11:01 volumiozonder volumio[15850]: info: Loading plugin "i2s_dacs"... Jun 14 23:11:01 volumiozonder volumio[15850]: info: I2S DAC not set, start Auto-detection Jun 14 23:11:01 volumiozonder volumio[15850]: info: Loading plugin "volumiodiscovery"... Jun 14 23:11:01 volumiozonder volumio[15850]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 14 23:11:01 volumiozonder volumio[15850]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 23:11:01 volumiozonder volumio[15850]: *** WARNING *** For more information see Jun 14 23:11:01 volumiozonder volumio[15850]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 14 23:11:01 volumiozonder volumio[15850]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 23:11:01 volumiozonder volumio[15850]: *** WARNING *** For more information see Jun 14 23:11:01 volumiozonder node[15850]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 14 23:11:01 volumiozonder node[15850]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 23:11:01 volumiozonder node[15850]: *** WARNING *** For more information see Jun 14 23:11:01 volumiozonder node[15850]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 14 23:11:01 volumiozonder node[15850]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 23:11:01 volumiozonder node[15850]: *** WARNING *** For more information see Jun 14 23:11:01 volumiozonder volumio[15850]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 14 23:11:01 volumiozonder volumio[15850]: info: Discovery: Started advertising with name: VolumioZonder Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 14 23:11:01 volumiozonder volumio[15850]: info: Loading plugin "autostart"... Jun 14 23:11:01 volumiozonder volumio[15850]: info: Applying required configuration parameters for plugin autostart Jun 14 23:11:01 volumiozonder volumio[15850]: info: AutoStart - onVolumioStart - read config.json Jun 14 23:11:01 volumiozonder volumio[15850]: info: Loading plugin "outputs"... Jun 14 23:11:01 volumiozonder volumio[15850]: info: Loading plugin "albumart"... Jun 14 23:11:01 volumiozonder volumio[15850]: info: Plugin example_plugin is not enabled Jun 14 23:11:01 volumiozonder volumio[15850]: info: Loading plugin "inputs"... Jun 14 23:11:01 volumiozonder volumio[15850]: info: Loading plugin "updater_comm"... Jun 14 23:11:01 volumiozonder volumio[15850]: info: Plugin mpdemulation is not enabled Jun 14 23:11:01 volumiozonder volumio[15850]: info: Loading plugin "rest_api"... Jun 14 23:11:01 volumiozonder volumio[15850]: info: Loading plugin "websocket"... Jun 14 23:11:01 volumiozonder volumio[15850]: info: Starting Socket.io Server version 1.7.4 Jun 14 23:11:01 volumiozonder volumio[15850]: info: Loading plugin "motherearthradio"... Jun 14 23:11:01 volumiozonder volumio[15850]: info: Applying required configuration parameters for plugin motherearthradio Jun 14 23:11:01 volumiozonder volumio[15850]: info: [1749935461495] [MotherEarth] API delay: 5 Jun 14 23:11:01 volumiozonder volumio[15850]: info: Loading plugin "radio_paradise"... Jun 14 23:11:01 volumiozonder volumio[15850]: info: Applying required configuration parameters for plugin radio_paradise Jun 14 23:11:01 volumiozonder volumio[15850]: info: [1749935461548] [RadioParadise] API delay: 5 Jun 14 23:11:01 volumiozonder volumio[15850]: info: Loading plugin "Systeminfo"... Jun 14 23:11:01 volumiozonder volumio[15913]: Forking 3 albumart workers Jun 14 23:11:01 volumiozonder volumio[15850]: info: Loading i18n strings for locale nl Jun 14 23:11:01 volumiozonder volumio[15850]: Updating browse sources language Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::initPlayerControls Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 23:11:01 volumiozonder volumio[15850]: Express server listening on port 3000 Jun 14 23:11:01 volumiozonder volumio[15850]: [Metrics] WebUI: 1s 754.19ms Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreStateMachine::resetVolumioState Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreStateMachine::getcurrentVolume Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioRetrievevolume Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:01 volumiozonder volumio[15850]: info: Volumio Network Manager: Network status updated: 2 Jun 14 23:11:01 volumiozonder volumio[15850]: verbose: New Socket.io Connection to 192.168.68.70:3000 from 192.168.68.57 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Jun 14 23:11:01 volumiozonder volumio[15850]: verbose: New Socket.io Connection to 192.168.68.70 from 192.168.68.57 UA: Mozilla/5.0 (Linux; Android 15; SM-S938B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.61 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 14 23:11:01 volumiozonder volumio[15850]: info: Reloading queue from file Jun 14 23:11:01 volumiozonder volumio[15850]: info: VolumeController:: Volume=100 Mute =false Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreStateMachine::pushState Jun 14 23:11:01 volumiozonder volumio[15850]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioPushState Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreStateMachine::updateTrackBlock Jun 14 23:11:01 volumiozonder volumio[15850]: info: CorePlayQueue::getTrackBlock Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioRetrievevolume Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreStateMachine::setRepeat null single undefined Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreStateMachine::pushState Jun 14 23:11:01 volumiozonder volumio[15850]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioPushState Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreStateMachine::setRandom null Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreStateMachine::pushState Jun 14 23:11:01 volumiozonder volumio[15850]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioPushState Jun 14 23:11:01 volumiozonder volumio[15850]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 14 23:11:01 volumiozonder volumio[15850]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Jun 14 23:11:01 volumiozonder volumio[15850]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 14 23:11:01 volumiozonder volumio[15850]: info: Setting Device type: Raspberry PI Jun 14 23:11:01 volumiozonder volumio[15850]: info: Completed loading Core Plugins Jun 14 23:11:01 volumiozonder volumio[15850]: info: Preparing to generate the ALSA configuration file Jun 14 23:11:01 volumiozonder volumio[15850]: info: VolumeController:: Volume=100 Mute =false Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreStateMachine::pushState Jun 14 23:11:01 volumiozonder volumio[15850]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioPushState Jun 14 23:11:01 volumiozonder volumio[15850]: info: Asound.conf file unchanged, so no further update is needed Jun 14 23:11:01 volumiozonder volumio[15850]: info: Output device has changed, restarting MPD Jun 14 23:11:01 volumiozonder volumio[15850]: info: Output device has changed, restarting Shairport Sync Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:01 volumiozonder sudo[15970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 14 23:11:01 volumiozonder sudo[15970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:01 volumiozonder sudo[15970]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:01 volumiozonder sudo[15974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 14 23:11:01 volumiozonder sudo[15974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:01 volumiozonder volumio[15850]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 14 23:11:01 volumiozonder volumio[15850]: info: ___________ START PLUGINS ___________ Jun 14 23:11:01 volumiozonder volumio[15850]: info: ControllerMpd::onStart: Initializing MPD Jun 14 23:11:01 volumiozonder volumio[15850]: info: Creating MPD Configuration file Jun 14 23:11:01 volumiozonder systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:11:01 volumiozonder volumio[15850]: info: [1749935461909] CoreMusicLibrary::Adding element Media Servers Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:11:01 volumiozonder sudo[15982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 14 23:11:01 volumiozonder sudo[15982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:01 volumiozonder sudo[15980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 14 23:11:01 volumiozonder sudo[15980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:01 volumiozonder sudo[15980]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:01 volumiozonder volumio[15850]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:01 volumiozonder volumio[15850]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:11:01 volumiozonder volumio[15850]: info: [1749935461970] CoreMusicLibrary::Adding element Last_100 Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:11:01 volumiozonder volumio[15850]: info: [1749935461971] CoreMusicLibrary::Adding element Webradio Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:11:01 volumiozonder systemd[1]: mpd.service: Deactivated successfully. Jun 14 23:11:01 volumiozonder systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 14 23:11:01 volumiozonder systemd[1]: mpd.service: Consumed 1.811s CPU time. Jun 14 23:11:01 volumiozonder systemd[1]: mpd.socket: Deactivated successfully. Jun 14 23:11:01 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:11:01 volumiozonder systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 14 23:11:01 volumiozonder systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 14 23:11:01 volumiozonder volumio[15850]: info: Initializing BBC Radios Jun 14 23:11:02 volumiozonder systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 14 23:11:02 volumiozonder systemd[1]: Starting mpd.service - Music Player Daemon... Jun 14 23:11:02 volumiozonder volumio[15923]: Starting albumart workers Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: AutoStart - onStart Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:11:02 volumiozonder volumio[15850]: info: [1749935462038] CoreMusicLibrary::Adding element Mother Earth Radio Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:11:02 volumiozonder volumio[15850]: Cannot find translation for source Mother Earth Radio Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:11:02 volumiozonder volumio[15850]: info: [1749935462040] CoreMusicLibrary::Adding element Radio Paradise Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:11:02 volumiozonder volumio[15850]: Cannot find translation for source Mother Earth Radio Jun 14 23:11:02 volumiozonder volumio[15850]: Cannot find translation for source Radio Paradise Jun 14 23:11:02 volumiozonder volumio[15850]: info: Volumio Calling Home Jun 14 23:11:02 volumiozonder volumio[15925]: Starting albumart workers Jun 14 23:11:02 volumiozonder volumio[15924]: Starting albumart workers Jun 14 23:11:02 volumiozonder sudo[16002]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 14 23:11:02 volumiozonder sudo[16002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 14 23:11:02 volumiozonder sudo[16002]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:02 volumiozonder volumio[15850]: info: MPD Permissions set Jun 14 23:11:02 volumiozonder volumio[15850]: info: MPD Permissions set Jun 14 23:11:02 volumiozonder volumio[15850]: error: Upnp client error: Error: This socket has been ended by the other party Jun 14 23:11:02 volumiozonder volumio[15850]: info: Volumio called home Jun 14 23:11:02 volumiozonder volumio[15850]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:02 volumiozonder volumio[15850]: info: Starting Shairport Sync Jun 14 23:11:02 volumiozonder volumio[15850]: info: Starting Shairport Sync Jun 14 23:11:02 volumiozonder volumio[15850]: info: Starting Shairport Sync Jun 14 23:11:02 volumiozonder sudo[16046]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 14 23:11:02 volumiozonder sudo[16046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:02 volumiozonder sudo[16048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 14 23:11:02 volumiozonder sudo[16050]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 14 23:11:02 volumiozonder sudo[16048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:02 volumiozonder sudo[16050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:02 volumiozonder volumio[15850]: info: Discovery: adding dd8534c6-3a17-426a-ab31-aa30780df5c7 Jun 14 23:11:02 volumiozonder volumio[15850]: info: Discovery: Found device VolumioZonder Jun 14 23:11:02 volumiozonder systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioGetState Jun 14 23:11:02 volumiozonder volumio[15850]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:02 volumiozonder systemd[1]: shairport-sync.service: Deactivated successfully. Jun 14 23:11:02 volumiozonder systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 14 23:11:02 volumiozonder systemd[1]: shairport-sync.service: Consumed 1.575s CPU time. Jun 14 23:11:02 volumiozonder volumio[15850]: info: Discovery: this is already registered, dd8534c6-3a17-426a-ab31-aa30780df5c7 Jun 14 23:11:02 volumiozonder volumio[15850]: info: Discovery: Found device VolumioZonder Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioGetState Jun 14 23:11:02 volumiozonder volumio[15850]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:02 volumiozonder systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 14 23:11:02 volumiozonder sudo[16048]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:02 volumiozonder sudo[16050]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:02 volumiozonder sudo[16046]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:02 volumiozonder volumio[15850]: info: Shairport-Sync Started Jun 14 23:11:02 volumiozonder volumio[15850]: Error adding Membership: Error: addMembership EINVAL Jun 14 23:11:02 volumiozonder volumio[15850]: info: Shairport-Sync Started Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioGetState Jun 14 23:11:02 volumiozonder volumio[15850]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:02 volumiozonder volumio[15850]: info: Shairport-Sync Started Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:11:02 volumiozonder volumio[15850]: info: Discovery: Getting this device information Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioGetState Jun 14 23:11:02 volumiozonder volumio[15850]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:02 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:11:02 volumiozonder mpd[16012]: 2025-06-14T23:11:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 14 23:11:02 volumiozonder volumio[15850]: verbose: New Socket.io Connection to 192.168.68.70:3000 from 192.168.68.57 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Jun 14 23:11:02 volumiozonder systemd[1]: Started mpd.service - Music Player Daemon. Jun 14 23:11:02 volumiozonder sudo[15974]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:02 volumiozonder sudo[15982]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:02 volumiozonder volumio[15850]: info: Completed starting Core Plugins Jun 14 23:11:02 volumiozonder volumio[15850]: info: ------------------------------------------- Jun 14 23:11:02 volumiozonder volumio[15850]: info: ----- MyVolumio plugins startup ---- Jun 14 23:11:02 volumiozonder volumio[15850]: info: ------------------------------------------- Jun 14 23:11:02 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 14 23:11:03 volumiozonder volumio[15850]: error: MPD error: The expression evaluated to a falsy value: Jun 14 23:11:03 volumiozonder volumio[15850]: assert.ok(self.idling) Jun 14 23:11:03 volumiozonder volumio[15850]: error: The expression evaluated to a falsy value: Jun 14 23:11:03 volumiozonder volumio[15850]: assert.ok(self.idling) Jun 14 23:11:03 volumiozonder volumio[15850]: error: updateQueue error: null Jun 14 23:11:03 volumiozonder volumio[15850]: info: MPD running with PID16012 Jun 14 23:11:03 volumiozonder volumio[15850]: ,establishing connection Jun 14 23:11:03 volumiozonder volumio[15850]: error: updateQueue error: null Jun 14 23:11:03 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioGetState Jun 14 23:11:03 volumiozonder volumio[15850]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:03 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 14 23:11:03 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 14 23:11:04 volumiozonder volumio[15850]: verbose: New Socket.io Connection to 192.168.68.70 from 192.168.68.57 UA: Mozilla/5.0 (Linux; Android 15; SM-S938B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.61 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 14 23:11:04 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioGetState Jun 14 23:11:04 volumiozonder volumio[15850]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:04 volumiozonder volumio-remote-updater[918]: [2025-06-14 23:11:04] [connect] Successful connection Jun 14 23:11:04 volumiozonder volumio-remote-updater[918]: [2025-06-14 23:11:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1749935464 101 Jun 14 23:11:04 volumiozonder volumio[15850]: 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: 6 Jun 14 23:11:04 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 14 23:11:04 volumiozonder volumio[15850]: info: Listing playlists Jun 14 23:11:04 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioGetVisibleSources Jun 14 23:11:04 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:11:04 volumiozonder volumio[15850]: info: CoreCommandRouter::volumioGetQueue Jun 14 23:11:04 volumiozonder volumio[15850]: info: CoreStateMachine::getQueue Jun 14 23:11:04 volumiozonder volumio[15850]: info: CorePlayQueue::getQueue Jun 14 23:11:04 volumiozonder sudo[15888]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:05 volumiozonder volumio[15850]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Jun 14 23:11:06 volumiozonder wpa_supplicant[1207]: RRM: Ignoring radio measurement request: Not RRM network Jun 14 23:11:08 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 14 23:11:08 volumiozonder volumio[15850]: info: Preload queue cleared Jun 14 23:11:08 volumiozonder volumio[15850]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 14 23:11:09 volumiozonder volumio[15850]: Cannot compose Albumart path Jun 14 23:11:09 volumiozonder volumio[15850]: Cannot compose Albumart path Jun 14 23:11:09 volumiozonder volumio[15850]: Cannot compose Albumart path Jun 14 23:11:09 volumiozonder volumio[15850]: Cannot compose Albumart path Jun 14 23:11:09 volumiozonder volumio[15850]: Cannot compose Albumart path Jun 14 23:11:09 volumiozonder volumio[15850]: Cannot compose Albumart path Jun 14 23:11:09 volumiozonder volumio[15850]: Cannot compose Albumart path Jun 14 23:11:10 volumiozonder volumio[15850]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Jun 14 23:11:10 volumiozonder sudo[16099]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 14 23:11:10 volumiozonder sudo[16099]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:10 volumiozonder sudo[16101]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 14 23:11:10 volumiozonder sudo[16101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:10 volumiozonder sudo[16099]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:10 volumiozonder sudo[16101]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:10 volumiozonder sudo[16105]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 14 23:11:10 volumiozonder sudo[16105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:10 volumiozonder sudo[16105]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:10 volumiozonder volumio[15850]: info: Upmpdcli Daemon Started Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 14 23:11:11 volumiozonder volumio[15850]: info: Adding plugin bluetooth to MyMusic Plugins Jun 14 23:11:11 volumiozonder volumio[15850]: info: Adding plugin multiroom to MyMusic Plugins Jun 14 23:11:11 volumiozonder volumio[15850]: info: Adding plugin metavolumio to MyMusic Plugins Jun 14 23:11:11 volumiozonder volumio[15850]: info: Adding plugin cd_controller to MyMusic Plugins Jun 14 23:11:11 volumiozonder volumio[15850]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 14 23:11:11 volumiozonder volumio[15850]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 14 23:11:11 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:11 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:11 volumiozonder volumio[15850]: info: Starting MyVolumio Remote Streaming Endpoints Jun 14 23:11:11 volumiozonder volumio[15850]: info: MyVolumio login type: Token Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 14 23:11:11 volumiozonder volumio[15850]: info: Streaming services startup Jun 14 23:11:11 volumiozonder volumio[15850]: info: Starting Streaming Daemon Jun 14 23:11:11 volumiozonder sudo[16110]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 14 23:11:11 volumiozonder sudo[16110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:11 volumiozonder volumio[15850]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 14 23:11:11 volumiozonder sudo[16110]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:11 volumiozonder volumio[15850]: error: Cannot start Volumio Streaming Daemon Jun 14 23:11:11 volumiozonder volumio[15850]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 14 23:11:11 volumiozonder volumio[15850]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 14 23:11:12 volumiozonder volumio[15850]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 14 23:11:12 volumiozonder wpa_supplicant[1207]: RRM: Ignoring radio measurement request: Not RRM network Jun 14 23:11:12 volumiozonder volumio[15850]: info: MyVolumio token set successfully Jun 14 23:11:12 volumiozonder volumio[15850]: info: MYVOLUMIO: Adding device Jun 14 23:11:12 volumiozonder volumio[15850]: info: MYVOLUMIO: Evaluating Server Jun 14 23:11:12 volumiozonder volumio[15850]: info: MyVolumio status changed Jun 14 23:11:12 volumiozonder volumio[15850]: info: Streaming services startup Jun 14 23:11:12 volumiozonder volumio[15850]: info: Starting Streaming Daemon Jun 14 23:11:12 volumiozonder volumio[15850]: info: Removing browser output: myVolumio user plan is not superstar Jun 14 23:11:12 volumiozonder volumio[15850]: info: Removing audio output: Jun 14 23:11:12 volumiozonder volumio[15850]: info: Stoppping Tunnel 1 Jun 14 23:11:12 volumiozonder sudo[16137]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 14 23:11:12 volumiozonder sudo[16137]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:12 volumiozonder sudo[16139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 14 23:11:12 volumiozonder sudo[16139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:12 volumiozonder 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 14 23:11:12 volumiozonder 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 14 23:11:12 volumiozonder sudo[16137]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:12 volumiozonder volumio[15850]: error: Cannot start Volumio Streaming Daemon Jun 14 23:11:12 volumiozonder volumio[15850]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 14 23:11:12 volumiozonder volumio[15850]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 14 23:11:12 volumiozonder 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 14 23:11:12 volumiozonder 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 14 23:11:12 volumiozonder 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 14 23:11:12 volumiozonder 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 14 23:11:12 volumiozonder 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 14 23:11:12 volumiozonder 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 14 23:11:12 volumiozonder sudo[16139]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:12 volumiozonder volumio[15850]: info: Remote SSH Stopped Jun 14 23:11:13 volumiozonder volumio[15850]: info: Setting Geolocation for MyVolumio to eu2 Jun 14 23:11:13 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:13 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:13 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:13 volumiozonder volumio[15850]: Searching plugin music_service/webradio Jun 14 23:11:13 volumiozonder volumio[15850]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 14 23:11:13 volumiozonder volumio[15850]: Use XMLElement.setAttribute instead Jun 14 23:11:13 volumiozonder volumio[15850]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 23:11:13 volumiozonder volumio[15850]: TypeError: children[i].attr(...).value is not a function Jun 14 23:11:13 volumiozonder volumio[15850]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Jun 14 23:11:13 volumiozonder volumio[15850]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 14 23:11:13 volumiozonder volumio[15850]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 23:11:13 volumiozonder sudo[16158]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-14 23:10' Jun 14 23:11:13 volumiozonder sudo[16158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:13 volumiozonder sudo[16158]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:14 volumiozonder volumio-remote-updater[918]: [2025-06-14 23:11:14] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 14 23:11:14 volumiozonder volumio-remote-updater[918]: [2025-06-14 23:11:14] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 14 23:11:14 volumiozonder systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 14 23:11:14 volumiozonder systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 14 23:11:14 volumiozonder systemd[1]: volumio.service: Consumed 10.721s CPU time. Jun 14 23:11:14 volumiozonder systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 14 23:11:14 volumiozonder systemd[1]: dynamicswap.service: Deactivated successfully. Jun 14 23:11:14 volumiozonder systemd[1]: volumio.service: Scheduled restart job, restart counter is at 13. Jun 14 23:11:14 volumiozonder systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 14 23:11:14 volumiozonder systemd[1]: Stopped volumio.service - Volumio Backend Module. Jun 14 23:11:14 volumiozonder systemd[1]: volumio.service: Consumed 10.721s CPU time. Jun 14 23:11:14 volumiozonder systemd[1]: Started volumio.service - Volumio Backend Module. Jun 14 23:11:14 volumiozonder systemd[1]: dynamicswap.service: Deactivated successfully. Jun 14 23:11:14 volumiozonder volumio[16170]: info: ------------------------------------------- Jun 14 23:11:14 volumiozonder volumio[16170]: info: ----- Volumio3 ---- Jun 14 23:11:14 volumiozonder volumio[16170]: info: ------------------------------------------- Jun 14 23:11:14 volumiozonder volumio[16170]: info: ----- System startup ---- Jun 14 23:11:14 volumiozonder volumio[16170]: info: ------------------------------------------- Jun 14 23:11:15 volumiozonder volumio[16170]: info: MYVOLUMIO Environment detected Jun 14 23:11:15 volumiozonder volumio[16170]: info: Plugin folders cleanup Jun 14 23:11:15 volumiozonder volumio[16170]: info: Scanning into folder /volumio/app/plugins/ Jun 14 23:11:15 volumiozonder volumio[16170]: info: Scanning category audio_interface Jun 14 23:11:15 volumiozonder volumio[16170]: info: Scanning category miscellanea Jun 14 23:11:15 volumiozonder volumio[16170]: info: Scanning category music_service Jun 14 23:11:15 volumiozonder volumio[16170]: info: Scanning category plugins.json Jun 14 23:11:15 volumiozonder volumio[16170]: info: Scanning category system_controller Jun 14 23:11:15 volumiozonder volumio[16170]: info: Scanning category user_interface Jun 14 23:11:15 volumiozonder volumio[16170]: info: Scanning into folder /data/plugins/ Jun 14 23:11:15 volumiozonder volumio[16170]: info: Scanning category music_service Jun 14 23:11:15 volumiozonder volumio[16170]: info: Scanning category system_controller Jun 14 23:11:15 volumiozonder volumio[16170]: info: Scanning category user_interface Jun 14 23:11:15 volumiozonder volumio[16170]: info: Plugin folders cleanup completed Jun 14 23:11:15 volumiozonder volumio[16170]: info: ------------------------------------------- Jun 14 23:11:15 volumiozonder volumio[16170]: info: ----- Core plugins startup ---- Jun 14 23:11:15 volumiozonder volumio[16170]: info: ------------------------------------------- Jun 14 23:11:15 volumiozonder volumio[16170]: info: Loading plugins from folder /volumio/app/plugins/ Jun 14 23:11:15 volumiozonder volumio[16170]: info: Adding plugin upnp to MyMusic Plugins Jun 14 23:11:15 volumiozonder volumio[16170]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 14 23:11:15 volumiozonder volumio[16170]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 14 23:11:15 volumiozonder volumio[16170]: info: Loading plugins from folder /data/plugins/ Jun 14 23:11:15 volumiozonder volumio[16170]: info: Loading plugin "system"... Jun 14 23:11:15 volumiozonder volumio[16170]: info: Loading plugin "appearance"... Jun 14 23:11:15 volumiozonder volumio[16170]: info: Loading plugin "network"... Jun 14 23:11:15 volumiozonder volumio[16170]: info: Refreshing Cached IP Addresses Jun 14 23:11:15 volumiozonder sudo[16198]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 14 23:11:15 volumiozonder sudo[16198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:15 volumiozonder sudo[16200]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 14 23:11:15 volumiozonder sudo[16198]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:15 volumiozonder sudo[16200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:15 volumiozonder sudo[16200]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:15 volumiozonder volumio[16170]: info: Loading plugin "services"... Jun 14 23:11:15 volumiozonder volumio[16170]: info: Loading plugin "alsa_controller"... Jun 14 23:11:15 volumiozonder sudo[16208]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 14 23:11:15 volumiozonder sudo[16208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:15 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:11:15 volumiozonder volumio[16170]: info: Loading plugin "wizard"... Jun 14 23:11:15 volumiozonder volumio[16170]: info: Loading plugin "networkfs"... Jun 14 23:11:15 volumiozonder volumio[16170]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Jun 14 23:11:15 volumiozonder volumio[16170]: info: Starting Udev Watcher for removable devices Jun 14 23:11:15 volumiozonder volumio[16170]: info: Ignoring mount for partition: boot Jun 14 23:11:15 volumiozonder volumio[16170]: info: Ignoring mount for partition: volumio Jun 14 23:11:15 volumiozonder volumio[16170]: info: Ignoring mount for partition: volumio_data Jun 14 23:11:15 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 14 23:11:15 volumiozonder volumio[16170]: info: Loading plugin "volumio_command_line_client"... Jun 14 23:11:15 volumiozonder volumio[16170]: info: Loading plugin "upnp"... Jun 14 23:11:15 volumiozonder volumio[16170]: info: [1749935475452] Starting Upmpd Daemon Jun 14 23:11:15 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 14 23:11:15 volumiozonder volumio[16170]: info: Loading plugin "my_music"... Jun 14 23:11:15 volumiozonder volumio[16170]: info: Loading plugin "mpd"... Jun 14 23:11:15 volumiozonder volumio[16170]: info: Loading plugin "upnp_browser"... Jun 14 23:11:15 volumiozonder volumio[16170]: info: Loading plugin "alarm-clock"... Jun 14 23:11:16 volumiozonder volumio[16170]: info: Loading plugin "airplay_emulation"... Jun 14 23:11:16 volumiozonder volumio[16170]: info: Starting Shairport Sync Jun 14 23:11:16 volumiozonder volumio[16170]: info: Loading plugin "last_100"... Jun 14 23:11:16 volumiozonder volumio[16170]: info: Loading plugin "webradio"... Jun 14 23:11:16 volumiozonder volumio[16170]: info: Loading plugin "i2s_dacs"... Jun 14 23:11:16 volumiozonder volumio[16170]: info: I2S DAC not set, start Auto-detection Jun 14 23:11:16 volumiozonder volumio[16170]: info: Loading plugin "volumiodiscovery"... Jun 14 23:11:16 volumiozonder volumio[16170]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 14 23:11:16 volumiozonder volumio[16170]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 23:11:16 volumiozonder volumio[16170]: *** WARNING *** For more information see Jun 14 23:11:16 volumiozonder volumio[16170]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 14 23:11:16 volumiozonder volumio[16170]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 23:11:16 volumiozonder volumio[16170]: *** WARNING *** For more information see Jun 14 23:11:16 volumiozonder node[16170]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 14 23:11:16 volumiozonder node[16170]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 23:11:16 volumiozonder node[16170]: *** WARNING *** For more information see Jun 14 23:11:16 volumiozonder node[16170]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 14 23:11:16 volumiozonder node[16170]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 23:11:16 volumiozonder node[16170]: *** WARNING *** For more information see Jun 14 23:11:16 volumiozonder volumio[16170]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 14 23:11:16 volumiozonder volumio[16170]: info: Discovery: Started advertising with name: VolumioZonder Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 14 23:11:16 volumiozonder volumio[16170]: info: Loading plugin "autostart"... Jun 14 23:11:16 volumiozonder volumio[16170]: info: Applying required configuration parameters for plugin autostart Jun 14 23:11:16 volumiozonder volumio[16170]: info: AutoStart - onVolumioStart - read config.json Jun 14 23:11:16 volumiozonder volumio[16170]: info: Loading plugin "outputs"... Jun 14 23:11:16 volumiozonder volumio[16170]: info: Loading plugin "albumart"... Jun 14 23:11:16 volumiozonder volumio[16170]: info: Plugin example_plugin is not enabled Jun 14 23:11:16 volumiozonder volumio[16170]: info: Loading plugin "inputs"... Jun 14 23:11:16 volumiozonder volumio[16170]: info: Loading plugin "updater_comm"... Jun 14 23:11:16 volumiozonder volumio[16170]: info: Plugin mpdemulation is not enabled Jun 14 23:11:16 volumiozonder volumio[16170]: info: Loading plugin "rest_api"... Jun 14 23:11:16 volumiozonder volumio[16170]: info: Loading plugin "websocket"... Jun 14 23:11:16 volumiozonder volumio[16170]: info: Starting Socket.io Server version 1.7.4 Jun 14 23:11:16 volumiozonder volumio[16170]: info: Loading plugin "motherearthradio"... Jun 14 23:11:16 volumiozonder volumio[16170]: info: Applying required configuration parameters for plugin motherearthradio Jun 14 23:11:16 volumiozonder volumio[16170]: info: [1749935476244] [MotherEarth] API delay: 5 Jun 14 23:11:16 volumiozonder volumio[16170]: info: Loading plugin "radio_paradise"... Jun 14 23:11:16 volumiozonder volumio[16170]: info: Applying required configuration parameters for plugin radio_paradise Jun 14 23:11:16 volumiozonder volumio[16170]: info: [1749935476298] [RadioParadise] API delay: 5 Jun 14 23:11:16 volumiozonder volumio[16170]: info: Loading plugin "Systeminfo"... Jun 14 23:11:16 volumiozonder volumio[16233]: Forking 3 albumart workers Jun 14 23:11:16 volumiozonder volumio[16170]: info: Loading i18n strings for locale nl Jun 14 23:11:16 volumiozonder volumio[16170]: Updating browse sources language Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::initPlayerControls Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 23:11:16 volumiozonder volumio[16170]: Express server listening on port 3000 Jun 14 23:11:16 volumiozonder volumio[16170]: [Metrics] WebUI: 1s 759.01ms Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreStateMachine::resetVolumioState Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreStateMachine::getcurrentVolume Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioRetrievevolume Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:16 volumiozonder volumio[16170]: info: Volumio Network Manager: Network status updated: 2 Jun 14 23:11:16 volumiozonder volumio[16170]: verbose: New Socket.io Connection to 192.168.68.70:3000 from 192.168.68.57 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Jun 14 23:11:16 volumiozonder volumio[16170]: verbose: New Socket.io Connection to 192.168.68.70 from 192.168.68.57 UA: Mozilla/5.0 (Linux; Android 15; SM-S938B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.61 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 14 23:11:16 volumiozonder volumio[16170]: info: VolumeController:: Volume=100 Mute =false Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreStateMachine::pushState Jun 14 23:11:16 volumiozonder volumio[16170]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioPushState Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreStateMachine::updateTrackBlock Jun 14 23:11:16 volumiozonder volumio[16170]: info: CorePlayQueue::getTrackBlock Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioRetrievevolume Jun 14 23:11:16 volumiozonder volumio[16170]: info: Reloading queue from file Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreStateMachine::setRepeat null single undefined Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreStateMachine::pushState Jun 14 23:11:16 volumiozonder volumio[16170]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioPushState Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreStateMachine::setRandom null Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreStateMachine::pushState Jun 14 23:11:16 volumiozonder volumio[16170]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioPushState Jun 14 23:11:16 volumiozonder volumio[16170]: info: Setting Device type: Raspberry PI Jun 14 23:11:16 volumiozonder volumio[16170]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 14 23:11:16 volumiozonder volumio[16170]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Jun 14 23:11:16 volumiozonder volumio[16170]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 14 23:11:16 volumiozonder volumio[16170]: info: Completed loading Core Plugins Jun 14 23:11:16 volumiozonder volumio[16170]: info: Preparing to generate the ALSA configuration file Jun 14 23:11:16 volumiozonder volumio[16170]: info: VolumeController:: Volume=100 Mute =false Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreStateMachine::pushState Jun 14 23:11:16 volumiozonder volumio[16170]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioPushState Jun 14 23:11:16 volumiozonder volumio[16170]: info: Asound.conf file unchanged, so no further update is needed Jun 14 23:11:16 volumiozonder volumio[16170]: info: Output device has changed, restarting MPD Jun 14 23:11:16 volumiozonder volumio[16170]: info: Output device has changed, restarting Shairport Sync Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:16 volumiozonder sudo[16291]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 14 23:11:16 volumiozonder sudo[16291]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:16 volumiozonder sudo[16293]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 14 23:11:16 volumiozonder sudo[16291]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:16 volumiozonder sudo[16293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:16 volumiozonder volumio[16170]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 14 23:11:16 volumiozonder volumio[16170]: info: ___________ START PLUGINS ___________ Jun 14 23:11:16 volumiozonder volumio[16170]: info: ControllerMpd::onStart: Initializing MPD Jun 14 23:11:16 volumiozonder volumio[16170]: info: Creating MPD Configuration file Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:11:16 volumiozonder volumio[16170]: info: [1749935476676] CoreMusicLibrary::Adding element Media Servers Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:11:16 volumiozonder sudo[16301]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 14 23:11:16 volumiozonder sudo[16301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:16 volumiozonder sudo[16303]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 14 23:11:16 volumiozonder sudo[16303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:16 volumiozonder volumio[16170]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 14 23:11:16 volumiozonder sudo[16301]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:16 volumiozonder volumio[16170]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:11:16 volumiozonder volumio[16170]: info: [1749935476717] CoreMusicLibrary::Adding element Last_100 Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:11:16 volumiozonder volumio[16170]: info: [1749935476718] CoreMusicLibrary::Adding element Webradio Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:11:16 volumiozonder volumio[16244]: Starting albumart workers Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:11:16 volumiozonder systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 14 23:11:16 volumiozonder volumio[16170]: info: Initializing BBC Radios Jun 14 23:11:16 volumiozonder systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 14 23:11:16 volumiozonder dbus-daemon[907]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.181' (uid=0 pid=16318 comm="timedatectl show --property=NTPSynchronized --valu") Jun 14 23:11:16 volumiozonder systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 14 23:11:16 volumiozonder systemd[1]: mpd.service: Deactivated successfully. Jun 14 23:11:16 volumiozonder systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 14 23:11:16 volumiozonder systemd[1]: mpd.service: Consumed 1.182s CPU time. Jun 14 23:11:16 volumiozonder systemd[1]: mpd.socket: Deactivated successfully. Jun 14 23:11:16 volumiozonder systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 14 23:11:16 volumiozonder systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 14 23:11:16 volumiozonder volumio[16245]: Starting albumart workers Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:16 volumiozonder volumio[16170]: info: AutoStart - onStart Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:11:16 volumiozonder volumio[16170]: info: [1749935476824] CoreMusicLibrary::Adding element Mother Earth Radio Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:11:16 volumiozonder volumio[16170]: Cannot find translation for source Mother Earth Radio Jun 14 23:11:16 volumiozonder systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:11:16 volumiozonder volumio[16170]: info: [1749935476827] CoreMusicLibrary::Adding element Radio Paradise Jun 14 23:11:16 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:11:16 volumiozonder volumio[16170]: Cannot find translation for source Mother Earth Radio Jun 14 23:11:16 volumiozonder volumio[16170]: Cannot find translation for source Radio Paradise Jun 14 23:11:16 volumiozonder volumio[16170]: info: Volumio Calling Home Jun 14 23:11:16 volumiozonder systemd[1]: Starting mpd.service - Music Player Daemon... Jun 14 23:11:16 volumiozonder volumio[16243]: Starting albumart workers Jun 14 23:11:16 volumiozonder dbus-daemon[907]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 14 23:11:16 volumiozonder systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 14 23:11:16 volumiozonder setdatetime-helper.sh[16304]: Time is already synchronized. Jun 14 23:11:16 volumiozonder systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 14 23:11:16 volumiozonder systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 14 23:11:16 volumiozonder sudo[16332]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 14 23:11:16 volumiozonder sudo[16332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 14 23:11:16 volumiozonder sudo[16332]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:17 volumiozonder volumio[16170]: info: MPD Permissions set Jun 14 23:11:17 volumiozonder volumio[16170]: info: MPD Permissions set Jun 14 23:11:17 volumiozonder volumio[16170]: info: Volumio called home Jun 14 23:11:17 volumiozonder volumio[16170]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:11:17 volumiozonder volumio[16170]: info: Starting Shairport Sync Jun 14 23:11:17 volumiozonder volumio[16170]: info: Starting Shairport Sync Jun 14 23:11:17 volumiozonder volumio[16170]: info: Starting Shairport Sync Jun 14 23:11:17 volumiozonder sudo[16363]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 14 23:11:17 volumiozonder sudo[16363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:17 volumiozonder sudo[16368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 14 23:11:17 volumiozonder sudo[16368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:17 volumiozonder sudo[16369]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 14 23:11:17 volumiozonder sudo[16369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:17 volumiozonder systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 14 23:11:17 volumiozonder volumio[16170]: info: Discovery: adding dd8534c6-3a17-426a-ab31-aa30780df5c7 Jun 14 23:11:17 volumiozonder volumio[16170]: info: Discovery: Found device VolumioZonder Jun 14 23:11:17 volumiozonder systemd[1]: shairport-sync.service: Deactivated successfully. Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioGetState Jun 14 23:11:17 volumiozonder volumio[16170]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:17 volumiozonder systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 14 23:11:17 volumiozonder systemd[1]: shairport-sync.service: Consumed 1.551s CPU time. Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioGetState Jun 14 23:11:17 volumiozonder volumio[16170]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:17 volumiozonder systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 14 23:11:17 volumiozonder sudo[16368]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:17 volumiozonder sudo[16363]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:17 volumiozonder sudo[16369]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:17 volumiozonder volumio[16170]: info: Shairport-Sync Started Jun 14 23:11:17 volumiozonder volumio[16170]: Error adding Membership: Error: addMembership EINVAL Jun 14 23:11:17 volumiozonder volumio[16170]: info: Shairport-Sync Started Jun 14 23:11:17 volumiozonder volumio[16170]: info: Shairport-Sync Started Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:11:17 volumiozonder volumio[16170]: info: Discovery: Getting this device information Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioGetState Jun 14 23:11:17 volumiozonder volumio[16170]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:11:17 volumiozonder volumio[16170]: info: Discovery: this is already registered, dd8534c6-3a17-426a-ab31-aa30780df5c7 Jun 14 23:11:17 volumiozonder volumio[16170]: info: Discovery: Found device VolumioZonder Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioGetState Jun 14 23:11:17 volumiozonder volumio[16170]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:17 volumiozonder volumio[16170]: verbose: New Socket.io Connection to 192.168.68.70:3000 from 192.168.68.57 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioGetState Jun 14 23:11:17 volumiozonder volumio[16170]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 14 23:11:17 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 14 23:11:17 volumiozonder mpd[16337]: 2025-06-14T23:11:17 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 14 23:11:17 volumiozonder systemd[1]: Started mpd.service - Music Player Daemon. Jun 14 23:11:17 volumiozonder sudo[16303]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:17 volumiozonder sudo[16293]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:17 volumiozonder volumio[16170]: info: Completed starting Core Plugins Jun 14 23:11:17 volumiozonder volumio[16170]: info: ------------------------------------------- Jun 14 23:11:17 volumiozonder volumio[16170]: info: ----- MyVolumio plugins startup ---- Jun 14 23:11:17 volumiozonder volumio[16170]: info: ------------------------------------------- Jun 14 23:11:17 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 14 23:11:17 volumiozonder volumio[16170]: error: MPD error: The expression evaluated to a falsy value: Jun 14 23:11:17 volumiozonder volumio[16170]: assert.ok(self.idling) Jun 14 23:11:17 volumiozonder volumio[16170]: error: The expression evaluated to a falsy value: Jun 14 23:11:17 volumiozonder volumio[16170]: assert.ok(self.idling) Jun 14 23:11:17 volumiozonder volumio[16170]: info: MPD running with PID16337 Jun 14 23:11:17 volumiozonder volumio[16170]: ,establishing connection Jun 14 23:11:17 volumiozonder volumio[16170]: error: updateQueue error: null Jun 14 23:11:17 volumiozonder volumio[16170]: error: updateQueue error: null Jun 14 23:11:18 volumiozonder wpa_supplicant[1207]: RRM: Ignoring radio measurement request: Not RRM network Jun 14 23:11:18 volumiozonder volumio[16170]: verbose: New Socket.io Connection to 192.168.68.70 from 192.168.68.57 UA: Mozilla/5.0 (Linux; Android 15; SM-S938B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.61 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 14 23:11:19 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioGetState Jun 14 23:11:19 volumiozonder volumio[16170]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:19 volumiozonder volumio-remote-updater[918]: [2025-06-14 23:11:19] [connect] Successful connection Jun 14 23:11:19 volumiozonder volumio-remote-updater[918]: [2025-06-14 23:11:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1749935479 101 Jun 14 23:11:19 volumiozonder volumio[16170]: 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: 6 Jun 14 23:11:19 volumiozonder sudo[16208]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:19 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 14 23:11:19 volumiozonder volumio[16170]: info: Listing playlists Jun 14 23:11:19 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioGetVisibleSources Jun 14 23:11:19 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:11:19 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioGetQueue Jun 14 23:11:19 volumiozonder volumio[16170]: info: CoreStateMachine::getQueue Jun 14 23:11:19 volumiozonder volumio[16170]: info: CorePlayQueue::getQueue Jun 14 23:11:20 volumiozonder volumio[16170]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Jun 14 23:11:23 volumiozonder volumio[16170]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 14 23:11:24 volumiozonder volumio[16170]: Cannot compose Albumart path Jun 14 23:11:24 volumiozonder volumio[16170]: Cannot compose Albumart path Jun 14 23:11:24 volumiozonder volumio[16170]: Cannot compose Albumart path Jun 14 23:11:24 volumiozonder volumio[16170]: Cannot compose Albumart path Jun 14 23:11:24 volumiozonder volumio[16170]: Cannot compose Albumart path Jun 14 23:11:24 volumiozonder volumio[16170]: Cannot compose Albumart path Jun 14 23:11:24 volumiozonder volumio[16170]: Cannot compose Albumart path Jun 14 23:11:24 volumiozonder wpa_supplicant[1207]: RRM: Ignoring radio measurement request: Not RRM network Jun 14 23:11:25 volumiozonder volumio[16170]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Jun 14 23:11:25 volumiozonder sudo[16414]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 14 23:11:25 volumiozonder sudo[16414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:25 volumiozonder sudo[16414]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:25 volumiozonder sudo[16416]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 14 23:11:25 volumiozonder sudo[16416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:25 volumiozonder sudo[16416]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:25 volumiozonder sudo[16419]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 14 23:11:25 volumiozonder sudo[16419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:25 volumiozonder sudo[16419]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:25 volumiozonder volumio[16170]: info: Upmpdcli Daemon Started Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 14 23:11:26 volumiozonder volumio[16170]: info: Adding plugin bluetooth to MyMusic Plugins Jun 14 23:11:26 volumiozonder volumio[16170]: info: Adding plugin multiroom to MyMusic Plugins Jun 14 23:11:26 volumiozonder volumio[16170]: info: Adding plugin metavolumio to MyMusic Plugins Jun 14 23:11:26 volumiozonder volumio[16170]: info: Adding plugin cd_controller to MyMusic Plugins Jun 14 23:11:26 volumiozonder volumio[16170]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 14 23:11:26 volumiozonder volumio[16170]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 14 23:11:26 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:26 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:26 volumiozonder volumio[16170]: info: Starting MyVolumio Remote Streaming Endpoints Jun 14 23:11:26 volumiozonder volumio[16170]: info: MyVolumio login type: Token Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 14 23:11:26 volumiozonder volumio[16170]: info: Streaming services startup Jun 14 23:11:26 volumiozonder volumio[16170]: info: Starting Streaming Daemon Jun 14 23:11:26 volumiozonder sudo[16423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 14 23:11:26 volumiozonder sudo[16423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:26 volumiozonder volumio[16170]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 14 23:11:26 volumiozonder sudo[16423]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:26 volumiozonder volumio[16170]: error: Cannot start Volumio Streaming Daemon Jun 14 23:11:26 volumiozonder volumio[16170]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 14 23:11:26 volumiozonder volumio[16170]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 14 23:11:27 volumiozonder volumio[16170]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 14 23:11:27 volumiozonder volumio[16170]: info: MyVolumio token set successfully Jun 14 23:11:27 volumiozonder volumio[16170]: info: MYVOLUMIO: Adding device Jun 14 23:11:27 volumiozonder volumio[16170]: info: MYVOLUMIO: Evaluating Server Jun 14 23:11:27 volumiozonder volumio[16170]: info: MyVolumio status changed Jun 14 23:11:27 volumiozonder volumio[16170]: info: Streaming services startup Jun 14 23:11:27 volumiozonder volumio[16170]: info: Starting Streaming Daemon Jun 14 23:11:27 volumiozonder volumio[16170]: info: Removing browser output: myVolumio user plan is not superstar Jun 14 23:11:27 volumiozonder volumio[16170]: info: Removing audio output: Jun 14 23:11:27 volumiozonder volumio[16170]: info: Stoppping Tunnel 1 Jun 14 23:11:27 volumiozonder sudo[16448]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 14 23:11:27 volumiozonder sudo[16448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:27 volumiozonder sudo[16450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 14 23:11:27 volumiozonder sudo[16450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:11:27 volumiozonder sudo[16448]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:27 volumiozonder volumio[16170]: error: Cannot start Volumio Streaming Daemon Jun 14 23:11:27 volumiozonder volumio[16170]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 14 23:11:27 volumiozonder volumio[16170]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 14 23:11:27 volumiozonder 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 14 23:11:27 volumiozonder 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 14 23:11:28 volumiozonder 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 14 23:11:28 volumiozonder 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 14 23:11:28 volumiozonder 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 14 23:11:28 volumiozonder 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 14 23:11:28 volumiozonder 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 14 23:11:28 volumiozonder 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 14 23:11:28 volumiozonder sudo[16450]: pam_unix(sudo:session): session closed for user root Jun 14 23:11:28 volumiozonder volumio[16170]: info: Remote SSH Stopped Jun 14 23:11:28 volumiozonder volumio[16170]: info: Setting Geolocation for MyVolumio to eu2 Jun 14 23:11:28 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:28 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:28 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:28 volumiozonder volumio[16170]: info: Successfully Added MyVolumio device Jun 14 23:11:29 volumiozonder volumio[16170]: info: Updating MyVolumio device info Jun 14 23:11:29 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:29 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:29 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:11:30 volumiozonder volumio[16170]: info: Successfully Updated MyVolumio device Jun 14 23:11:30 volumiozonder volumio[16170]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Jun 14 23:11:30 volumiozonder wpa_supplicant[1207]: RRM: Ignoring radio measurement request: Not RRM network Jun 14 23:11:31 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:11:31 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:11:31 volumiozonder volumio[16170]: info: Discovery: Getting this device information Jun 14 23:11:31 volumiozonder volumio[16170]: info: CoreCommandRouter::volumioGetState Jun 14 23:11:31 volumiozonder volumio[16170]: info: CorePlayQueue::getTrack 0 Jun 14 23:11:31 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:11:32 volumiozonder volumio[16170]: Searching plugin music_service/webradio Jun 14 23:11:32 volumiozonder volumio[16170]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 14 23:11:32 volumiozonder volumio[16170]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 23:11:32 volumiozonder volumio[16170]: TypeError: Cannot read properties of undefined (reading 'length') Jun 14 23:11:32 volumiozonder volumio[16170]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:186:126 Jun 14 23:11:32 volumiozonder volumio[16170]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Jun 14 23:11:32 volumiozonder volumio[16170]: at /volumio/app/plugins/music_service/webradio/index.js:949:31 Jun 14 23:11:32 volumiozonder volumio[16170]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 14 23:11:32 volumiozonder volumio[16170]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 23:11:33 volumiozonder sudo[16467]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-14 23:10' Jun 14 23:11:33 volumiozonder sudo[16467]: 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="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 14 07:24:14 UTC 2025" VOLUMIO_VERSION="4.012" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b365b054264e3ebc2709665da2b5d1ee"