Apr 25 12:03:00 volumio volumio[3205]: Use XMLElement.setAttribute instead Apr 25 12:03:00 volumio volumio[3205]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 12:03:00 volumio volumio[3205]: TypeError: children[i].attr(...).value is not a function Apr 25 12:03:00 volumio volumio[3205]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Apr 25 12:03:00 volumio volumio[3205]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 25 12:03:00 volumio volumio[3205]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 12:03:00 volumio sudo[3771]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-04-25 12:02' Apr 25 12:03:00 volumio sudo[3771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:00 volumio sudo[3771]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:00 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 25 12:03:00 volumio volumio-remote-updater[870]: [2025-04-25 12:03:00] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 25 12:03:00 volumio volumio-remote-updater[870]: [2025-04-25 12:03:00] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 25 12:03:00 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 25 12:03:00 volumio systemd[1]: volumio.service: Consumed 6.616s CPU time. Apr 25 12:03:00 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 25 12:03:00 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Apr 25 12:03:00 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Apr 25 12:03:00 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 25 12:03:00 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 25 12:03:00 volumio systemd[1]: volumio.service: Consumed 6.616s CPU time. Apr 25 12:03:00 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 25 12:03:00 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Apr 25 12:03:00 volumio volumio[3785]: info: ------------------------------------------- Apr 25 12:03:00 volumio volumio[3785]: info: ----- Volumio3 ---- Apr 25 12:03:00 volumio volumio[3785]: info: ------------------------------------------- Apr 25 12:03:00 volumio volumio[3785]: info: ----- System startup ---- Apr 25 12:03:00 volumio volumio[3785]: info: ------------------------------------------- Apr 25 12:03:01 volumio volumio[3785]: info: MYVOLUMIO Environment detected Apr 25 12:03:01 volumio volumio[3785]: info: Plugin folders cleanup Apr 25 12:03:01 volumio volumio[3785]: info: Scanning into folder /volumio/app/plugins/ Apr 25 12:03:01 volumio volumio[3785]: info: Scanning category audio_interface Apr 25 12:03:01 volumio volumio[3785]: info: Scanning category miscellanea Apr 25 12:03:01 volumio volumio[3785]: info: Scanning category music_service Apr 25 12:03:01 volumio volumio[3785]: info: Scanning category plugins.json Apr 25 12:03:01 volumio volumio[3785]: info: Scanning category system_controller Apr 25 12:03:01 volumio volumio[3785]: info: Scanning category user_interface Apr 25 12:03:01 volumio volumio[3785]: info: Scanning into folder /data/plugins/ Apr 25 12:03:01 volumio volumio[3785]: info: Plugin folders cleanup completed Apr 25 12:03:01 volumio volumio[3785]: info: ------------------------------------------- Apr 25 12:03:01 volumio volumio[3785]: info: ----- Core plugins startup ---- Apr 25 12:03:01 volumio volumio[3785]: info: ------------------------------------------- Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugins from folder /volumio/app/plugins/ Apr 25 12:03:01 volumio volumio[3785]: info: Adding plugin upnp to MyMusic Plugins Apr 25 12:03:01 volumio volumio[3785]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 25 12:03:01 volumio volumio[3785]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugins from folder /data/plugins/ Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "system"... Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "appearance"... Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "network"... Apr 25 12:03:01 volumio volumio[3785]: info: Refreshing Cached IP Addresses Apr 25 12:03:01 volumio sudo[3813]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 25 12:03:01 volumio sudo[3813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:01 volumio sudo[3815]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 25 12:03:01 volumio sudo[3815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:01 volumio sudo[3813]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:01 volumio sudo[3815]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "services"... Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "alsa_controller"... Apr 25 12:03:01 volumio sudo[3820]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 25 12:03:01 volumio sudo[3820]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "wizard"... Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "networkfs"... Apr 25 12:03:01 volumio volumio[3785]: info: Starting Udev Watcher for removable devices Apr 25 12:03:01 volumio volumio[3785]: info: Ignoring mount for partition: boot Apr 25 12:03:01 volumio volumio[3785]: info: Ignoring mount for partition: volumio Apr 25 12:03:01 volumio volumio[3785]: info: Ignoring mount for partition: volumio_data Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "volumio_command_line_client"... Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "upnp"... Apr 25 12:03:01 volumio volumio[3785]: info: [1745575381399] Starting Upmpd Daemon Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "my_music"... Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "mpd"... Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "upnp_browser"... Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "alarm-clock"... Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "airplay_emulation"... Apr 25 12:03:01 volumio volumio[3785]: info: Starting Shairport Sync Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "last_100"... Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "webradio"... Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "i2s_dacs"... Apr 25 12:03:01 volumio volumio[3785]: info: I2S DAC not set, start Auto-detection Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "volumiodiscovery"... Apr 25 12:03:01 volumio volumio[3785]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 25 12:03:01 volumio node[3785]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 25 12:03:01 volumio volumio[3785]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:03:01 volumio node[3785]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:03:01 volumio volumio[3785]: *** WARNING *** For more information see Apr 25 12:03:01 volumio node[3785]: *** WARNING *** For more information see Apr 25 12:03:01 volumio volumio[3785]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 25 12:03:01 volumio node[3785]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 25 12:03:01 volumio volumio[3785]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:03:01 volumio node[3785]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:03:01 volumio volumio[3785]: *** WARNING *** For more information see Apr 25 12:03:01 volumio node[3785]: *** WARNING *** For more information see Apr 25 12:03:01 volumio volumio[3785]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 25 12:03:01 volumio volumio[3785]: info: Discovery: Started advertising with name: Volumio Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "outputs"... Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "albumart"... Apr 25 12:03:01 volumio volumio[3785]: info: Plugin example_plugin is not enabled Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "inputs"... Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "updater_comm"... Apr 25 12:03:01 volumio volumio[3785]: info: Plugin mpdemulation is not enabled Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "rest_api"... Apr 25 12:03:01 volumio volumio[3785]: info: Loading plugin "websocket"... Apr 25 12:03:01 volumio volumio[3785]: info: Starting Socket.io Server version 1.7.4 Apr 25 12:03:01 volumio volumio[3785]: info: Loading i18n strings for locale it Apr 25 12:03:01 volumio volumio[3785]: Updating browse sources language Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::initPlayerControls Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:03:01 volumio volumio[3785]: Express server listening on port 3000 Apr 25 12:03:01 volumio volumio[3785]: [Metrics] WebUI: 1s 19.61ms Apr 25 12:03:01 volumio volumio[3785]: info: CoreStateMachine::resetVolumioState Apr 25 12:03:01 volumio volumio[3785]: info: CoreStateMachine::getcurrentVolume Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:01 volumio volumio[3785]: info: Volumio Network Manager: Network status updated: 2 Apr 25 12:03:01 volumio volumio[3785]: Unable to parse: Apr 25 12:03:01 volumio volumio[3785]: Simple mixer control 'Master',0 Apr 25 12:03:01 volumio volumio[3785]: Capabilities: volume pswitch pswitch-joined Apr 25 12:03:01 volumio volumio[3785]: Playback channels: Front Left - Front Right Apr 25 12:03:01 volumio volumio[3785]: Capture channels: Front Left - Front Right Apr 25 12:03:01 volumio volumio[3785]: Limits: 0 - 128 Apr 25 12:03:01 volumio volumio[3785]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:01 volumio volumio[3785]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:01 volumio volumio[3785]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 25 12:03:01 volumio volumio[3785]: info: Reloading queue from file Apr 25 12:03:01 volumio volumio[3848]: Forking 3 albumart workers Apr 25 12:03:01 volumio volumio[3785]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Apr 25 12:03:01 volumio volumio[3785]: Unable to parse: Apr 25 12:03:01 volumio volumio[3785]: Simple mixer control 'Master',0 Apr 25 12:03:01 volumio volumio[3785]: Capabilities: volume pswitch pswitch-joined Apr 25 12:03:01 volumio volumio[3785]: Playback channels: Front Left - Front Right Apr 25 12:03:01 volumio volumio[3785]: Capture channels: Front Left - Front Right Apr 25 12:03:01 volumio volumio[3785]: Limits: 0 - 128 Apr 25 12:03:01 volumio volumio[3785]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:01 volumio volumio[3785]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:01 volumio volumio[3785]: info: VolumeController:: Volume=undefined Mute =false Apr 25 12:03:01 volumio volumio[3785]: info: CoreStateMachine::pushState Apr 25 12:03:01 volumio volumio[3785]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::volumioPushState Apr 25 12:03:01 volumio volumio[3785]: info: CoreStateMachine::updateTrackBlock Apr 25 12:03:01 volumio volumio[3785]: info: CorePlayQueue::getTrackBlock Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 12:03:01 volumio volumio[3785]: info: CoreStateMachine::setRepeat null single undefined Apr 25 12:03:01 volumio volumio[3785]: info: CoreStateMachine::pushState Apr 25 12:03:01 volumio volumio[3785]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::volumioPushState Apr 25 12:03:01 volumio volumio[3785]: info: CoreStateMachine::setRandom null Apr 25 12:03:01 volumio volumio[3785]: info: CoreStateMachine::pushState Apr 25 12:03:01 volumio volumio[3785]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::volumioPushState Apr 25 12:03:01 volumio volumio[3785]: info: Setting Device type: Raspberry PI Apr 25 12:03:01 volumio volumio[3785]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 25 12:03:01 volumio volumio[3785]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b04170 Apr 25 12:03:01 volumio volumio[3785]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 25 12:03:01 volumio volumio[3785]: Unable to parse: Apr 25 12:03:01 volumio volumio[3785]: Simple mixer control 'Master',0 Apr 25 12:03:01 volumio volumio[3785]: Capabilities: volume pswitch pswitch-joined Apr 25 12:03:01 volumio volumio[3785]: Playback channels: Front Left - Front Right Apr 25 12:03:01 volumio volumio[3785]: Capture channels: Front Left - Front Right Apr 25 12:03:01 volumio volumio[3785]: Limits: 0 - 128 Apr 25 12:03:01 volumio volumio[3785]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:01 volumio volumio[3785]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:01 volumio volumio[3785]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 25 12:03:01 volumio volumio[3785]: info: Completed loading Core Plugins Apr 25 12:03:01 volumio volumio[3785]: info: Preparing to generate the ALSA configuration file Apr 25 12:03:01 volumio volumio[3785]: Unable to parse: Apr 25 12:03:01 volumio volumio[3785]: Simple mixer control 'Master',0 Apr 25 12:03:01 volumio volumio[3785]: Capabilities: volume pswitch pswitch-joined Apr 25 12:03:01 volumio volumio[3785]: Playback channels: Front Left - Front Right Apr 25 12:03:01 volumio volumio[3785]: Capture channels: Front Left - Front Right Apr 25 12:03:01 volumio volumio[3785]: Limits: 0 - 128 Apr 25 12:03:01 volumio volumio[3785]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:01 volumio volumio[3785]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:01 volumio volumio[3785]: info: VolumeController:: Volume=undefined Mute =false Apr 25 12:03:01 volumio volumio[3785]: info: CoreStateMachine::pushState Apr 25 12:03:01 volumio volumio[3785]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:01 volumio volumio[3785]: info: CoreCommandRouter::volumioPushState Apr 25 12:03:01 volumio volumio[3785]: info: Asound.conf file unchanged, so no further update is needed Apr 25 12:03:01 volumio volumio[3785]: info: Output device has changed, restarting MPD Apr 25 12:03:02 volumio volumio[3785]: info: Output device has changed, restarting Shairport Sync Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:02 volumio sudo[3903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 12:03:02 volumio sudo[3905]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 12:03:02 volumio sudo[3903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:02 volumio sudo[3905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:02 volumio sudo[3903]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:02 volumio volumio[3785]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:03:02 volumio volumio[3785]: info: ___________ START PLUGINS ___________ Apr 25 12:03:02 volumio volumio[3785]: info: ControllerMpd::onStart: Initializing MPD Apr 25 12:03:02 volumio volumio[3785]: info: Creating MPD Configuration file Apr 25 12:03:02 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:03:02 volumio volumio[3785]: info: [1745575382041] CoreMusicLibrary::Adding element Server multimediali Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:02 volumio sudo[3913]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 12:03:02 volumio sudo[3913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:02 volumio sudo[3913]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:02 volumio sudo[3915]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 12:03:02 volumio sudo[3915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:02 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 12:03:02 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 12:03:02 volumio systemd[1]: mpd.service: Consumed 2.298s CPU time. Apr 25 12:03:02 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 12:03:02 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 12:03:02 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 12:03:02 volumio volumio[3785]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:03:02 volumio volumio[3785]: info: [1745575382087] CoreMusicLibrary::Adding element Last_100 Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:03:02 volumio volumio[3785]: info: [1745575382088] CoreMusicLibrary::Adding element Webradio Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:03:02 volumio volumio[3785]: info: Initializing BBC Radios Apr 25 12:03:02 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 12:03:02 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 12:03:02 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 12:03:02 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 12:03:02 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 12:03:02 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 12:03:02 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: Volumio Calling Home Apr 25 12:03:02 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 12:03:02 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 12:03:02 volumio sudo[3943]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 12:03:02 volumio sudo[3943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 25 12:03:02 volumio sudo[3943]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:02 volumio volumio[3870]: Starting albumart workers Apr 25 12:03:02 volumio volumio[3871]: Starting albumart workers Apr 25 12:03:02 volumio volumio[3880]: Starting albumart workers Apr 25 12:03:02 volumio volumio[3785]: info: MPD Permissions set Apr 25 12:03:02 volumio volumio[3785]: info: MPD Permissions set Apr 25 12:03:02 volumio volumio[3785]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 25 12:03:02 volumio volumio[3785]: info: Volumio called home Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:02 volumio volumio[3785]: Searching plugin music_service/webradio Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 25 12:03:02 volumio volumio[3785]: info: Starting Shairport Sync Apr 25 12:03:02 volumio volumio[3785]: info: Starting Shairport Sync Apr 25 12:03:02 volumio sudo[3978]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:03:02 volumio sudo[3978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:02 volumio volumio[3785]: info: Starting Shairport Sync Apr 25 12:03:02 volumio sudo[3981]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:03:02 volumio sudo[3981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 12:03:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 12:03:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:03:02 volumio systemd[1]: shairport-sync.service: Consumed 1.567s CPU time. Apr 25 12:03:02 volumio sudo[3982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:03:02 volumio sudo[3982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:02 volumio volumio[3785]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:03:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 12:03:02 volumio sudo[3978]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 12:03:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:03:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:03:02 volumio sudo[3982]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:02 volumio sudo[3981]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::volumioGetVisibleSources Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:02 volumio volumio[3785]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:02 volumio volumio[3785]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 25 12:03:02 volumio volumio[3785]: info: Received Get System Info Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 12:03:02 volumio volumio[3785]: info: Discovery: Getting this device information Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:02 volumio volumio[3785]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:02 volumio volumio[3785]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:02 volumio volumio[3785]: info: Listing playlists Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::volumioGetQueue Apr 25 12:03:02 volumio volumio[3785]: info: CoreStateMachine::getQueue Apr 25 12:03:02 volumio volumio[3785]: info: CorePlayQueue::getQueue Apr 25 12:03:02 volumio volumio[3785]: Searching plugin music_service/webradio Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 25 12:03:02 volumio volumio[3785]: info: Shairport-Sync Started Apr 25 12:03:02 volumio volumio[3785]: Error adding Membership: Error: addMembership EINVAL Apr 25 12:03:02 volumio volumio[3785]: info: Shairport-Sync Started Apr 25 12:03:02 volumio volumio[3785]: info: Shairport-Sync Started Apr 25 12:03:02 volumio mpd[3947]: 2025-04-25T12:03:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 25 12:03:02 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 25 12:03:02 volumio sudo[3905]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:02 volumio sudo[3915]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:02 volumio volumio[3785]: info: Completed starting Core Plugins Apr 25 12:03:02 volumio volumio[3785]: info: ------------------------------------------- Apr 25 12:03:02 volumio volumio[3785]: info: ----- MyVolumio plugins startup ---- Apr 25 12:03:02 volumio volumio[3785]: info: ------------------------------------------- Apr 25 12:03:02 volumio volumio[3785]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 25 12:03:02 volumio volumio[3785]: error: MPD error: The expression evaluated to a falsy value: Apr 25 12:03:02 volumio volumio[3785]: assert.ok(self.idling) Apr 25 12:03:02 volumio volumio[3785]: error: The expression evaluated to a falsy value: Apr 25 12:03:02 volumio volumio[3785]: assert.ok(self.idling) Apr 25 12:03:02 volumio volumio[3785]: error: updateQueue error: null Apr 25 12:03:02 volumio volumio[3785]: info: Discovery: adding 7254fb98-9cc9-453e-b80a-02d2215a0256 Apr 25 12:03:02 volumio volumio[3785]: info: Discovery: Found device Volumio Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:02 volumio volumio[3785]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:02 volumio volumio[3785]: info: Discovery: this is already registered, 7254fb98-9cc9-453e-b80a-02d2215a0256 Apr 25 12:03:02 volumio volumio[3785]: info: Discovery: Found device Volumio Apr 25 12:03:02 volumio volumio[3785]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:02 volumio volumio[3785]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:02 volumio volumio[3785]: info: MPD running with PID3947 Apr 25 12:03:02 volumio volumio[3785]: ,establishing connection Apr 25 12:03:02 volumio volumio[3785]: error: updateQueue error: null Apr 25 12:03:02 volumio volumio[3785]: Use XMLElement.setAttribute instead Apr 25 12:03:02 volumio volumio[3785]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 12:03:02 volumio volumio[3785]: TypeError: children[i].attr(...).value is not a function Apr 25 12:03:02 volumio volumio[3785]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Apr 25 12:03:02 volumio volumio[3785]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 25 12:03:02 volumio volumio[3785]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 12:03:03 volumio sudo[4026]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-04-25 12:02' Apr 25 12:03:03 volumio sudo[4026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:03 volumio sudo[4026]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:03 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 25 12:03:03 volumio sudo[3820]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:03 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 25 12:03:03 volumio systemd[1]: volumio.service: Consumed 4.389s CPU time. Apr 25 12:03:03 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 25 12:03:03 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Apr 25 12:03:03 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Apr 25 12:03:03 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 25 12:03:03 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 25 12:03:03 volumio systemd[1]: volumio.service: Consumed 4.389s CPU time. Apr 25 12:03:03 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 25 12:03:03 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Apr 25 12:03:03 volumio volumio[4046]: info: ------------------------------------------- Apr 25 12:03:03 volumio volumio[4046]: info: ----- Volumio3 ---- Apr 25 12:03:03 volumio volumio[4046]: info: ------------------------------------------- Apr 25 12:03:03 volumio volumio[4046]: info: ----- System startup ---- Apr 25 12:03:03 volumio volumio[4046]: info: ------------------------------------------- Apr 25 12:03:03 volumio volumio[4046]: info: MYVOLUMIO Environment detected Apr 25 12:03:03 volumio volumio[4046]: info: Plugin folders cleanup Apr 25 12:03:03 volumio volumio[4046]: info: Scanning into folder /volumio/app/plugins/ Apr 25 12:03:03 volumio volumio[4046]: info: Scanning category audio_interface Apr 25 12:03:03 volumio volumio[4046]: info: Scanning category miscellanea Apr 25 12:03:03 volumio volumio[4046]: info: Scanning category music_service Apr 25 12:03:03 volumio volumio[4046]: info: Scanning category plugins.json Apr 25 12:03:03 volumio volumio[4046]: info: Scanning category system_controller Apr 25 12:03:03 volumio volumio[4046]: info: Scanning category user_interface Apr 25 12:03:03 volumio volumio[4046]: info: Scanning into folder /data/plugins/ Apr 25 12:03:03 volumio volumio[4046]: info: Plugin folders cleanup completed Apr 25 12:03:03 volumio volumio[4046]: info: ------------------------------------------- Apr 25 12:03:03 volumio volumio[4046]: info: ----- Core plugins startup ---- Apr 25 12:03:03 volumio volumio[4046]: info: ------------------------------------------- Apr 25 12:03:03 volumio volumio[4046]: info: Loading plugins from folder /volumio/app/plugins/ Apr 25 12:03:03 volumio volumio[4046]: info: Adding plugin upnp to MyMusic Plugins Apr 25 12:03:03 volumio volumio[4046]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 25 12:03:03 volumio volumio[4046]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 25 12:03:03 volumio volumio[4046]: info: Loading plugins from folder /data/plugins/ Apr 25 12:03:03 volumio volumio[4046]: info: Loading plugin "system"... Apr 25 12:03:03 volumio volumio[4046]: info: Loading plugin "appearance"... Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "network"... Apr 25 12:03:04 volumio volumio[4046]: info: Refreshing Cached IP Addresses Apr 25 12:03:04 volumio sudo[4074]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 25 12:03:04 volumio sudo[4074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:04 volumio sudo[4074]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:04 volumio sudo[4077]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 25 12:03:04 volumio sudo[4077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:04 volumio sudo[4077]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "services"... Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "alsa_controller"... Apr 25 12:03:04 volumio sudo[4085]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 25 12:03:04 volumio sudo[4085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:04 volumio sudo[4085]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "wizard"... Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "networkfs"... Apr 25 12:03:04 volumio volumio[4046]: info: Starting Udev Watcher for removable devices Apr 25 12:03:04 volumio volumio[4046]: info: Ignoring mount for partition: boot Apr 25 12:03:04 volumio volumio[4046]: info: Ignoring mount for partition: volumio Apr 25 12:03:04 volumio volumio[4046]: info: Ignoring mount for partition: volumio_data Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "volumio_command_line_client"... Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "upnp"... Apr 25 12:03:04 volumio volumio[4046]: info: [1745575384143] Starting Upmpd Daemon Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "my_music"... Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "mpd"... Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "upnp_browser"... Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "alarm-clock"... Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "airplay_emulation"... Apr 25 12:03:04 volumio volumio[4046]: info: Starting Shairport Sync Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "last_100"... Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "webradio"... Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "i2s_dacs"... Apr 25 12:03:04 volumio volumio[4046]: info: I2S DAC not set, start Auto-detection Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "volumiodiscovery"... Apr 25 12:03:04 volumio volumio[4046]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 25 12:03:04 volumio volumio[4046]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:03:04 volumio volumio[4046]: *** WARNING *** For more information see Apr 25 12:03:04 volumio volumio[4046]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 25 12:03:04 volumio volumio[4046]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:03:04 volumio node[4046]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 25 12:03:04 volumio volumio[4046]: *** WARNING *** For more information see Apr 25 12:03:04 volumio node[4046]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:03:04 volumio node[4046]: *** WARNING *** For more information see Apr 25 12:03:04 volumio node[4046]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 25 12:03:04 volumio node[4046]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:03:04 volumio node[4046]: *** WARNING *** For more information see Apr 25 12:03:04 volumio volumio[4046]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 25 12:03:04 volumio volumio[4046]: info: Discovery: Started advertising with name: Volumio Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "outputs"... Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "albumart"... Apr 25 12:03:04 volumio volumio[4046]: info: Plugin example_plugin is not enabled Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "inputs"... Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "updater_comm"... Apr 25 12:03:04 volumio volumio[4046]: info: Plugin mpdemulation is not enabled Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "rest_api"... Apr 25 12:03:04 volumio volumio[4046]: info: Loading plugin "websocket"... Apr 25 12:03:04 volumio volumio[4046]: info: Starting Socket.io Server version 1.7.4 Apr 25 12:03:04 volumio volumio[4046]: info: Loading i18n strings for locale it Apr 25 12:03:04 volumio volumio[4046]: Updating browse sources language Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::initPlayerControls Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:03:04 volumio volumio[4046]: Express server listening on port 3000 Apr 25 12:03:04 volumio volumio[4046]: [Metrics] WebUI: 1s 3.56ms Apr 25 12:03:04 volumio volumio[4046]: info: CoreStateMachine::resetVolumioState Apr 25 12:03:04 volumio volumio[4046]: info: CoreStateMachine::getcurrentVolume Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:04 volumio volumio[4046]: info: Volumio Network Manager: Network status updated: 2 Apr 25 12:03:04 volumio volumio[4046]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 25 12:03:04 volumio volumio[4046]: wlan0 Interface doesn't support scanning : Device or resource busy Apr 25 12:03:04 volumio volumio[4046]: info: Cannot use regular scanning, forcing with ap-force Apr 25 12:03:04 volumio sudo[4127]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 25 12:03:04 volumio sudo[4127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:04 volumio sudo[4127]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:04 volumio volumio[4046]: command failed: Device or resource busy (-16) Apr 25 12:03:04 volumio volumio[4046]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Apr 25 12:03:04 volumio volumio[4046]: command failed: Device or resource busy (-16) Apr 25 12:03:04 volumio volumio[4109]: Forking 3 albumart workers Apr 25 12:03:04 volumio volumio[4046]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Apr 25 12:03:04 volumio volumio[4046]: Unable to parse: Apr 25 12:03:04 volumio volumio[4046]: Simple mixer control 'Master',0 Apr 25 12:03:04 volumio volumio[4046]: Capabilities: volume pswitch pswitch-joined Apr 25 12:03:04 volumio volumio[4046]: Playback channels: Front Left - Front Right Apr 25 12:03:04 volumio volumio[4046]: Capture channels: Front Left - Front Right Apr 25 12:03:04 volumio volumio[4046]: Limits: 0 - 128 Apr 25 12:03:04 volumio volumio[4046]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:04 volumio volumio[4046]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:04 volumio volumio[4046]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 25 12:03:04 volumio volumio[4046]: info: Reloading queue from file Apr 25 12:03:04 volumio volumio[4046]: Unable to parse: Apr 25 12:03:04 volumio volumio[4046]: Simple mixer control 'Master',0 Apr 25 12:03:04 volumio volumio[4046]: Capabilities: volume pswitch pswitch-joined Apr 25 12:03:04 volumio volumio[4046]: Playback channels: Front Left - Front Right Apr 25 12:03:04 volumio volumio[4046]: Capture channels: Front Left - Front Right Apr 25 12:03:04 volumio volumio[4046]: Limits: 0 - 128 Apr 25 12:03:04 volumio volumio[4046]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:04 volumio volumio[4046]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:04 volumio volumio[4046]: info: VolumeController:: Volume=undefined Mute =false Apr 25 12:03:04 volumio volumio[4046]: info: CoreStateMachine::pushState Apr 25 12:03:04 volumio volumio[4046]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::volumioPushState Apr 25 12:03:04 volumio volumio[4046]: info: CoreStateMachine::updateTrackBlock Apr 25 12:03:04 volumio volumio[4046]: info: CorePlayQueue::getTrackBlock Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 12:03:04 volumio volumio[4046]: info: CoreStateMachine::setRepeat null single undefined Apr 25 12:03:04 volumio volumio[4046]: info: CoreStateMachine::pushState Apr 25 12:03:04 volumio volumio[4046]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::volumioPushState Apr 25 12:03:04 volumio volumio[4046]: info: CoreStateMachine::setRandom null Apr 25 12:03:04 volumio volumio[4046]: info: CoreStateMachine::pushState Apr 25 12:03:04 volumio volumio[4046]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::volumioPushState Apr 25 12:03:04 volumio volumio[4046]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 25 12:03:04 volumio volumio[4046]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b04170 Apr 25 12:03:04 volumio volumio[4046]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 25 12:03:04 volumio volumio[4046]: info: Setting Device type: Raspberry PI Apr 25 12:03:04 volumio volumio[4046]: Unable to parse: Apr 25 12:03:04 volumio volumio[4046]: Simple mixer control 'Master',0 Apr 25 12:03:04 volumio volumio[4046]: Capabilities: volume pswitch pswitch-joined Apr 25 12:03:04 volumio volumio[4046]: Playback channels: Front Left - Front Right Apr 25 12:03:04 volumio volumio[4046]: Capture channels: Front Left - Front Right Apr 25 12:03:04 volumio volumio[4046]: Limits: 0 - 128 Apr 25 12:03:04 volumio volumio[4046]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:04 volumio volumio[4046]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:04 volumio volumio[4046]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 25 12:03:04 volumio volumio[4046]: info: Completed loading Core Plugins Apr 25 12:03:04 volumio volumio[4046]: info: Preparing to generate the ALSA configuration file Apr 25 12:03:04 volumio volumio[4046]: Unable to parse: Apr 25 12:03:04 volumio volumio[4046]: Simple mixer control 'Master',0 Apr 25 12:03:04 volumio volumio[4046]: Capabilities: volume pswitch pswitch-joined Apr 25 12:03:04 volumio volumio[4046]: Playback channels: Front Left - Front Right Apr 25 12:03:04 volumio volumio[4046]: Capture channels: Front Left - Front Right Apr 25 12:03:04 volumio volumio[4046]: Limits: 0 - 128 Apr 25 12:03:04 volumio volumio[4046]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:04 volumio volumio[4046]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:04 volumio volumio[4046]: info: VolumeController:: Volume=undefined Mute =false Apr 25 12:03:04 volumio volumio[4046]: info: CoreStateMachine::pushState Apr 25 12:03:04 volumio volumio[4046]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::volumioPushState Apr 25 12:03:04 volumio volumio[4046]: info: Asound.conf file unchanged, so no further update is needed Apr 25 12:03:04 volumio volumio[4046]: info: Output device has changed, restarting MPD Apr 25 12:03:04 volumio volumio[4046]: info: Output device has changed, restarting Shairport Sync Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:04 volumio sudo[4169]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 12:03:04 volumio sudo[4169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:04 volumio sudo[4169]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:04 volumio sudo[4171]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 12:03:04 volumio sudo[4171]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:04 volumio volumio[4046]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:03:04 volumio volumio[4046]: info: ___________ START PLUGINS ___________ Apr 25 12:03:04 volumio volumio[4046]: info: ControllerMpd::onStart: Initializing MPD Apr 25 12:03:04 volumio volumio[4046]: info: Creating MPD Configuration file Apr 25 12:03:04 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:03:04 volumio volumio[4046]: info: [1745575384804] CoreMusicLibrary::Adding element Server multimediali Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:04 volumio sudo[4179]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:04 volumio sudo[4181]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 12:03:04 volumio sudo[4181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:04 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 12:03:04 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 12:03:04 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 12:03:04 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 12:03:04 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 12:03:04 volumio sudo[4179]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:04 volumio sudo[4179]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:04 volumio volumio[4046]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 12:03:04 volumio volumio[4046]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:03:04 volumio volumio[4046]: info: [1745575384844] CoreMusicLibrary::Adding element Last_100 Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:03:04 volumio volumio[4046]: info: [1745575384854] CoreMusicLibrary::Adding element Webradio Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:03:04 volumio volumio[4046]: info: Initializing BBC Radios Apr 25 12:03:04 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 12:03:04 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 12:03:04 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 12:03:04 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 12:03:04 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 12:03:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 12:03:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:03:04 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:04 volumio volumio[4046]: info: Volumio Calling Home Apr 25 12:03:04 volumio sudo[4204]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 12:03:04 volumio sudo[4204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 25 12:03:04 volumio sudo[4204]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:04 volumio volumio[4130]: Starting albumart workers Apr 25 12:03:05 volumio volumio[4132]: Starting albumart workers Apr 25 12:03:05 volumio volumio[4129]: Starting albumart workers Apr 25 12:03:05 volumio volumio[4046]: info: MPD Permissions set Apr 25 12:03:05 volumio volumio[4046]: info: MPD Permissions set Apr 25 12:03:05 volumio volumio[4046]: info: Volumio called home Apr 25 12:03:05 volumio volumio[4046]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:05 volumio volumio[4046]: info: Starting Shairport Sync Apr 25 12:03:05 volumio volumio[4046]: info: Starting Shairport Sync Apr 25 12:03:05 volumio sudo[4243]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:03:05 volumio sudo[4243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:05 volumio volumio[4046]: info: Starting Shairport Sync Apr 25 12:03:05 volumio sudo[4245]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:03:05 volumio sudo[4245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:05 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 12:03:05 volumio sudo[4248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:03:05 volumio sudo[4248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:05 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 12:03:05 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:03:05 volumio systemd[1]: shairport-sync.service: Consumed 1.524s CPU time. Apr 25 12:03:05 volumio volumio-remote-updater[870]: [2025-04-25 12:03:05] [connect] Successful connection Apr 25 12:03:05 volumio volumio-remote-updater[870]: [2025-04-25 12:03:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745575385 101 Apr 25 12:03:05 volumio volumio[4046]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:05 volumio volumio[4046]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:05 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:03:05 volumio sudo[4243]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:05 volumio volumio[4046]: info: Shairport-Sync Started Apr 25 12:03:05 volumio volumio[4046]: Error adding Membership: Error: addMembership EINVAL Apr 25 12:03:05 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 12:03:05 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 12:03:05 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:03:05 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:03:05 volumio sudo[4248]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:05 volumio sudo[4245]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:05 volumio volumio[4046]: info: Shairport-Sync Started Apr 25 12:03:05 volumio volumio[4046]: info: Discovery: adding 7254fb98-9cc9-453e-b80a-02d2215a0256 Apr 25 12:03:05 volumio volumio[4046]: info: Discovery: Found device Volumio Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:05 volumio volumio[4046]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:05 volumio volumio[4046]: info: Discovery: this is already registered, 7254fb98-9cc9-453e-b80a-02d2215a0256 Apr 25 12:03:05 volumio volumio[4046]: info: Discovery: Found device Volumio Apr 25 12:03:05 volumio volumio[4046]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:05 volumio volumio[4046]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:05 volumio volumio[4046]: info: Shairport-Sync Started Apr 25 12:03:05 volumio mpd[4213]: 2025-04-25T12:03:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 25 12:03:05 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 25 12:03:05 volumio sudo[4171]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:05 volumio sudo[4181]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:05 volumio volumio[4046]: info: Completed starting Core Plugins Apr 25 12:03:05 volumio volumio[4046]: info: ------------------------------------------- Apr 25 12:03:05 volumio volumio[4046]: info: ----- MyVolumio plugins startup ---- Apr 25 12:03:05 volumio volumio[4046]: info: ------------------------------------------- Apr 25 12:03:05 volumio volumio[4046]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 25 12:03:05 volumio volumio[4046]: error: MPD error: The expression evaluated to a falsy value: Apr 25 12:03:05 volumio volumio[4046]: assert.ok(self.idling) Apr 25 12:03:05 volumio volumio[4046]: error: The expression evaluated to a falsy value: Apr 25 12:03:05 volumio volumio[4046]: assert.ok(self.idling) Apr 25 12:03:05 volumio volumio[4046]: info: MPD running with PID4213 Apr 25 12:03:05 volumio volumio[4046]: ,establishing connection Apr 25 12:03:05 volumio volumio[4046]: error: updateQueue error: null Apr 25 12:03:05 volumio volumio[4046]: error: updateQueue error: null Apr 25 12:03:07 volumio volumio[4046]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 4 Apr 25 12:03:07 volumio volumio[4046]: Searching plugin music_service/webradio Apr 25 12:03:07 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 25 12:03:07 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 12:03:07 volumio volumio[4046]: info: CoreCommandRouter::volumioGetVisibleSources Apr 25 12:03:07 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:07 volumio volumio[4046]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:07 volumio volumio[4046]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:07 volumio volumio[4046]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:07 volumio volumio[4046]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:07 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 25 12:03:07 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 25 12:03:07 volumio volumio[4046]: info: Received Get System Info Apr 25 12:03:07 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 12:03:07 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 12:03:07 volumio volumio[4046]: info: Discovery: Getting this device information Apr 25 12:03:07 volumio volumio[4046]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:07 volumio volumio[4046]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:07 volumio volumio[4046]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 12:03:07 volumio volumio[4046]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:07 volumio volumio[4046]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:07 volumio volumio[4046]: info: Listing playlists Apr 25 12:03:07 volumio volumio[4046]: info: CoreCommandRouter::volumioGetQueue Apr 25 12:03:07 volumio volumio[4046]: info: CoreStateMachine::getQueue Apr 25 12:03:07 volumio volumio[4046]: info: CorePlayQueue::getQueue Apr 25 12:03:07 volumio volumio[4046]: Use XMLElement.setAttribute instead Apr 25 12:03:07 volumio volumio[4046]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 12:03:07 volumio volumio[4046]: TypeError: children[i].attr(...).value is not a function Apr 25 12:03:07 volumio volumio[4046]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Apr 25 12:03:07 volumio volumio[4046]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 25 12:03:07 volumio volumio[4046]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 12:03:08 volumio sudo[4304]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-04-25 12:02' Apr 25 12:03:08 volumio sudo[4304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:08 volumio sudo[4304]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:08 volumio volumio-remote-updater[870]: [2025-04-25 12:03:08] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 25 12:03:08 volumio volumio-remote-updater[870]: [2025-04-25 12:03:08] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 25 12:03:08 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 25 12:03:08 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 25 12:03:08 volumio systemd[1]: volumio.service: Consumed 4.505s CPU time. Apr 25 12:03:08 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 25 12:03:08 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Apr 25 12:03:08 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Apr 25 12:03:08 volumio systemd[1]: dynamicswap.service: Start request repeated too quickly. Apr 25 12:03:08 volumio systemd[1]: dynamicswap.service: Failed with result 'start-limit-hit'. Apr 25 12:03:08 volumio systemd[1]: Failed to start dynamicswap.service - dynamicswap service. Apr 25 12:03:08 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 25 12:03:08 volumio systemd[1]: volumio.service: Consumed 4.505s CPU time. Apr 25 12:03:08 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 25 12:03:08 volumio volumio[4316]: info: ------------------------------------------- Apr 25 12:03:08 volumio volumio[4316]: info: ----- Volumio3 ---- Apr 25 12:03:08 volumio volumio[4316]: info: ------------------------------------------- Apr 25 12:03:08 volumio volumio[4316]: info: ----- System startup ---- Apr 25 12:03:08 volumio volumio[4316]: info: ------------------------------------------- Apr 25 12:03:08 volumio volumio[4316]: info: MYVOLUMIO Environment detected Apr 25 12:03:08 volumio volumio[4316]: info: Plugin folders cleanup Apr 25 12:03:08 volumio volumio[4316]: info: Scanning into folder /volumio/app/plugins/ Apr 25 12:03:08 volumio volumio[4316]: info: Scanning category audio_interface Apr 25 12:03:08 volumio volumio[4316]: info: Scanning category miscellanea Apr 25 12:03:08 volumio volumio[4316]: info: Scanning category music_service Apr 25 12:03:08 volumio volumio[4316]: info: Scanning category plugins.json Apr 25 12:03:08 volumio volumio[4316]: info: Scanning category system_controller Apr 25 12:03:08 volumio volumio[4316]: info: Scanning category user_interface Apr 25 12:03:08 volumio volumio[4316]: info: Scanning into folder /data/plugins/ Apr 25 12:03:08 volumio volumio[4316]: info: Plugin folders cleanup completed Apr 25 12:03:08 volumio volumio[4316]: info: ------------------------------------------- Apr 25 12:03:08 volumio volumio[4316]: info: ----- Core plugins startup ---- Apr 25 12:03:08 volumio volumio[4316]: info: ------------------------------------------- Apr 25 12:03:08 volumio volumio[4316]: info: Loading plugins from folder /volumio/app/plugins/ Apr 25 12:03:08 volumio volumio[4316]: info: Adding plugin upnp to MyMusic Plugins Apr 25 12:03:08 volumio volumio[4316]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 25 12:03:08 volumio volumio[4316]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 25 12:03:08 volumio volumio[4316]: info: Loading plugins from folder /data/plugins/ Apr 25 12:03:08 volumio volumio[4316]: info: Loading plugin "system"... Apr 25 12:03:08 volumio volumio[4316]: info: Loading plugin "appearance"... Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "network"... Apr 25 12:03:09 volumio volumio[4316]: info: Refreshing Cached IP Addresses Apr 25 12:03:09 volumio sudo[4339]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 25 12:03:09 volumio sudo[4339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:09 volumio sudo[4339]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:09 volumio sudo[4341]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 25 12:03:09 volumio sudo[4341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:09 volumio sudo[4341]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "services"... Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "alsa_controller"... Apr 25 12:03:09 volumio sudo[4350]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 25 12:03:09 volumio sudo[4350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "wizard"... Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "networkfs"... Apr 25 12:03:09 volumio volumio[4316]: info: Starting Udev Watcher for removable devices Apr 25 12:03:09 volumio volumio[4316]: info: Ignoring mount for partition: boot Apr 25 12:03:09 volumio volumio[4316]: info: Ignoring mount for partition: volumio Apr 25 12:03:09 volumio volumio[4316]: info: Ignoring mount for partition: volumio_data Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "volumio_command_line_client"... Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "upnp"... Apr 25 12:03:09 volumio volumio[4316]: info: [1745575389148] Starting Upmpd Daemon Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "my_music"... Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "mpd"... Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "upnp_browser"... Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "alarm-clock"... Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "airplay_emulation"... Apr 25 12:03:09 volumio volumio[4316]: info: Starting Shairport Sync Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "last_100"... Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "webradio"... Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "i2s_dacs"... Apr 25 12:03:09 volumio volumio[4316]: info: I2S DAC not set, start Auto-detection Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "volumiodiscovery"... Apr 25 12:03:09 volumio volumio[4316]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 25 12:03:09 volumio node[4316]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 25 12:03:09 volumio volumio[4316]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:03:09 volumio node[4316]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:03:09 volumio volumio[4316]: *** WARNING *** For more information see Apr 25 12:03:09 volumio node[4316]: *** WARNING *** For more information see Apr 25 12:03:09 volumio volumio[4316]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 25 12:03:09 volumio volumio[4316]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:03:09 volumio volumio[4316]: *** WARNING *** For more information see Apr 25 12:03:09 volumio volumio[4316]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 25 12:03:09 volumio node[4316]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 25 12:03:09 volumio node[4316]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:03:09 volumio node[4316]: *** WARNING *** For more information see Apr 25 12:03:09 volumio volumio[4316]: info: Discovery: Started advertising with name: Volumio Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "outputs"... Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "albumart"... Apr 25 12:03:09 volumio volumio[4316]: info: Plugin example_plugin is not enabled Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "inputs"... Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "updater_comm"... Apr 25 12:03:09 volumio volumio[4316]: info: Plugin mpdemulation is not enabled Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "rest_api"... Apr 25 12:03:09 volumio volumio[4316]: info: Loading plugin "websocket"... Apr 25 12:03:09 volumio volumio[4316]: info: Starting Socket.io Server version 1.7.4 Apr 25 12:03:09 volumio volumio[4316]: info: Loading i18n strings for locale it Apr 25 12:03:09 volumio volumio[4316]: Updating browse sources language Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::initPlayerControls Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:03:09 volumio volumio[4316]: Express server listening on port 3000 Apr 25 12:03:09 volumio volumio[4316]: [Metrics] WebUI: 0s 985.50ms Apr 25 12:03:09 volumio volumio[4316]: info: CoreStateMachine::resetVolumioState Apr 25 12:03:09 volumio volumio[4316]: info: CoreStateMachine::getcurrentVolume Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:09 volumio volumio[4316]: info: Volumio Network Manager: Network status updated: 2 Apr 25 12:03:09 volumio volumio[4316]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Apr 25 12:03:09 volumio volumio[4316]: Unable to parse: Apr 25 12:03:09 volumio volumio[4316]: Simple mixer control 'Master',0 Apr 25 12:03:09 volumio volumio[4316]: Capabilities: volume pswitch pswitch-joined Apr 25 12:03:09 volumio volumio[4316]: Playback channels: Front Left - Front Right Apr 25 12:03:09 volumio volumio[4316]: Capture channels: Front Left - Front Right Apr 25 12:03:09 volumio volumio[4316]: Limits: 0 - 128 Apr 25 12:03:09 volumio volumio[4316]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:09 volumio volumio[4316]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:09 volumio volumio[4316]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 25 12:03:09 volumio volumio[4374]: Forking 3 albumart workers Apr 25 12:03:09 volumio volumio[4316]: info: Reloading queue from file Apr 25 12:03:09 volumio volumio[4316]: info: CoreStateMachine::setRepeat null single undefined Apr 25 12:03:09 volumio volumio[4316]: info: CoreStateMachine::pushState Apr 25 12:03:09 volumio volumio[4316]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::volumioPushState Apr 25 12:03:09 volumio volumio[4316]: info: CoreStateMachine::setRandom null Apr 25 12:03:09 volumio volumio[4316]: info: CoreStateMachine::pushState Apr 25 12:03:09 volumio volumio[4316]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::volumioPushState Apr 25 12:03:09 volumio volumio[4316]: Unable to parse: Apr 25 12:03:09 volumio volumio[4316]: Simple mixer control 'Master',0 Apr 25 12:03:09 volumio volumio[4316]: Capabilities: volume pswitch pswitch-joined Apr 25 12:03:09 volumio volumio[4316]: Playback channels: Front Left - Front Right Apr 25 12:03:09 volumio volumio[4316]: Capture channels: Front Left - Front Right Apr 25 12:03:09 volumio volumio[4316]: Limits: 0 - 128 Apr 25 12:03:09 volumio volumio[4316]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:09 volumio volumio[4316]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:09 volumio volumio[4316]: info: VolumeController:: Volume=undefined Mute =false Apr 25 12:03:09 volumio volumio[4316]: info: CoreStateMachine::pushState Apr 25 12:03:09 volumio volumio[4316]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::volumioPushState Apr 25 12:03:09 volumio volumio[4316]: info: CoreStateMachine::updateTrackBlock Apr 25 12:03:09 volumio volumio[4316]: info: CorePlayQueue::getTrackBlock Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 12:03:09 volumio volumio[4316]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 25 12:03:09 volumio volumio[4316]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b04170 Apr 25 12:03:09 volumio volumio[4316]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 25 12:03:09 volumio volumio[4316]: info: Setting Device type: Raspberry PI Apr 25 12:03:09 volumio volumio[4316]: Unable to parse: Apr 25 12:03:09 volumio volumio[4316]: Simple mixer control 'Master',0 Apr 25 12:03:09 volumio volumio[4316]: Capabilities: volume pswitch pswitch-joined Apr 25 12:03:09 volumio volumio[4316]: Playback channels: Front Left - Front Right Apr 25 12:03:09 volumio volumio[4316]: Capture channels: Front Left - Front Right Apr 25 12:03:09 volumio volumio[4316]: Limits: 0 - 128 Apr 25 12:03:09 volumio volumio[4316]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:09 volumio volumio[4316]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:09 volumio volumio[4316]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 25 12:03:09 volumio volumio[4316]: Unable to parse: Apr 25 12:03:09 volumio volumio[4316]: Simple mixer control 'Master',0 Apr 25 12:03:09 volumio volumio[4316]: Capabilities: volume pswitch pswitch-joined Apr 25 12:03:09 volumio volumio[4316]: Playback channels: Front Left - Front Right Apr 25 12:03:09 volumio volumio[4316]: Capture channels: Front Left - Front Right Apr 25 12:03:09 volumio volumio[4316]: Limits: 0 - 128 Apr 25 12:03:09 volumio volumio[4316]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:09 volumio volumio[4316]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:09 volumio volumio[4316]: info: VolumeController:: Volume=undefined Mute =false Apr 25 12:03:09 volumio volumio[4316]: info: CoreStateMachine::pushState Apr 25 12:03:09 volumio volumio[4316]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::volumioPushState Apr 25 12:03:09 volumio volumio[4316]: info: Completed loading Core Plugins Apr 25 12:03:09 volumio volumio[4316]: info: Preparing to generate the ALSA configuration file Apr 25 12:03:09 volumio volumio[4316]: info: Asound.conf file unchanged, so no further update is needed Apr 25 12:03:09 volumio volumio[4316]: info: Output device has changed, restarting MPD Apr 25 12:03:09 volumio sudo[4428]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 12:03:09 volumio sudo[4428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:09 volumio sudo[4428]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:09 volumio volumio[4316]: info: Output device has changed, restarting Shairport Sync Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:09 volumio sudo[4431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 12:03:09 volumio sudo[4431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:09 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 25 12:03:09 volumio volumio[4316]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:03:09 volumio volumio[4316]: info: ___________ START PLUGINS ___________ Apr 25 12:03:09 volumio volumio[4316]: info: ControllerMpd::onStart: Initializing MPD Apr 25 12:03:09 volumio volumio[4316]: info: Creating MPD Configuration file Apr 25 12:03:09 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 12:03:09 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 12:03:09 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 12:03:09 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 12:03:09 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 12:03:09 volumio sudo[4439]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 12:03:09 volumio sudo[4439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:03:09 volumio volumio[4316]: info: [1745575389798] CoreMusicLibrary::Adding element Server multimediali Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:09 volumio sudo[4439]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:09 volumio sudo[4441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 12:03:09 volumio sudo[4441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:09 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 12:03:09 volumio volumio[4316]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:03:09 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:09 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 12:03:09 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 12:03:09 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 12:03:09 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 12:03:09 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 12:03:09 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 12:03:09 volumio volumio[4316]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:03:09 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:03:09 volumio volumio[4316]: info: [1745575389846] CoreMusicLibrary::Adding element Last_100 Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:03:09 volumio volumio[4316]: info: [1745575389847] CoreMusicLibrary::Adding element Webradio Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:03:09 volumio volumio[4316]: info: Initializing BBC Radios Apr 25 12:03:09 volumio sudo[4454]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 12:03:09 volumio sudo[4454]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:03:09 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:09 volumio volumio[4316]: info: Volumio Calling Home Apr 25 12:03:09 volumio sudo[4454]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:10 volumio volumio[4394]: Starting albumart workers Apr 25 12:03:10 volumio volumio[4316]: info: MPD Permissions set Apr 25 12:03:10 volumio volumio[4316]: info: MPD Permissions set Apr 25 12:03:10 volumio volumio[4395]: Starting albumart workers Apr 25 12:03:10 volumio volumio[4316]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 25 12:03:10 volumio volumio[4391]: Starting albumart workers Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:10 volumio volumio[4316]: info: Starting Shairport Sync Apr 25 12:03:10 volumio sudo[4484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:03:10 volumio sudo[4484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:10 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 12:03:10 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 12:03:10 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:03:10 volumio systemd[1]: shairport-sync.service: Consumed 1.527s CPU time. Apr 25 12:03:10 volumio volumio[4316]: info: Volumio called home Apr 25 12:03:10 volumio volumio[4316]: info: Starting Shairport Sync Apr 25 12:03:10 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:03:10 volumio volumio[4316]: info: Starting Shairport Sync Apr 25 12:03:10 volumio sudo[4484]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:10 volumio sudo[4500]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:03:10 volumio sudo[4500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:10 volumio sudo[4502]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:03:10 volumio sudo[4502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:10 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 12:03:10 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 12:03:10 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:03:10 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Apr 25 12:03:10 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Apr 25 12:03:10 volumio systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:03:10 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Apr 25 12:03:10 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Apr 25 12:03:10 volumio systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:03:10 volumio sudo[4502]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:10 volumio sudo[4500]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:10 volumio volumio[4316]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Apr 25 12:03:10 volumio volumio[4316]: Job for shairport-sync.service failed because start of the service was attempted too often. Apr 25 12:03:10 volumio volumio[4316]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Apr 25 12:03:10 volumio volumio[4316]: To force a start use "systemctl reset-failed shairport-sync.service" Apr 25 12:03:10 volumio volumio[4316]: followed by "systemctl start shairport-sync.service" again. Apr 25 12:03:10 volumio volumio[4316]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Apr 25 12:03:10 volumio volumio[4316]: Job for shairport-sync.service failed because start of the service was attempted too often. Apr 25 12:03:10 volumio volumio[4316]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Apr 25 12:03:10 volumio volumio[4316]: To force a start use "systemctl reset-failed shairport-sync.service" Apr 25 12:03:10 volumio volumio[4316]: followed by "systemctl start shairport-sync.service" again. Apr 25 12:03:10 volumio volumio[4316]: info: Shairport-Sync Started Apr 25 12:03:10 volumio volumio[4316]: Error adding Membership: Error: addMembership EINVAL Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:10 volumio volumio[4316]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:10 volumio mpd[4471]: 2025-04-25T12:03:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 25 12:03:10 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 25 12:03:10 volumio sudo[4441]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:10 volumio sudo[4431]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:10 volumio volumio[4316]: info: Completed starting Core Plugins Apr 25 12:03:10 volumio volumio[4316]: info: ------------------------------------------- Apr 25 12:03:10 volumio volumio[4316]: info: ----- MyVolumio plugins startup ---- Apr 25 12:03:10 volumio volumio[4316]: info: ------------------------------------------- Apr 25 12:03:10 volumio volumio[4316]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 25 12:03:10 volumio volumio[4316]: error: MPD error: The expression evaluated to a falsy value: Apr 25 12:03:10 volumio volumio[4316]: assert.ok(self.idling) Apr 25 12:03:10 volumio volumio[4316]: error: The expression evaluated to a falsy value: Apr 25 12:03:10 volumio volumio[4316]: assert.ok(self.idling) Apr 25 12:03:10 volumio volumio[4316]: info: MPD running with PID4471 Apr 25 12:03:10 volumio volumio[4316]: ,establishing connection Apr 25 12:03:10 volumio volumio[4316]: error: updateQueue error: null Apr 25 12:03:10 volumio volumio[4316]: error: updateQueue error: null Apr 25 12:03:10 volumio volumio[4316]: info: Discovery: adding 7254fb98-9cc9-453e-b80a-02d2215a0256 Apr 25 12:03:10 volumio volumio[4316]: info: Discovery: Found device Volumio Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:10 volumio volumio[4316]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:10 volumio volumio[4316]: info: Discovery: this is already registered, 7254fb98-9cc9-453e-b80a-02d2215a0256 Apr 25 12:03:10 volumio volumio[4316]: info: Discovery: Found device Volumio Apr 25 12:03:10 volumio volumio[4316]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:10 volumio volumio[4316]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:11 volumio volumio[4316]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Apr 25 12:03:12 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 12:03:12 volumio volumio[4316]: info: CoreCommandRouter::volumioGetVisibleSources Apr 25 12:03:12 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:12 volumio volumio[4316]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:12 volumio volumio[4316]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:12 volumio volumio[4316]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:12 volumio volumio[4316]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:12 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 25 12:03:12 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 25 12:03:12 volumio volumio[4316]: info: Received Get System Info Apr 25 12:03:12 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 12:03:12 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 12:03:12 volumio volumio[4316]: info: Discovery: Getting this device information Apr 25 12:03:12 volumio volumio[4316]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:12 volumio volumio[4316]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:12 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 12:03:12 volumio volumio[4316]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:12 volumio volumio[4316]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:12 volumio volumio[4316]: info: Listing playlists Apr 25 12:03:12 volumio volumio[4316]: info: CoreCommandRouter::volumioGetQueue Apr 25 12:03:12 volumio volumio[4316]: info: CoreStateMachine::getQueue Apr 25 12:03:12 volumio volumio[4316]: info: CorePlayQueue::getQueue Apr 25 12:03:12 volumio sudo[4350]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:13 volumio volumio-remote-updater[870]: [2025-04-25 12:03:13] [connect] Successful connection Apr 25 12:03:13 volumio volumio-remote-updater[870]: [2025-04-25 12:03:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745575393 101 Apr 25 12:03:13 volumio volumio[4316]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Apr 25 12:03:15 volumio volumio[4316]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 25 12:03:18 volumio volumio[4316]: info: Adding plugin bluetooth to MyMusic Plugins Apr 25 12:03:18 volumio volumio[4316]: info: Adding plugin multiroom to MyMusic Plugins Apr 25 12:03:18 volumio volumio[4316]: info: Adding plugin metavolumio to MyMusic Plugins Apr 25 12:03:18 volumio volumio[4316]: info: Adding plugin cd_controller to MyMusic Plugins Apr 25 12:03:18 volumio volumio[4316]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 25 12:03:18 volumio volumio[4316]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 25 12:03:18 volumio volumio[4316]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 25 12:03:19 volumio volumio[4316]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 25 12:03:19 volumio volumio[4316]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 25 12:03:19 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:19 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:19 volumio volumio[4316]: info: Starting MyVolumio Remote Streaming Endpoints Apr 25 12:03:19 volumio volumio[4316]: info: MyVolumio not started Apr 25 12:03:19 volumio volumio[4316]: info: Initializing device activation check Apr 25 12:03:19 volumio volumio[4316]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 25 12:03:19 volumio volumio[4316]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 25 12:03:19 volumio volumio[4316]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 25 12:03:19 volumio volumio[4316]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 25 12:03:19 volumio volumio[4316]: info: Streaming services startup Apr 25 12:03:19 volumio volumio[4316]: info: Starting Streaming Daemon Apr 25 12:03:19 volumio sudo[4548]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 25 12:03:19 volumio sudo[4548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:19 volumio volumio[4316]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 25 12:03:19 volumio sudo[4548]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:19 volumio sudo[4555]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 25 12:03:19 volumio sudo[4555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:19 volumio sudo[4555]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:19 volumio sudo[4557]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 25 12:03:19 volumio sudo[4557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:19 volumio sudo[4557]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:19 volumio volumio[4316]: error: Cannot start Volumio Streaming Daemon Apr 25 12:03:19 volumio volumio[4316]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 25 12:03:19 volumio volumio[4316]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 25 12:03:19 volumio sudo[4561]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 25 12:03:19 volumio sudo[4561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:19 volumio sudo[4561]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:19 volumio volumio[4316]: info: Upmpdcli Daemon Started Apr 25 12:03:34 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 25 12:03:34 volumio volumio[4316]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 25 12:03:34 volumio volumio[4316]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:34 volumio volumio[4316]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:35 volumio volumio[4316]: Searching plugin music_service/webradio Apr 25 12:03:35 volumio volumio[4316]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 25 12:03:35 volumio volumio[4316]: Use XMLElement.setAttribute instead Apr 25 12:03:35 volumio volumio[4316]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 12:03:35 volumio volumio[4316]: TypeError: children[i].attr(...).value is not a function Apr 25 12:03:35 volumio volumio[4316]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Apr 25 12:03:35 volumio volumio[4316]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 25 12:03:35 volumio volumio[4316]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 12:03:36 volumio sudo[4592]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-04-25 12:02' Apr 25 12:03:36 volumio sudo[4592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:36 volumio sudo[4592]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:36 volumio volumio-remote-updater[870]: [2025-04-25 12:03:36] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 25 12:03:36 volumio volumio-remote-updater[870]: [2025-04-25 12:03:36] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 25 12:03:36 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 25 12:03:36 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 25 12:03:36 volumio systemd[1]: volumio.service: Consumed 5.796s CPU time. Apr 25 12:03:36 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 25 12:03:36 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Apr 25 12:03:36 volumio ntpd[1055]: PROTO: 151.22.209.90 unlink local addr 192.168.1.32 -> Apr 25 12:03:36 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Apr 25 12:03:36 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 25 12:03:36 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 25 12:03:36 volumio systemd[1]: volumio.service: Consumed 5.796s CPU time. Apr 25 12:03:36 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 25 12:03:36 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Apr 25 12:03:36 volumio volumio[4605]: info: ------------------------------------------- Apr 25 12:03:36 volumio volumio[4605]: info: ----- Volumio3 ---- Apr 25 12:03:36 volumio volumio[4605]: info: ------------------------------------------- Apr 25 12:03:36 volumio volumio[4605]: info: ----- System startup ---- Apr 25 12:03:36 volumio volumio[4605]: info: ------------------------------------------- Apr 25 12:03:36 volumio volumio[4605]: info: MYVOLUMIO Environment detected Apr 25 12:03:36 volumio volumio[4605]: info: Plugin folders cleanup Apr 25 12:03:36 volumio volumio[4605]: info: Scanning into folder /volumio/app/plugins/ Apr 25 12:03:36 volumio volumio[4605]: info: Scanning category audio_interface Apr 25 12:03:36 volumio volumio[4605]: info: Scanning category miscellanea Apr 25 12:03:36 volumio volumio[4605]: info: Scanning category music_service Apr 25 12:03:36 volumio volumio[4605]: info: Scanning category plugins.json Apr 25 12:03:36 volumio volumio[4605]: info: Scanning category system_controller Apr 25 12:03:36 volumio volumio[4605]: info: Scanning category user_interface Apr 25 12:03:36 volumio volumio[4605]: info: Scanning into folder /data/plugins/ Apr 25 12:03:36 volumio volumio[4605]: info: Plugin folders cleanup completed Apr 25 12:03:36 volumio volumio[4605]: info: ------------------------------------------- Apr 25 12:03:36 volumio volumio[4605]: info: ----- Core plugins startup ---- Apr 25 12:03:36 volumio volumio[4605]: info: ------------------------------------------- Apr 25 12:03:36 volumio volumio[4605]: info: Loading plugins from folder /volumio/app/plugins/ Apr 25 12:03:36 volumio volumio[4605]: info: Adding plugin upnp to MyMusic Plugins Apr 25 12:03:36 volumio volumio[4605]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 25 12:03:36 volumio volumio[4605]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 25 12:03:36 volumio volumio[4605]: info: Loading plugins from folder /data/plugins/ Apr 25 12:03:36 volumio volumio[4605]: info: Loading plugin "system"... Apr 25 12:03:36 volumio volumio[4605]: info: Loading plugin "appearance"... Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "network"... Apr 25 12:03:37 volumio volumio[4605]: info: Refreshing Cached IP Addresses Apr 25 12:03:37 volumio sudo[4633]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 25 12:03:37 volumio sudo[4633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:37 volumio sudo[4635]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 25 12:03:37 volumio sudo[4635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:37 volumio sudo[4633]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:37 volumio sudo[4635]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "services"... Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "alsa_controller"... Apr 25 12:03:37 volumio sudo[4644]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 25 12:03:37 volumio sudo[4644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "wizard"... Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "networkfs"... Apr 25 12:03:37 volumio volumio[4605]: info: Starting Udev Watcher for removable devices Apr 25 12:03:37 volumio volumio[4605]: info: Ignoring mount for partition: boot Apr 25 12:03:37 volumio volumio[4605]: info: Ignoring mount for partition: volumio Apr 25 12:03:37 volumio volumio[4605]: info: Ignoring mount for partition: volumio_data Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "volumio_command_line_client"... Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "upnp"... Apr 25 12:03:37 volumio volumio[4605]: info: [1745575417145] Starting Upmpd Daemon Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "my_music"... Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "mpd"... Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "upnp_browser"... Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "alarm-clock"... Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "airplay_emulation"... Apr 25 12:03:37 volumio volumio[4605]: info: Starting Shairport Sync Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "last_100"... Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "webradio"... Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "i2s_dacs"... Apr 25 12:03:37 volumio volumio[4605]: info: I2S DAC not set, start Auto-detection Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "volumiodiscovery"... Apr 25 12:03:37 volumio volumio[4605]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 25 12:03:37 volumio volumio[4605]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:03:37 volumio volumio[4605]: *** WARNING *** For more information see Apr 25 12:03:37 volumio volumio[4605]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 25 12:03:37 volumio node[4605]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 25 12:03:37 volumio volumio[4605]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:03:37 volumio volumio[4605]: *** WARNING *** For more information see Apr 25 12:03:37 volumio node[4605]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:03:37 volumio node[4605]: *** WARNING *** For more information see Apr 25 12:03:37 volumio node[4605]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 25 12:03:37 volumio node[4605]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:03:37 volumio node[4605]: *** WARNING *** For more information see Apr 25 12:03:37 volumio volumio[4605]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 25 12:03:37 volumio volumio[4605]: info: Discovery: Started advertising with name: Volumio Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "outputs"... Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "albumart"... Apr 25 12:03:37 volumio volumio[4605]: info: Plugin example_plugin is not enabled Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "inputs"... Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "updater_comm"... Apr 25 12:03:37 volumio volumio[4605]: info: Plugin mpdemulation is not enabled Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "rest_api"... Apr 25 12:03:37 volumio volumio[4605]: info: Loading plugin "websocket"... Apr 25 12:03:37 volumio volumio[4605]: info: Starting Socket.io Server version 1.7.4 Apr 25 12:03:37 volumio volumio[4605]: info: Loading i18n strings for locale it Apr 25 12:03:37 volumio volumio[4605]: Updating browse sources language Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::initPlayerControls Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:03:37 volumio volumio[4605]: Express server listening on port 3000 Apr 25 12:03:37 volumio volumio[4605]: [Metrics] WebUI: 0s 983.77ms Apr 25 12:03:37 volumio volumio[4605]: info: CoreStateMachine::resetVolumioState Apr 25 12:03:37 volumio volumio[4605]: info: CoreStateMachine::getcurrentVolume Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:37 volumio volumio[4605]: info: Volumio Network Manager: Network status updated: 2 Apr 25 12:03:37 volumio volumio[4668]: Forking 3 albumart workers Apr 25 12:03:37 volumio volumio[4605]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Apr 25 12:03:37 volumio volumio[4605]: Unable to parse: Apr 25 12:03:37 volumio volumio[4605]: Simple mixer control 'Master',0 Apr 25 12:03:37 volumio volumio[4605]: Capabilities: volume pswitch pswitch-joined Apr 25 12:03:37 volumio volumio[4605]: Playback channels: Front Left - Front Right Apr 25 12:03:37 volumio volumio[4605]: Capture channels: Front Left - Front Right Apr 25 12:03:37 volumio volumio[4605]: Limits: 0 - 128 Apr 25 12:03:37 volumio volumio[4605]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:37 volumio volumio[4605]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:37 volumio volumio[4605]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 25 12:03:37 volumio volumio[4605]: info: Reloading queue from file Apr 25 12:03:37 volumio volumio[4605]: Unable to parse: Apr 25 12:03:37 volumio volumio[4605]: Simple mixer control 'Master',0 Apr 25 12:03:37 volumio volumio[4605]: Capabilities: volume pswitch pswitch-joined Apr 25 12:03:37 volumio volumio[4605]: Playback channels: Front Left - Front Right Apr 25 12:03:37 volumio volumio[4605]: Capture channels: Front Left - Front Right Apr 25 12:03:37 volumio volumio[4605]: Limits: 0 - 128 Apr 25 12:03:37 volumio volumio[4605]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:37 volumio volumio[4605]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:37 volumio volumio[4605]: info: VolumeController:: Volume=undefined Mute =false Apr 25 12:03:37 volumio volumio[4605]: info: CoreStateMachine::pushState Apr 25 12:03:37 volumio volumio[4605]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::volumioPushState Apr 25 12:03:37 volumio volumio[4605]: info: CoreStateMachine::updateTrackBlock Apr 25 12:03:37 volumio volumio[4605]: info: CorePlayQueue::getTrackBlock Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 12:03:37 volumio volumio[4605]: info: CoreStateMachine::setRepeat null single undefined Apr 25 12:03:37 volumio volumio[4605]: info: CoreStateMachine::pushState Apr 25 12:03:37 volumio volumio[4605]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::volumioPushState Apr 25 12:03:37 volumio volumio[4605]: info: CoreStateMachine::setRandom null Apr 25 12:03:37 volumio volumio[4605]: info: CoreStateMachine::pushState Apr 25 12:03:37 volumio volumio[4605]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::volumioPushState Apr 25 12:03:37 volumio volumio[4605]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 25 12:03:37 volumio volumio[4605]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b04170 Apr 25 12:03:37 volumio volumio[4605]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 25 12:03:37 volumio volumio[4605]: info: Setting Device type: Raspberry PI Apr 25 12:03:37 volumio volumio[4605]: Unable to parse: Apr 25 12:03:37 volumio volumio[4605]: Simple mixer control 'Master',0 Apr 25 12:03:37 volumio volumio[4605]: Capabilities: volume pswitch pswitch-joined Apr 25 12:03:37 volumio volumio[4605]: Playback channels: Front Left - Front Right Apr 25 12:03:37 volumio volumio[4605]: Capture channels: Front Left - Front Right Apr 25 12:03:37 volumio volumio[4605]: Limits: 0 - 128 Apr 25 12:03:37 volumio volumio[4605]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:37 volumio volumio[4605]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:37 volumio volumio[4605]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 25 12:03:37 volumio volumio[4605]: info: Completed loading Core Plugins Apr 25 12:03:37 volumio volumio[4605]: info: Preparing to generate the ALSA configuration file Apr 25 12:03:37 volumio volumio[4605]: Unable to parse: Apr 25 12:03:37 volumio volumio[4605]: Simple mixer control 'Master',0 Apr 25 12:03:37 volumio volumio[4605]: Capabilities: volume pswitch pswitch-joined Apr 25 12:03:37 volumio volumio[4605]: Playback channels: Front Left - Front Right Apr 25 12:03:37 volumio volumio[4605]: Capture channels: Front Left - Front Right Apr 25 12:03:37 volumio volumio[4605]: Limits: 0 - 128 Apr 25 12:03:37 volumio volumio[4605]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:37 volumio volumio[4605]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:03:37 volumio volumio[4605]: info: VolumeController:: Volume=undefined Mute =false Apr 25 12:03:37 volumio volumio[4605]: info: CoreStateMachine::pushState Apr 25 12:03:37 volumio volumio[4605]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::volumioPushState Apr 25 12:03:37 volumio volumio[4605]: info: Asound.conf file unchanged, so no further update is needed Apr 25 12:03:37 volumio volumio[4605]: info: Output device has changed, restarting MPD Apr 25 12:03:37 volumio volumio[4605]: info: Output device has changed, restarting Shairport Sync Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:37 volumio sudo[4721]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 12:03:37 volumio sudo[4721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:37 volumio volumio[4605]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:03:37 volumio volumio[4605]: info: ___________ START PLUGINS ___________ Apr 25 12:03:37 volumio volumio[4605]: info: ControllerMpd::onStart: Initializing MPD Apr 25 12:03:37 volumio volumio[4605]: info: Creating MPD Configuration file Apr 25 12:03:37 volumio sudo[4724]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 12:03:37 volumio sudo[4724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:03:37 volumio volumio[4605]: info: [1745575417753] CoreMusicLibrary::Adding element Server multimediali Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:37 volumio sudo[4721]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:37 volumio sudo[4732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 12:03:37 volumio sudo[4732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:37 volumio sudo[4734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 12:03:37 volumio sudo[4734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:37 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 25 12:03:37 volumio sudo[4732]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:37 volumio volumio[4605]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:37 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 12:03:37 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 12:03:37 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 12:03:37 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 12:03:37 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 12:03:37 volumio volumio[4605]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:03:37 volumio volumio[4605]: info: [1745575417793] CoreMusicLibrary::Adding element Last_100 Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:03:37 volumio volumio[4605]: info: [1745575417798] CoreMusicLibrary::Adding element Webradio Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:03:37 volumio volumio[4605]: info: Initializing BBC Radios Apr 25 12:03:37 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 12:03:37 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:03:37 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:37 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 12:03:37 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 12:03:37 volumio volumio[4605]: info: Volumio Calling Home Apr 25 12:03:37 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 12:03:37 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 12:03:37 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 12:03:37 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 12:03:37 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 12:03:37 volumio sudo[4762]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 12:03:37 volumio sudo[4762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 25 12:03:37 volumio sudo[4762]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:38 volumio volumio[4684]: Starting albumart workers Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 12:03:38 volumio volumio[4691]: Starting albumart workers Apr 25 12:03:38 volumio volumio[4605]: info: MPD Permissions set Apr 25 12:03:38 volumio volumio[4605]: info: MPD Permissions set Apr 25 12:03:38 volumio volumio[4687]: Starting albumart workers Apr 25 12:03:38 volumio volumio[4605]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:38 volumio ntpd[1055]: PROTO: 93.94.88.51 unlink local addr 192.168.1.32 -> Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::volumioGetVisibleSources Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:38 volumio volumio[4605]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:38 volumio volumio[4605]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 25 12:03:38 volumio volumio[4605]: info: Received Get System Info Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 12:03:38 volumio volumio[4605]: info: Discovery: Getting this device information Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:38 volumio volumio[4605]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:38 volumio volumio[4605]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:38 volumio volumio[4605]: info: Listing playlists Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::volumioGetQueue Apr 25 12:03:38 volumio volumio[4605]: info: CoreStateMachine::getQueue Apr 25 12:03:38 volumio volumio[4605]: info: CorePlayQueue::getQueue Apr 25 12:03:38 volumio volumio[4605]: info: Volumio called home Apr 25 12:03:38 volumio volumio[4605]: info: Starting Shairport Sync Apr 25 12:03:38 volumio volumio[4605]: info: Starting Shairport Sync Apr 25 12:03:38 volumio volumio[4605]: info: Starting Shairport Sync Apr 25 12:03:38 volumio sudo[4785]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:03:38 volumio sudo[4785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:38 volumio sudo[4789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:03:38 volumio sudo[4789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:38 volumio volumio[4605]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:38 volumio sudo[4796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:03:38 volumio sudo[4796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:38 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:03:38 volumio sudo[4785]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:38 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 12:03:38 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 12:03:38 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:03:38 volumio volumio[4605]: info: Shairport-Sync Started Apr 25 12:03:38 volumio volumio[4605]: Error adding Membership: Error: addMembership EINVAL Apr 25 12:03:38 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:03:38 volumio sudo[4789]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:38 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 12:03:38 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 12:03:38 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:03:38 volumio volumio[4605]: info: Shairport-Sync Started Apr 25 12:03:38 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:03:38 volumio sudo[4796]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:38 volumio volumio[4605]: info: Shairport-Sync Started Apr 25 12:03:38 volumio mpd[4766]: 2025-04-25T12:03:38 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 25 12:03:38 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 25 12:03:38 volumio sudo[4724]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:38 volumio sudo[4734]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:38 volumio volumio[4605]: info: Completed starting Core Plugins Apr 25 12:03:38 volumio volumio[4605]: info: ------------------------------------------- Apr 25 12:03:38 volumio volumio[4605]: info: ----- MyVolumio plugins startup ---- Apr 25 12:03:38 volumio volumio[4605]: info: ------------------------------------------- Apr 25 12:03:38 volumio volumio[4605]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 25 12:03:38 volumio volumio[4605]: error: MPD error: The expression evaluated to a falsy value: Apr 25 12:03:38 volumio volumio[4605]: assert.ok(self.idling) Apr 25 12:03:38 volumio volumio[4605]: error: The expression evaluated to a falsy value: Apr 25 12:03:38 volumio volumio[4605]: assert.ok(self.idling) Apr 25 12:03:38 volumio volumio[4605]: info: MPD running with PID4766 Apr 25 12:03:38 volumio volumio[4605]: ,establishing connection Apr 25 12:03:38 volumio volumio[4605]: error: updateQueue error: null Apr 25 12:03:38 volumio volumio[4605]: error: updateQueue error: null Apr 25 12:03:38 volumio volumio[4605]: info: Discovery: adding 7254fb98-9cc9-453e-b80a-02d2215a0256 Apr 25 12:03:38 volumio volumio[4605]: info: Discovery: Found device Volumio Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:38 volumio volumio[4605]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:38 volumio volumio[4605]: info: Discovery: this is already registered, 7254fb98-9cc9-453e-b80a-02d2215a0256 Apr 25 12:03:38 volumio volumio[4605]: info: Discovery: Found device Volumio Apr 25 12:03:38 volumio volumio[4605]: info: CoreCommandRouter::volumioGetState Apr 25 12:03:38 volumio volumio[4605]: info: CorePlayQueue::getTrack 0 Apr 25 12:03:40 volumio sudo[4644]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:41 volumio volumio-remote-updater[870]: [2025-04-25 12:03:41] [connect] Successful connection Apr 25 12:03:41 volumio volumio-remote-updater[870]: [2025-04-25 12:03:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745575421 101 Apr 25 12:03:41 volumio volumio[4605]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Apr 25 12:03:43 volumio volumio[4605]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 25 12:03:44 volumio ntpd[1055]: PROTO: 31.14.133.122 unlink local addr 192.168.1.32 -> Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 25 12:03:46 volumio volumio[4605]: info: Adding plugin bluetooth to MyMusic Plugins Apr 25 12:03:46 volumio volumio[4605]: info: Adding plugin multiroom to MyMusic Plugins Apr 25 12:03:46 volumio volumio[4605]: info: Adding plugin metavolumio to MyMusic Plugins Apr 25 12:03:46 volumio volumio[4605]: info: Adding plugin cd_controller to MyMusic Plugins Apr 25 12:03:46 volumio volumio[4605]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 25 12:03:46 volumio volumio[4605]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 25 12:03:46 volumio volumio[4605]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 25 12:03:47 volumio volumio[4605]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 25 12:03:47 volumio volumio[4605]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 25 12:03:47 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:47 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:03:47 volumio volumio[4605]: info: Starting MyVolumio Remote Streaming Endpoints Apr 25 12:03:47 volumio volumio[4605]: info: MyVolumio not started Apr 25 12:03:47 volumio volumio[4605]: info: Initializing device activation check Apr 25 12:03:47 volumio volumio[4605]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 25 12:03:47 volumio volumio[4605]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 25 12:03:47 volumio volumio[4605]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 25 12:03:47 volumio volumio[4605]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 25 12:03:47 volumio volumio[4605]: info: Streaming services startup Apr 25 12:03:47 volumio volumio[4605]: info: Starting Streaming Daemon Apr 25 12:03:47 volumio sudo[4858]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 25 12:03:47 volumio sudo[4858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:47 volumio volumio[4605]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 25 12:03:47 volumio sudo[4858]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:47 volumio volumio[4605]: error: Cannot start Volumio Streaming Daemon Apr 25 12:03:47 volumio volumio[4605]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 25 12:03:47 volumio volumio[4605]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 25 12:03:47 volumio sudo[4865]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 25 12:03:47 volumio sudo[4865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:47 volumio sudo[4865]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:47 volumio sudo[4867]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 25 12:03:47 volumio sudo[4867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:47 volumio sudo[4867]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:47 volumio sudo[4871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 25 12:03:47 volumio sudo[4871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:47 volumio sudo[4871]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:47 volumio volumio[4605]: info: Upmpdcli Daemon Started Apr 25 12:03:49 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 25 12:03:49 volumio volumio[4605]: info: TuneIn handleBrowseUri: tunein/location Apr 25 12:03:49 volumio volumio[4605]: error: Cannot read properties of undefined (reading 'indexOf') Apr 25 12:03:49 volumio volumio[4605]: error: Failed to execute browseSource: Error: Cannot list category items for location: TypeError: Cannot read properties of undefined (reading 'indexOf') Apr 25 12:03:51 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 25 12:03:51 volumio volumio[4605]: info: TuneIn handleBrowseUri: tunein/local Apr 25 12:03:52 volumio volumio[4605]: info: Preload queue cleared Apr 25 12:03:52 volumio volumio[4605]: info: Preload queue cleared Apr 25 12:03:58 volumio volumio[4605]: Searching plugin music_service/webradio Apr 25 12:03:58 volumio volumio[4605]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 25 12:03:58 volumio volumio[4605]: Use XMLElement.setAttribute instead Apr 25 12:03:58 volumio volumio[4605]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 12:03:58 volumio volumio[4605]: TypeError: children[i].attr(...).value is not a function Apr 25 12:03:58 volumio volumio[4605]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Apr 25 12:03:58 volumio volumio[4605]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 25 12:03:58 volumio volumio[4605]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 12:03:58 volumio sudo[4902]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-04-25 12:02' Apr 25 12:03:58 volumio sudo[4902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:58 volumio sudo[4902]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:58 volumio volumio-remote-updater[870]: [2025-04-25 12:03:58] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 25 12:03:58 volumio volumio-remote-updater[870]: [2025-04-25 12:03:58] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 25 12:03:58 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 25 12:03:58 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 25 12:03:58 volumio systemd[1]: volumio.service: Consumed 5.903s CPU time. Apr 25 12:03:58 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 25 12:03:58 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Apr 25 12:03:58 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Apr 25 12:03:58 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 25 12:03:58 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 25 12:03:58 volumio systemd[1]: volumio.service: Consumed 5.903s CPU time. Apr 25 12:03:58 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 25 12:03:58 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Apr 25 12:03:59 volumio volumio[4918]: info: ------------------------------------------- Apr 25 12:03:59 volumio volumio[4918]: info: ----- Volumio3 ---- Apr 25 12:03:59 volumio volumio[4918]: info: ------------------------------------------- Apr 25 12:03:59 volumio volumio[4918]: info: ----- System startup ---- Apr 25 12:03:59 volumio volumio[4918]: info: ------------------------------------------- Apr 25 12:03:59 volumio volumio[4918]: info: MYVOLUMIO Environment detected Apr 25 12:03:59 volumio volumio[4918]: info: Plugin folders cleanup Apr 25 12:03:59 volumio volumio[4918]: info: Scanning into folder /volumio/app/plugins/ Apr 25 12:03:59 volumio volumio[4918]: info: Scanning category audio_interface Apr 25 12:03:59 volumio volumio[4918]: info: Scanning category miscellanea Apr 25 12:03:59 volumio volumio[4918]: info: Scanning category music_service Apr 25 12:03:59 volumio volumio[4918]: info: Scanning category plugins.json Apr 25 12:03:59 volumio volumio[4918]: info: Scanning category system_controller Apr 25 12:03:59 volumio volumio[4918]: info: Scanning category user_interface Apr 25 12:03:59 volumio volumio[4918]: info: Scanning into folder /data/plugins/ Apr 25 12:03:59 volumio volumio[4918]: info: Plugin folders cleanup completed Apr 25 12:03:59 volumio volumio[4918]: info: ------------------------------------------- Apr 25 12:03:59 volumio volumio[4918]: info: ----- Core plugins startup ---- Apr 25 12:03:59 volumio volumio[4918]: info: ------------------------------------------- Apr 25 12:03:59 volumio volumio[4918]: info: Loading plugins from folder /volumio/app/plugins/ Apr 25 12:03:59 volumio volumio[4918]: info: Adding plugin upnp to MyMusic Plugins Apr 25 12:03:59 volumio volumio[4918]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 25 12:03:59 volumio volumio[4918]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 25 12:03:59 volumio volumio[4918]: info: Loading plugins from folder /data/plugins/ Apr 25 12:03:59 volumio volumio[4918]: info: Loading plugin "system"... Apr 25 12:03:59 volumio volumio[4918]: info: Loading plugin "appearance"... Apr 25 12:03:59 volumio volumio[4918]: info: Loading plugin "network"... Apr 25 12:03:59 volumio volumio[4918]: info: Refreshing Cached IP Addresses Apr 25 12:03:59 volumio sudo[4946]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 25 12:03:59 volumio sudo[4946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:59 volumio sudo[4948]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 25 12:03:59 volumio sudo[4948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:59 volumio sudo[4946]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:59 volumio sudo[4948]: pam_unix(sudo:session): session closed for user root Apr 25 12:03:59 volumio volumio[4918]: info: Loading plugin "services"... Apr 25 12:03:59 volumio volumio[4918]: info: Loading plugin "alsa_controller"... Apr 25 12:03:59 volumio sudo[4954]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 25 12:03:59 volumio sudo[4954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:03:59 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:03:59 volumio volumio[4918]: info: Loading plugin "wizard"... Apr 25 12:03:59 volumio volumio[4918]: info: Loading plugin "networkfs"... Apr 25 12:03:59 volumio volumio[4918]: info: Starting Udev Watcher for removable devices Apr 25 12:03:59 volumio volumio[4918]: info: Ignoring mount for partition: boot Apr 25 12:03:59 volumio volumio[4918]: info: Ignoring mount for partition: volumio Apr 25 12:03:59 volumio volumio[4918]: info: Ignoring mount for partition: volumio_data Apr 25 12:03:59 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:03:59 volumio volumio[4918]: info: Loading plugin "volumio_command_line_client"... Apr 25 12:03:59 volumio volumio[4918]: info: Loading plugin "upnp"... Apr 25 12:03:59 volumio volumio[4918]: info: [1745575439656] Starting Upmpd Daemon Apr 25 12:03:59 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:03:59 volumio volumio[4918]: info: Loading plugin "my_music"... Apr 25 12:03:59 volumio volumio[4918]: info: Loading plugin "mpd"... Apr 25 12:03:59 volumio volumio[4918]: info: Loading plugin "upnp_browser"... Apr 25 12:03:59 volumio volumio[4918]: info: Loading plugin "alarm-clock"... Apr 25 12:04:00 volumio volumio[4918]: info: Loading plugin "airplay_emulation"... Apr 25 12:04:00 volumio volumio[4918]: info: Starting Shairport Sync Apr 25 12:04:00 volumio volumio[4918]: info: Loading plugin "last_100"... Apr 25 12:04:00 volumio volumio[4918]: info: Loading plugin "webradio"... Apr 25 12:04:00 volumio volumio[4918]: info: Loading plugin "i2s_dacs"... Apr 25 12:04:00 volumio volumio[4918]: info: I2S DAC not set, start Auto-detection Apr 25 12:04:00 volumio volumio[4918]: info: Loading plugin "volumiodiscovery"... Apr 25 12:04:00 volumio volumio[4918]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 25 12:04:00 volumio volumio[4918]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:04:00 volumio volumio[4918]: *** WARNING *** For more information see Apr 25 12:04:00 volumio volumio[4918]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 25 12:04:00 volumio node[4918]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 25 12:04:00 volumio volumio[4918]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:04:00 volumio volumio[4918]: *** WARNING *** For more information see Apr 25 12:04:00 volumio node[4918]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:04:00 volumio node[4918]: *** WARNING *** For more information see Apr 25 12:04:00 volumio node[4918]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 25 12:04:00 volumio node[4918]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:04:00 volumio node[4918]: *** WARNING *** For more information see Apr 25 12:04:00 volumio volumio[4918]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 25 12:04:00 volumio volumio[4918]: info: Discovery: Started advertising with name: Volumio Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:04:00 volumio volumio[4918]: info: Loading plugin "outputs"... Apr 25 12:04:00 volumio volumio[4918]: info: Loading plugin "albumart"... Apr 25 12:04:00 volumio volumio[4918]: info: Plugin example_plugin is not enabled Apr 25 12:04:00 volumio volumio[4918]: info: Loading plugin "inputs"... Apr 25 12:04:00 volumio volumio[4918]: info: Loading plugin "updater_comm"... Apr 25 12:04:00 volumio volumio[4918]: info: Plugin mpdemulation is not enabled Apr 25 12:04:00 volumio volumio[4918]: info: Loading plugin "rest_api"... Apr 25 12:04:00 volumio volumio[4918]: info: Loading plugin "websocket"... Apr 25 12:04:00 volumio volumio[4918]: info: Starting Socket.io Server version 1.7.4 Apr 25 12:04:00 volumio volumio[4918]: info: Loading i18n strings for locale it Apr 25 12:04:00 volumio volumio[4918]: Updating browse sources language Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::initPlayerControls Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: Express server listening on port 3000 Apr 25 12:04:00 volumio volumio[4918]: [Metrics] WebUI: 0s 998.72ms Apr 25 12:04:00 volumio volumio[4918]: info: CoreStateMachine::resetVolumioState Apr 25 12:04:00 volumio volumio[4918]: info: CoreStateMachine::getcurrentVolume Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: Volumio Network Manager: Network status updated: 2 Apr 25 12:04:00 volumio volumio[4918]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Apr 25 12:04:00 volumio volumio[4918]: Unable to parse: Apr 25 12:04:00 volumio volumio[4918]: Simple mixer control 'Master',0 Apr 25 12:04:00 volumio volumio[4918]: Capabilities: volume pswitch pswitch-joined Apr 25 12:04:00 volumio volumio[4918]: Playback channels: Front Left - Front Right Apr 25 12:04:00 volumio volumio[4918]: Capture channels: Front Left - Front Right Apr 25 12:04:00 volumio volumio[4918]: Limits: 0 - 128 Apr 25 12:04:00 volumio volumio[4918]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:00 volumio volumio[4918]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:00 volumio volumio[4918]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 25 12:04:00 volumio volumio[4981]: Forking 3 albumart workers Apr 25 12:04:00 volumio volumio[4918]: info: Reloading queue from file Apr 25 12:04:00 volumio volumio[4918]: info: CoreStateMachine::setRepeat null single undefined Apr 25 12:04:00 volumio volumio[4918]: info: CoreStateMachine::pushState Apr 25 12:04:00 volumio volumio[4918]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::volumioPushState Apr 25 12:04:00 volumio volumio[4918]: info: CoreStateMachine::setRandom null Apr 25 12:04:00 volumio volumio[4918]: info: CoreStateMachine::pushState Apr 25 12:04:00 volumio volumio[4918]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::volumioPushState Apr 25 12:04:00 volumio volumio[4918]: info: Setting Device type: Raspberry PI Apr 25 12:04:00 volumio volumio[4918]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 25 12:04:00 volumio volumio[4918]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b04170 Apr 25 12:04:00 volumio volumio[4918]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 25 12:04:00 volumio volumio[4918]: Unable to parse: Apr 25 12:04:00 volumio volumio[4918]: Simple mixer control 'Master',0 Apr 25 12:04:00 volumio volumio[4918]: Capabilities: volume pswitch pswitch-joined Apr 25 12:04:00 volumio volumio[4918]: Playback channels: Front Left - Front Right Apr 25 12:04:00 volumio volumio[4918]: Capture channels: Front Left - Front Right Apr 25 12:04:00 volumio volumio[4918]: Limits: 0 - 128 Apr 25 12:04:00 volumio volumio[4918]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:00 volumio volumio[4918]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:00 volumio volumio[4918]: info: VolumeController:: Volume=undefined Mute =false Apr 25 12:04:00 volumio volumio[4918]: info: CoreStateMachine::pushState Apr 25 12:04:00 volumio volumio[4918]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::volumioPushState Apr 25 12:04:00 volumio volumio[4918]: info: CoreStateMachine::updateTrackBlock Apr 25 12:04:00 volumio volumio[4918]: info: CorePlayQueue::getTrackBlock Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 12:04:00 volumio volumio[4918]: Unable to parse: Apr 25 12:04:00 volumio volumio[4918]: Simple mixer control 'Master',0 Apr 25 12:04:00 volumio volumio[4918]: Capabilities: volume pswitch pswitch-joined Apr 25 12:04:00 volumio volumio[4918]: Playback channels: Front Left - Front Right Apr 25 12:04:00 volumio volumio[4918]: Capture channels: Front Left - Front Right Apr 25 12:04:00 volumio volumio[4918]: Limits: 0 - 128 Apr 25 12:04:00 volumio volumio[4918]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:00 volumio volumio[4918]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:00 volumio volumio[4918]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 25 12:04:00 volumio volumio[4918]: info: Completed loading Core Plugins Apr 25 12:04:00 volumio volumio[4918]: info: Preparing to generate the ALSA configuration file Apr 25 12:04:00 volumio volumio[4918]: Unable to parse: Apr 25 12:04:00 volumio volumio[4918]: Simple mixer control 'Master',0 Apr 25 12:04:00 volumio volumio[4918]: Capabilities: volume pswitch pswitch-joined Apr 25 12:04:00 volumio volumio[4918]: Playback channels: Front Left - Front Right Apr 25 12:04:00 volumio volumio[4918]: Capture channels: Front Left - Front Right Apr 25 12:04:00 volumio volumio[4918]: Limits: 0 - 128 Apr 25 12:04:00 volumio volumio[4918]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:00 volumio volumio[4918]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:00 volumio volumio[4918]: info: VolumeController:: Volume=undefined Mute =false Apr 25 12:04:00 volumio volumio[4918]: info: CoreStateMachine::pushState Apr 25 12:04:00 volumio volumio[4918]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::volumioPushState Apr 25 12:04:00 volumio volumio[4918]: info: Asound.conf file unchanged, so no further update is needed Apr 25 12:04:00 volumio volumio[4918]: info: Output device has changed, restarting MPD Apr 25 12:04:00 volumio volumio[4918]: info: Output device has changed, restarting Shairport Sync Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:00 volumio sudo[5035]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 12:04:00 volumio sudo[5035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:00 volumio sudo[5033]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 12:04:00 volumio sudo[5033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:00 volumio sudo[5033]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:00 volumio volumio[4918]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:04:00 volumio volumio[4918]: info: ___________ START PLUGINS ___________ Apr 25 12:04:00 volumio volumio[4918]: info: ControllerMpd::onStart: Initializing MPD Apr 25 12:04:00 volumio volumio[4918]: info: Creating MPD Configuration file Apr 25 12:04:00 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:04:00 volumio volumio[4918]: info: [1745575440271] CoreMusicLibrary::Adding element Server multimediali Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:04:00 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 12:04:00 volumio sudo[5044]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 12:04:00 volumio sudo[5044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:00 volumio sudo[5046]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 12:04:00 volumio sudo[5046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:00 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 12:04:00 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 12:04:00 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 12:04:00 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 12:04:00 volumio sudo[5044]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:00 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 12:04:00 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 12:04:00 volumio volumio[4918]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:04:00 volumio volumio[4918]: info: [1745575440321] CoreMusicLibrary::Adding element Last_100 Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:04:00 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 12:04:00 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 12:04:00 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 12:04:00 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 12:04:00 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:04:00 volumio volumio[4918]: info: [1745575440324] CoreMusicLibrary::Adding element Webradio Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:04:00 volumio volumio[4918]: info: Initializing BBC Radios Apr 25 12:04:00 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 12:04:00 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: Volumio Calling Home Apr 25 12:04:00 volumio sudo[5063]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 12:04:00 volumio sudo[5063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 25 12:04:00 volumio sudo[5063]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:00 volumio volumio[5002]: Starting albumart workers Apr 25 12:04:00 volumio volumio[5003]: Starting albumart workers Apr 25 12:04:00 volumio volumio[4918]: info: MPD Permissions set Apr 25 12:04:00 volumio volumio[4918]: info: MPD Permissions set Apr 25 12:04:00 volumio volumio[4918]: info: Volumio called home Apr 25 12:04:00 volumio volumio[4918]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[5005]: Starting albumart workers Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:00 volumio volumio[4918]: info: Starting Shairport Sync Apr 25 12:04:00 volumio volumio[4918]: info: Starting Shairport Sync Apr 25 12:04:00 volumio volumio[4918]: info: Starting Shairport Sync Apr 25 12:04:00 volumio sudo[5101]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:04:00 volumio sudo[5101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:00 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 12:04:00 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 12:04:00 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:04:00 volumio systemd[1]: shairport-sync.service: Consumed 1.541s CPU time. Apr 25 12:04:00 volumio sudo[5099]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:04:00 volumio sudo[5099]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:00 volumio sudo[5105]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:04:00 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:04:00 volumio sudo[5105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:00 volumio sudo[5101]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:00 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 12:04:00 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 12:04:00 volumio volumio[4918]: info: Shairport-Sync Started Apr 25 12:04:00 volumio volumio[4918]: Error adding Membership: Error: addMembership EINVAL Apr 25 12:04:00 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:04:00 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:04:00 volumio sudo[5099]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:00 volumio volumio[4918]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:00 volumio volumio[4918]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:00 volumio volumio[4918]: info: Shairport-Sync Started Apr 25 12:04:00 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 12:04:00 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 12:04:00 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:04:00 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:04:00 volumio sudo[5105]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:00 volumio volumio[4918]: info: Shairport-Sync Started Apr 25 12:04:00 volumio mpd[5078]: 2025-04-25T12:04:00 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 25 12:04:00 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 25 12:04:00 volumio sudo[5046]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:00 volumio sudo[5035]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:00 volumio volumio[4918]: info: Completed starting Core Plugins Apr 25 12:04:00 volumio volumio[4918]: info: ------------------------------------------- Apr 25 12:04:00 volumio volumio[4918]: info: ----- MyVolumio plugins startup ---- Apr 25 12:04:00 volumio volumio[4918]: info: ------------------------------------------- Apr 25 12:04:00 volumio volumio[4918]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 25 12:04:00 volumio volumio[4918]: error: MPD error: The expression evaluated to a falsy value: Apr 25 12:04:00 volumio volumio[4918]: assert.ok(self.idling) Apr 25 12:04:00 volumio volumio[4918]: error: The expression evaluated to a falsy value: Apr 25 12:04:00 volumio volumio[4918]: assert.ok(self.idling) Apr 25 12:04:00 volumio volumio[4918]: error: updateQueue error: null Apr 25 12:04:00 volumio volumio[4918]: info: MPD running with PID5078 Apr 25 12:04:00 volumio volumio[4918]: ,establishing connection Apr 25 12:04:01 volumio volumio[4918]: error: updateQueue error: null Apr 25 12:04:01 volumio volumio[4918]: info: Discovery: adding 7254fb98-9cc9-453e-b80a-02d2215a0256 Apr 25 12:04:01 volumio volumio[4918]: info: Discovery: Found device Volumio Apr 25 12:04:01 volumio volumio[4918]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:01 volumio volumio[4918]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:01 volumio volumio[4918]: info: Discovery: this is already registered, 7254fb98-9cc9-453e-b80a-02d2215a0256 Apr 25 12:04:01 volumio volumio[4918]: info: Discovery: Found device Volumio Apr 25 12:04:01 volumio volumio[4918]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:01 volumio volumio[4918]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:01 volumio volumio[4918]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Apr 25 12:04:01 volumio volumio[4918]: Searching plugin music_service/webradio Apr 25 12:04:01 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 25 12:04:01 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 12:04:01 volumio volumio[4918]: info: CoreCommandRouter::volumioGetVisibleSources Apr 25 12:04:01 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:04:01 volumio volumio[4918]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:01 volumio volumio[4918]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:01 volumio volumio[4918]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:01 volumio volumio[4918]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:01 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 25 12:04:01 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 25 12:04:01 volumio volumio[4918]: info: Received Get System Info Apr 25 12:04:01 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 12:04:01 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 12:04:01 volumio volumio[4918]: info: Discovery: Getting this device information Apr 25 12:04:01 volumio volumio[4918]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:01 volumio volumio[4918]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:01 volumio volumio[4918]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 12:04:01 volumio volumio[4918]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:01 volumio volumio[4918]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:01 volumio volumio[4918]: info: Listing playlists Apr 25 12:04:01 volumio volumio[4918]: info: CoreCommandRouter::volumioGetQueue Apr 25 12:04:01 volumio volumio[4918]: info: CoreStateMachine::getQueue Apr 25 12:04:01 volumio volumio[4918]: info: CorePlayQueue::getQueue Apr 25 12:04:01 volumio volumio[4918]: Use XMLElement.setAttribute instead Apr 25 12:04:01 volumio volumio[4918]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 12:04:01 volumio volumio[4918]: TypeError: children[i].attr(...).value is not a function Apr 25 12:04:01 volumio volumio[4918]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Apr 25 12:04:01 volumio volumio[4918]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 25 12:04:01 volumio volumio[4918]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 12:04:01 volumio sudo[5170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-04-25 12:03' Apr 25 12:04:01 volumio sudo[5170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:01 volumio sudo[5170]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:01 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 25 12:04:01 volumio sudo[4954]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:01 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 25 12:04:01 volumio systemd[1]: volumio.service: Consumed 4.488s CPU time. Apr 25 12:04:01 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 25 12:04:01 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Apr 25 12:04:02 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Apr 25 12:04:02 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 25 12:04:02 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 25 12:04:02 volumio systemd[1]: volumio.service: Consumed 4.488s CPU time. Apr 25 12:04:02 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 25 12:04:02 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Apr 25 12:04:02 volumio volumio[5191]: info: ------------------------------------------- Apr 25 12:04:02 volumio volumio[5191]: info: ----- Volumio3 ---- Apr 25 12:04:02 volumio volumio[5191]: info: ------------------------------------------- Apr 25 12:04:02 volumio volumio[5191]: info: ----- System startup ---- Apr 25 12:04:02 volumio volumio[5191]: info: ------------------------------------------- Apr 25 12:04:02 volumio volumio[5191]: info: MYVOLUMIO Environment detected Apr 25 12:04:02 volumio volumio[5191]: info: Plugin folders cleanup Apr 25 12:04:02 volumio volumio[5191]: info: Scanning into folder /volumio/app/plugins/ Apr 25 12:04:02 volumio volumio[5191]: info: Scanning category audio_interface Apr 25 12:04:02 volumio volumio[5191]: info: Scanning category miscellanea Apr 25 12:04:02 volumio volumio[5191]: info: Scanning category music_service Apr 25 12:04:02 volumio volumio[5191]: info: Scanning category plugins.json Apr 25 12:04:02 volumio volumio[5191]: info: Scanning category system_controller Apr 25 12:04:02 volumio volumio[5191]: info: Scanning category user_interface Apr 25 12:04:02 volumio volumio[5191]: info: Scanning into folder /data/plugins/ Apr 25 12:04:02 volumio volumio[5191]: info: Plugin folders cleanup completed Apr 25 12:04:02 volumio volumio[5191]: info: ------------------------------------------- Apr 25 12:04:02 volumio volumio[5191]: info: ----- Core plugins startup ---- Apr 25 12:04:02 volumio volumio[5191]: info: ------------------------------------------- Apr 25 12:04:02 volumio volumio[5191]: info: Loading plugins from folder /volumio/app/plugins/ Apr 25 12:04:02 volumio volumio[5191]: info: Adding plugin upnp to MyMusic Plugins Apr 25 12:04:02 volumio volumio[5191]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 25 12:04:02 volumio volumio[5191]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 25 12:04:02 volumio volumio[5191]: info: Loading plugins from folder /data/plugins/ Apr 25 12:04:02 volumio volumio[5191]: info: Loading plugin "system"... Apr 25 12:04:02 volumio volumio[5191]: info: Loading plugin "appearance"... Apr 25 12:04:02 volumio volumio[5191]: info: Loading plugin "network"... Apr 25 12:04:02 volumio volumio[5191]: info: Refreshing Cached IP Addresses Apr 25 12:04:02 volumio sudo[5219]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 25 12:04:02 volumio sudo[5219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:02 volumio sudo[5221]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 25 12:04:02 volumio sudo[5221]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:02 volumio sudo[5219]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:02 volumio sudo[5221]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:02 volumio volumio[5191]: info: Loading plugin "services"... Apr 25 12:04:02 volumio volumio[5191]: info: Loading plugin "alsa_controller"... Apr 25 12:04:02 volumio sudo[5230]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 25 12:04:02 volumio sudo[5230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:02 volumio sudo[5230]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:02 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:04:02 volumio volumio[5191]: info: Loading plugin "wizard"... Apr 25 12:04:02 volumio volumio[5191]: info: Loading plugin "networkfs"... Apr 25 12:04:02 volumio volumio[5191]: info: Starting Udev Watcher for removable devices Apr 25 12:04:02 volumio volumio[5191]: info: Ignoring mount for partition: boot Apr 25 12:04:02 volumio volumio[5191]: info: Ignoring mount for partition: volumio Apr 25 12:04:02 volumio volumio[5191]: info: Ignoring mount for partition: volumio_data Apr 25 12:04:02 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:04:02 volumio volumio[5191]: info: Loading plugin "volumio_command_line_client"... Apr 25 12:04:02 volumio volumio[5191]: info: Loading plugin "upnp"... Apr 25 12:04:02 volumio volumio[5191]: info: [1745575442865] Starting Upmpd Daemon Apr 25 12:04:02 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:04:02 volumio volumio[5191]: info: Loading plugin "my_music"... Apr 25 12:04:02 volumio volumio[5191]: info: Loading plugin "mpd"... Apr 25 12:04:02 volumio volumio[5191]: info: Loading plugin "upnp_browser"... Apr 25 12:04:03 volumio volumio[5191]: info: Loading plugin "alarm-clock"... Apr 25 12:04:03 volumio volumio[5191]: info: Loading plugin "airplay_emulation"... Apr 25 12:04:03 volumio volumio[5191]: info: Starting Shairport Sync Apr 25 12:04:03 volumio volumio[5191]: info: Loading plugin "last_100"... Apr 25 12:04:03 volumio volumio[5191]: info: Loading plugin "webradio"... Apr 25 12:04:03 volumio volumio[5191]: info: Loading plugin "i2s_dacs"... Apr 25 12:04:03 volumio volumio[5191]: info: I2S DAC not set, start Auto-detection Apr 25 12:04:03 volumio volumio[5191]: info: Loading plugin "volumiodiscovery"... Apr 25 12:04:03 volumio volumio[5191]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 25 12:04:03 volumio volumio[5191]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:04:03 volumio volumio[5191]: *** WARNING *** For more information see Apr 25 12:04:03 volumio node[5191]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 25 12:04:03 volumio volumio[5191]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 25 12:04:03 volumio volumio[5191]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:04:03 volumio volumio[5191]: *** WARNING *** For more information see Apr 25 12:04:03 volumio node[5191]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:04:03 volumio node[5191]: *** WARNING *** For more information see Apr 25 12:04:03 volumio node[5191]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 25 12:04:03 volumio node[5191]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:04:03 volumio node[5191]: *** WARNING *** For more information see Apr 25 12:04:03 volumio volumio[5191]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 25 12:04:03 volumio volumio[5191]: info: Discovery: Started advertising with name: Volumio Apr 25 12:04:03 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:04:03 volumio volumio[5191]: info: Loading plugin "outputs"... Apr 25 12:04:03 volumio volumio[5191]: info: Loading plugin "albumart"... Apr 25 12:04:03 volumio volumio[5191]: info: Plugin example_plugin is not enabled Apr 25 12:04:03 volumio volumio[5191]: info: Loading plugin "inputs"... Apr 25 12:04:03 volumio volumio[5191]: info: Loading plugin "updater_comm"... Apr 25 12:04:03 volumio volumio[5191]: info: Plugin mpdemulation is not enabled Apr 25 12:04:03 volumio volumio[5191]: info: Loading plugin "rest_api"... Apr 25 12:04:03 volumio volumio[5191]: info: Loading plugin "websocket"... Apr 25 12:04:03 volumio volumio[5191]: info: Starting Socket.io Server version 1.7.4 Apr 25 12:04:03 volumio volumio[5191]: info: Loading i18n strings for locale it Apr 25 12:04:03 volumio volumio[5191]: Updating browse sources language Apr 25 12:04:03 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:04:03 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:04:03 volumio volumio[5191]: info: CoreCommandRouter::initPlayerControls Apr 25 12:04:03 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:03 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:03 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:03 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:03 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:03 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:03 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:03 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:03 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:04:03 volumio volumio[5191]: Express server listening on port 3000 Apr 25 12:04:03 volumio volumio[5191]: [Metrics] WebUI: 0s 975.00ms Apr 25 12:04:03 volumio volumio[5191]: info: CoreStateMachine::resetVolumioState Apr 25 12:04:03 volumio volumio[5191]: info: CoreStateMachine::getcurrentVolume Apr 25 12:04:03 volumio volumio[5191]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 12:04:03 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:03 volumio volumio[5191]: info: Volumio Network Manager: Network status updated: 2 Apr 25 12:04:03 volumio volumio[5191]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 25 12:04:03 volumio volumio[5191]: wlan0 Interface doesn't support scanning : Device or resource busy Apr 25 12:04:03 volumio volumio[5191]: info: Cannot use regular scanning, forcing with ap-force Apr 25 12:04:03 volumio sudo[5272]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 25 12:04:03 volumio sudo[5272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:03 volumio volumio[5254]: Forking 3 albumart workers Apr 25 12:04:03 volumio volumio-remote-updater[870]: [2025-04-25 12:04:03] [connect] Successful connection Apr 25 12:04:03 volumio volumio[5274]: Starting albumart workers Apr 25 12:04:03 volumio volumio[5276]: Starting albumart workers Apr 25 12:04:03 volumio volumio[5275]: Starting albumart workers Apr 25 12:04:06 volumio sudo[5272]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:07 volumio volumio[5191]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Apr 25 12:04:07 volumio volumio[5191]: Unable to parse: Apr 25 12:04:07 volumio volumio[5191]: Simple mixer control 'Master',0 Apr 25 12:04:07 volumio volumio[5191]: Capabilities: volume pswitch pswitch-joined Apr 25 12:04:07 volumio volumio[5191]: Playback channels: Front Left - Front Right Apr 25 12:04:07 volumio volumio[5191]: Capture channels: Front Left - Front Right Apr 25 12:04:07 volumio volumio[5191]: Limits: 0 - 128 Apr 25 12:04:07 volumio volumio[5191]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:07 volumio volumio[5191]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:07 volumio volumio[5191]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 25 12:04:07 volumio volumio-remote-updater[870]: [2025-04-25 12:04:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745575443 101 Apr 25 12:04:07 volumio volumio[5191]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Apr 25 12:04:07 volumio volumio[5191]: info: Reloading queue from file Apr 25 12:04:07 volumio volumio[5191]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Apr 25 12:04:07 volumio volumio[5191]: Unable to parse: Apr 25 12:04:07 volumio volumio[5191]: Simple mixer control 'Master',0 Apr 25 12:04:07 volumio volumio[5191]: Capabilities: volume pswitch pswitch-joined Apr 25 12:04:07 volumio volumio[5191]: Playback channels: Front Left - Front Right Apr 25 12:04:07 volumio volumio[5191]: Capture channels: Front Left - Front Right Apr 25 12:04:07 volumio volumio[5191]: Limits: 0 - 128 Apr 25 12:04:07 volumio volumio[5191]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:07 volumio volumio[5191]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:07 volumio volumio[5191]: info: VolumeController:: Volume=undefined Mute =false Apr 25 12:04:07 volumio volumio[5191]: info: CoreStateMachine::pushState Apr 25 12:04:07 volumio volumio[5191]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::volumioPushState Apr 25 12:04:07 volumio volumio[5191]: info: CoreStateMachine::updateTrackBlock Apr 25 12:04:07 volumio volumio[5191]: info: CorePlayQueue::getTrackBlock Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 12:04:07 volumio volumio[5191]: info: CoreStateMachine::setRepeat null single undefined Apr 25 12:04:07 volumio volumio[5191]: info: CoreStateMachine::pushState Apr 25 12:04:07 volumio volumio[5191]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::volumioPushState Apr 25 12:04:07 volumio volumio[5191]: info: CoreStateMachine::setRandom null Apr 25 12:04:07 volumio volumio[5191]: info: CoreStateMachine::pushState Apr 25 12:04:07 volumio volumio[5191]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::volumioPushState Apr 25 12:04:07 volumio volumio[5191]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 25 12:04:07 volumio volumio[5191]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b04170 Apr 25 12:04:07 volumio volumio[5191]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 25 12:04:07 volumio volumio[5191]: info: Setting Device type: Raspberry PI Apr 25 12:04:07 volumio volumio[5191]: Unable to parse: Apr 25 12:04:07 volumio volumio[5191]: Simple mixer control 'Master',0 Apr 25 12:04:07 volumio volumio[5191]: Capabilities: volume pswitch pswitch-joined Apr 25 12:04:07 volumio volumio[5191]: Playback channels: Front Left - Front Right Apr 25 12:04:07 volumio volumio[5191]: Capture channels: Front Left - Front Right Apr 25 12:04:07 volumio volumio[5191]: Limits: 0 - 128 Apr 25 12:04:07 volumio volumio[5191]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:07 volumio volumio[5191]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:07 volumio volumio[5191]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 25 12:04:07 volumio volumio[5191]: info: Completed loading Core Plugins Apr 25 12:04:07 volumio volumio[5191]: info: Preparing to generate the ALSA configuration file Apr 25 12:04:07 volumio volumio[5191]: Unable to parse: Apr 25 12:04:07 volumio volumio[5191]: Simple mixer control 'Master',0 Apr 25 12:04:07 volumio volumio[5191]: Capabilities: volume pswitch pswitch-joined Apr 25 12:04:07 volumio volumio[5191]: Playback channels: Front Left - Front Right Apr 25 12:04:07 volumio volumio[5191]: Capture channels: Front Left - Front Right Apr 25 12:04:07 volumio volumio[5191]: Limits: 0 - 128 Apr 25 12:04:07 volumio volumio[5191]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:07 volumio volumio[5191]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:07 volumio volumio[5191]: info: VolumeController:: Volume=undefined Mute =false Apr 25 12:04:07 volumio volumio[5191]: info: CoreStateMachine::pushState Apr 25 12:04:07 volumio volumio[5191]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::volumioPushState Apr 25 12:04:07 volumio volumio[5191]: info: Asound.conf file unchanged, so no further update is needed Apr 25 12:04:07 volumio volumio[5191]: info: Output device has changed, restarting MPD Apr 25 12:04:07 volumio volumio[5191]: info: Output device has changed, restarting Shairport Sync Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:07 volumio sudo[5334]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 12:04:07 volumio sudo[5334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:07 volumio sudo[5336]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 12:04:07 volumio sudo[5336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:07 volumio sudo[5334]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:07 volumio volumio[5191]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:04:07 volumio volumio[5191]: info: ___________ START PLUGINS ___________ Apr 25 12:04:07 volumio volumio[5191]: info: ControllerMpd::onStart: Initializing MPD Apr 25 12:04:07 volumio volumio[5191]: info: Creating MPD Configuration file Apr 25 12:04:07 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:04:07 volumio volumio[5191]: info: [1745575447103] CoreMusicLibrary::Adding element Server multimediali Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:04:07 volumio sudo[5344]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 12:04:07 volumio sudo[5344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:07 volumio sudo[5344]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:07 volumio sudo[5346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:07 volumio sudo[5346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:07 volumio volumio[5191]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:07 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 12:04:07 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 12:04:07 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 12:04:07 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 12:04:07 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 12:04:07 volumio volumio[5191]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:04:07 volumio volumio[5191]: info: [1745575447131] CoreMusicLibrary::Adding element Last_100 Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:04:07 volumio volumio[5191]: info: [1745575447132] CoreMusicLibrary::Adding element Webradio Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:04:07 volumio volumio[5191]: info: Initializing BBC Radios Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: Volumio Calling Home Apr 25 12:04:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 12:04:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 12:04:07 volumio sudo[5373]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 12:04:07 volumio sudo[5373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 25 12:04:07 volumio sudo[5373]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:07 volumio volumio[5191]: info: Discovery: adding 7254fb98-9cc9-453e-b80a-02d2215a0256 Apr 25 12:04:07 volumio volumio[5191]: info: Discovery: Found device Volumio Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:07 volumio volumio[5191]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:07 volumio volumio[5191]: info: Discovery: this is already registered, 7254fb98-9cc9-453e-b80a-02d2215a0256 Apr 25 12:04:07 volumio volumio[5191]: info: Discovery: Found device Volumio Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:07 volumio volumio[5191]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:07 volumio volumio[5191]: info: MPD Permissions set Apr 25 12:04:07 volumio volumio[5191]: info: MPD Permissions set Apr 25 12:04:07 volumio volumio[5191]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 25 12:04:07 volumio volumio[5191]: info: Volumio called home Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:07 volumio volumio[5191]: info: Starting Shairport Sync Apr 25 12:04:07 volumio volumio[5191]: info: Starting Shairport Sync Apr 25 12:04:07 volumio volumio[5191]: info: Starting Shairport Sync Apr 25 12:04:07 volumio sudo[5388]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:04:07 volumio sudo[5388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:07 volumio sudo[5390]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:04:07 volumio sudo[5390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:07 volumio sudo[5393]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:04:07 volumio sudo[5393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:07 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 12:04:07 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 12:04:07 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:04:07 volumio systemd[1]: shairport-sync.service: Consumed 1.515s CPU time. Apr 25 12:04:07 volumio volumio[5191]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:07 volumio volumio[5191]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:07 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:04:07 volumio sudo[5388]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:07 volumio volumio[5191]: info: Shairport-Sync Started Apr 25 12:04:07 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 12:04:07 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 12:04:07 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:04:07 volumio volumio[5191]: Error adding Membership: Error: addMembership EINVAL Apr 25 12:04:07 volumio sudo[5390]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:07 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:04:07 volumio volumio[5191]: info: Shairport-Sync Started Apr 25 12:04:07 volumio sudo[5393]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:07 volumio volumio[5191]: info: Shairport-Sync Started Apr 25 12:04:07 volumio mpd[5376]: 2025-04-25T12:04:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 25 12:04:07 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 25 12:04:07 volumio sudo[5346]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:07 volumio sudo[5336]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:07 volumio volumio[5191]: info: Completed starting Core Plugins Apr 25 12:04:07 volumio volumio[5191]: info: ------------------------------------------- Apr 25 12:04:07 volumio volumio[5191]: info: ----- MyVolumio plugins startup ---- Apr 25 12:04:07 volumio volumio[5191]: info: ------------------------------------------- Apr 25 12:04:07 volumio volumio[5191]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 25 12:04:07 volumio volumio[5191]: error: MPD error: The expression evaluated to a falsy value: Apr 25 12:04:07 volumio volumio[5191]: assert.ok(self.idling) Apr 25 12:04:07 volumio volumio[5191]: error: The expression evaluated to a falsy value: Apr 25 12:04:07 volumio volumio[5191]: assert.ok(self.idling) Apr 25 12:04:07 volumio volumio[5191]: info: MPD running with PID5376 Apr 25 12:04:07 volumio volumio[5191]: ,establishing connection Apr 25 12:04:07 volumio volumio[5191]: error: updateQueue error: null Apr 25 12:04:07 volumio volumio[5191]: error: updateQueue error: null Apr 25 12:04:10 volumio volumio[5191]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 5 Apr 25 12:04:10 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 12:04:10 volumio volumio[5191]: info: CoreCommandRouter::volumioGetVisibleSources Apr 25 12:04:10 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:04:10 volumio volumio[5191]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:10 volumio volumio[5191]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:10 volumio volumio[5191]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:10 volumio volumio[5191]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:10 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 25 12:04:10 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 25 12:04:10 volumio volumio[5191]: info: Received Get System Info Apr 25 12:04:10 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 12:04:10 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 12:04:10 volumio volumio[5191]: info: Discovery: Getting this device information Apr 25 12:04:10 volumio volumio[5191]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:10 volumio volumio[5191]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:10 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 12:04:10 volumio volumio[5191]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:10 volumio volumio[5191]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:10 volumio volumio[5191]: info: Listing playlists Apr 25 12:04:10 volumio volumio[5191]: info: CoreCommandRouter::volumioGetQueue Apr 25 12:04:10 volumio volumio[5191]: info: CoreStateMachine::getQueue Apr 25 12:04:10 volumio volumio[5191]: info: CorePlayQueue::getQueue Apr 25 12:04:12 volumio volumio[5191]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 25 12:04:12 volumio sudo[5434]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 25 12:04:12 volumio sudo[5434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:12 volumio sudo[5436]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 25 12:04:12 volumio sudo[5436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:12 volumio sudo[5434]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:12 volumio sudo[5436]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:12 volumio sudo[5438]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 25 12:04:12 volumio sudo[5438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:12 volumio sudo[5438]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:12 volumio volumio[5191]: info: Upmpdcli Daemon Started Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 25 12:04:16 volumio volumio[5191]: info: Adding plugin bluetooth to MyMusic Plugins Apr 25 12:04:16 volumio volumio[5191]: info: Adding plugin multiroom to MyMusic Plugins Apr 25 12:04:16 volumio volumio[5191]: info: Adding plugin metavolumio to MyMusic Plugins Apr 25 12:04:16 volumio volumio[5191]: info: Adding plugin cd_controller to MyMusic Plugins Apr 25 12:04:16 volumio volumio[5191]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 25 12:04:16 volumio volumio[5191]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 25 12:04:16 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:16 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:16 volumio volumio[5191]: info: Starting MyVolumio Remote Streaming Endpoints Apr 25 12:04:16 volumio volumio[5191]: info: MyVolumio not started Apr 25 12:04:16 volumio volumio[5191]: info: Initializing device activation check Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 25 12:04:16 volumio volumio[5191]: info: Streaming services startup Apr 25 12:04:16 volumio volumio[5191]: info: Starting Streaming Daemon Apr 25 12:04:16 volumio sudo[5444]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 25 12:04:16 volumio sudo[5444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:16 volumio volumio[5191]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 25 12:04:16 volumio sudo[5444]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:16 volumio volumio[5191]: error: Cannot start Volumio Streaming Daemon Apr 25 12:04:16 volumio volumio[5191]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 25 12:04:16 volumio volumio[5191]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 25 12:04:31 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 25 12:04:31 volumio volumio[5191]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 25 12:04:31 volumio volumio[5191]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:31 volumio volumio[5191]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:36 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:36 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:36 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 25 12:04:36 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 25 12:04:36 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 25 12:04:36 volumio volumio[5191]: info: CoreCommandRouter::volumioGetBrowseSources Apr 25 12:04:36 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:04:36 volumio volumio[5191]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 25 12:04:36 volumio volumio[5191]: info: Completed starting MyVolumio Plugin Apr 25 12:04:36 volumio volumio[5191]: [Metrics] CommandRouter: 33s 973.77ms Apr 25 12:04:36 volumio volumio[5191]: info: CoreCommandRouter::volumiosetStartupVolume Apr 25 12:04:36 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:36 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:36 volumio volumio[5191]: info: CoreCommandRouter::Close All Modals sent Apr 25 12:04:36 volumio volumio[5191]: info: CoreCommandRouter::Close All Modals sent Apr 25 12:04:37 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:04:37 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:37 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 12:04:37 volumio volumio-remote-updater[870]: No test mode Apr 25 12:04:37 volumio volumio-remote-updater[870]: No alpha test mode Apr 25 12:04:37 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 25 12:04:37 volumio volumio[5191]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 25 12:04:37 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 25 12:04:37 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 25 12:04:37 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 12:04:37 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 25 12:04:43 volumio volumio[5191]: info: BOOT COMPLETED Apr 25 12:04:43 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 25 12:04:43 volumio volumio[5191]: info: TuneIn handleBrowseUri: tunein/browse/?id=r100359 Apr 25 12:04:43 volumio volumio[5191]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r100359 Apr 25 12:04:43 volumio volumio[5191]: error: Cannot read properties of undefined (reading 'indexOf') Apr 25 12:04:43 volumio volumio[5191]: error: Failed to execute browseSource: Error: Cannot list category items for tunein/browse/?id=r100359: TypeError: Cannot read properties of undefined (reading 'indexOf') Apr 25 12:04:53 volumio volumio[5191]: Searching plugin music_service/webradio Apr 25 12:04:53 volumio volumio[5191]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 25 12:04:53 volumio volumio[5191]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 12:04:53 volumio volumio[5191]: TypeError: Cannot read properties of undefined (reading 'length') Apr 25 12:04:53 volumio volumio[5191]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:186:126 Apr 25 12:04:53 volumio volumio[5191]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Apr 25 12:04:53 volumio volumio[5191]: at /volumio/app/plugins/music_service/webradio/index.js:949:31 Apr 25 12:04:53 volumio volumio[5191]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 25 12:04:53 volumio volumio[5191]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 12:04:53 volumio sudo[5531]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-04-25 12:03' Apr 25 12:04:53 volumio sudo[5531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:54 volumio sudo[5531]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:54 volumio volumio-remote-updater[870]: [2025-04-25 12:04:54] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 25 12:04:54 volumio volumio-remote-updater[870]: [2025-04-25 12:04:54] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 25 12:04:54 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 25 12:04:54 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 25 12:04:54 volumio systemd[1]: volumio.service: Consumed 5.946s CPU time. Apr 25 12:04:54 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 25 12:04:54 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Apr 25 12:04:54 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9. Apr 25 12:04:54 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 25 12:04:54 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 25 12:04:54 volumio systemd[1]: volumio.service: Consumed 5.946s CPU time. Apr 25 12:04:54 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 25 12:04:54 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Apr 25 12:04:54 volumio volumio[5547]: info: ------------------------------------------- Apr 25 12:04:54 volumio volumio[5547]: info: ----- Volumio3 ---- Apr 25 12:04:54 volumio volumio[5547]: info: ------------------------------------------- Apr 25 12:04:54 volumio volumio[5547]: info: ----- System startup ---- Apr 25 12:04:54 volumio volumio[5547]: info: ------------------------------------------- Apr 25 12:04:55 volumio volumio[5547]: info: MYVOLUMIO Environment detected Apr 25 12:04:55 volumio volumio[5547]: info: Plugin folders cleanup Apr 25 12:04:55 volumio volumio[5547]: info: Scanning into folder /volumio/app/plugins/ Apr 25 12:04:55 volumio volumio[5547]: info: Scanning category audio_interface Apr 25 12:04:55 volumio volumio[5547]: info: Scanning category miscellanea Apr 25 12:04:55 volumio volumio[5547]: info: Scanning category music_service Apr 25 12:04:55 volumio volumio[5547]: info: Scanning category plugins.json Apr 25 12:04:55 volumio volumio[5547]: info: Scanning category system_controller Apr 25 12:04:55 volumio volumio[5547]: info: Scanning category user_interface Apr 25 12:04:55 volumio volumio[5547]: info: Scanning into folder /data/plugins/ Apr 25 12:04:55 volumio volumio[5547]: info: Plugin folders cleanup completed Apr 25 12:04:55 volumio volumio[5547]: info: ------------------------------------------- Apr 25 12:04:55 volumio volumio[5547]: info: ----- Core plugins startup ---- Apr 25 12:04:55 volumio volumio[5547]: info: ------------------------------------------- Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugins from folder /volumio/app/plugins/ Apr 25 12:04:55 volumio volumio[5547]: info: Adding plugin upnp to MyMusic Plugins Apr 25 12:04:55 volumio volumio[5547]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 25 12:04:55 volumio volumio[5547]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugins from folder /data/plugins/ Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "system"... Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "appearance"... Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "network"... Apr 25 12:04:55 volumio volumio[5547]: info: Refreshing Cached IP Addresses Apr 25 12:04:55 volumio sudo[5575]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 25 12:04:55 volumio sudo[5575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:55 volumio sudo[5577]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 25 12:04:55 volumio sudo[5577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:55 volumio sudo[5575]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "services"... Apr 25 12:04:55 volumio sudo[5577]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "alsa_controller"... Apr 25 12:04:55 volumio sudo[5586]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 25 12:04:55 volumio sudo[5586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "wizard"... Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "networkfs"... Apr 25 12:04:55 volumio volumio[5547]: info: Starting Udev Watcher for removable devices Apr 25 12:04:55 volumio volumio[5547]: info: Ignoring mount for partition: boot Apr 25 12:04:55 volumio volumio[5547]: info: Ignoring mount for partition: volumio Apr 25 12:04:55 volumio volumio[5547]: info: Ignoring mount for partition: volumio_data Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "volumio_command_line_client"... Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "upnp"... Apr 25 12:04:55 volumio volumio[5547]: info: [1745575495392] Starting Upmpd Daemon Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "my_music"... Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "mpd"... Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "upnp_browser"... Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "alarm-clock"... Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "airplay_emulation"... Apr 25 12:04:55 volumio volumio[5547]: info: Starting Shairport Sync Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "last_100"... Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "webradio"... Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "i2s_dacs"... Apr 25 12:04:55 volumio volumio[5547]: info: I2S DAC not set, start Auto-detection Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "volumiodiscovery"... Apr 25 12:04:55 volumio volumio[5547]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 25 12:04:55 volumio volumio[5547]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:04:55 volumio volumio[5547]: *** WARNING *** For more information see Apr 25 12:04:55 volumio volumio[5547]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 25 12:04:55 volumio volumio[5547]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:04:55 volumio volumio[5547]: *** WARNING *** For more information see Apr 25 12:04:55 volumio node[5547]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 25 12:04:55 volumio node[5547]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:04:55 volumio node[5547]: *** WARNING *** For more information see Apr 25 12:04:55 volumio node[5547]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 25 12:04:55 volumio node[5547]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 25 12:04:55 volumio node[5547]: *** WARNING *** For more information see Apr 25 12:04:55 volumio volumio[5547]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 25 12:04:55 volumio volumio[5547]: info: Discovery: Started advertising with name: Volumio Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "outputs"... Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "albumart"... Apr 25 12:04:55 volumio volumio[5547]: info: Plugin example_plugin is not enabled Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "inputs"... Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "updater_comm"... Apr 25 12:04:55 volumio volumio[5547]: info: Plugin mpdemulation is not enabled Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "rest_api"... Apr 25 12:04:55 volumio volumio[5547]: info: Loading plugin "websocket"... Apr 25 12:04:55 volumio volumio[5547]: info: Starting Socket.io Server version 1.7.4 Apr 25 12:04:55 volumio volumio[5547]: info: Loading i18n strings for locale it Apr 25 12:04:55 volumio volumio[5547]: Updating browse sources language Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::initPlayerControls Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:04:55 volumio volumio[5547]: Express server listening on port 3000 Apr 25 12:04:55 volumio volumio[5547]: [Metrics] WebUI: 0s 980.29ms Apr 25 12:04:55 volumio volumio[5547]: info: CoreStateMachine::resetVolumioState Apr 25 12:04:55 volumio volumio[5547]: info: CoreStateMachine::getcurrentVolume Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:55 volumio volumio[5547]: info: Volumio Network Manager: Network status updated: 2 Apr 25 12:04:55 volumio volumio[5547]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Apr 25 12:04:55 volumio volumio[5610]: Forking 3 albumart workers Apr 25 12:04:55 volumio volumio[5547]: Unable to parse: Apr 25 12:04:55 volumio volumio[5547]: Simple mixer control 'Master',0 Apr 25 12:04:55 volumio volumio[5547]: Capabilities: volume pswitch pswitch-joined Apr 25 12:04:55 volumio volumio[5547]: Playback channels: Front Left - Front Right Apr 25 12:04:55 volumio volumio[5547]: Capture channels: Front Left - Front Right Apr 25 12:04:55 volumio volumio[5547]: Limits: 0 - 128 Apr 25 12:04:55 volumio volumio[5547]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:55 volumio volumio[5547]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:55 volumio volumio[5547]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 25 12:04:55 volumio volumio[5547]: info: Reloading queue from file Apr 25 12:04:55 volumio volumio[5547]: info: CoreStateMachine::setRepeat null single undefined Apr 25 12:04:55 volumio volumio[5547]: info: CoreStateMachine::pushState Apr 25 12:04:55 volumio volumio[5547]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::volumioPushState Apr 25 12:04:55 volumio volumio[5547]: info: CoreStateMachine::setRandom null Apr 25 12:04:55 volumio volumio[5547]: info: CoreStateMachine::pushState Apr 25 12:04:55 volumio volumio[5547]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::volumioPushState Apr 25 12:04:55 volumio volumio[5547]: Unable to parse: Apr 25 12:04:55 volumio volumio[5547]: Simple mixer control 'Master',0 Apr 25 12:04:55 volumio volumio[5547]: Capabilities: volume pswitch pswitch-joined Apr 25 12:04:55 volumio volumio[5547]: Playback channels: Front Left - Front Right Apr 25 12:04:55 volumio volumio[5547]: Capture channels: Front Left - Front Right Apr 25 12:04:55 volumio volumio[5547]: Limits: 0 - 128 Apr 25 12:04:55 volumio volumio[5547]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:55 volumio volumio[5547]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:55 volumio volumio[5547]: info: VolumeController:: Volume=undefined Mute =false Apr 25 12:04:55 volumio volumio[5547]: info: CoreStateMachine::pushState Apr 25 12:04:55 volumio volumio[5547]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::volumioPushState Apr 25 12:04:55 volumio volumio[5547]: info: CoreStateMachine::updateTrackBlock Apr 25 12:04:55 volumio volumio[5547]: info: CorePlayQueue::getTrackBlock Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 12:04:55 volumio volumio[5547]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 25 12:04:55 volumio volumio[5547]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b04170 Apr 25 12:04:55 volumio volumio[5547]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 25 12:04:55 volumio volumio[5547]: info: Setting Device type: Raspberry PI Apr 25 12:04:55 volumio volumio[5547]: info: Completed loading Core Plugins Apr 25 12:04:55 volumio volumio[5547]: info: Preparing to generate the ALSA configuration file Apr 25 12:04:55 volumio volumio[5547]: Unable to parse: Apr 25 12:04:55 volumio volumio[5547]: Simple mixer control 'Master',0 Apr 25 12:04:55 volumio volumio[5547]: Capabilities: volume pswitch pswitch-joined Apr 25 12:04:55 volumio volumio[5547]: Playback channels: Front Left - Front Right Apr 25 12:04:55 volumio volumio[5547]: Capture channels: Front Left - Front Right Apr 25 12:04:55 volumio volumio[5547]: Limits: 0 - 128 Apr 25 12:04:55 volumio volumio[5547]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:55 volumio volumio[5547]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:55 volumio volumio[5547]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 25 12:04:55 volumio volumio[5547]: info: Asound.conf file unchanged, so no further update is needed Apr 25 12:04:55 volumio volumio[5547]: info: Output device has changed, restarting MPD Apr 25 12:04:55 volumio volumio[5547]: info: Output device has changed, restarting Shairport Sync Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:55 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:55 volumio sudo[5664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 12:04:55 volumio sudo[5664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:55 volumio sudo[5664]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:55 volumio sudo[5666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 12:04:55 volumio sudo[5666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:56 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 25 12:04:56 volumio volumio[5547]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:04:56 volumio volumio[5547]: info: ___________ START PLUGINS ___________ Apr 25 12:04:56 volumio volumio[5547]: info: ControllerMpd::onStart: Initializing MPD Apr 25 12:04:56 volumio volumio[5547]: info: Creating MPD Configuration file Apr 25 12:04:56 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:04:56 volumio volumio[5547]: info: [1745575496023] CoreMusicLibrary::Adding element Server multimediali Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:04:56 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 12:04:56 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 12:04:56 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 12:04:56 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 12:04:56 volumio sudo[5674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 12:04:56 volumio sudo[5674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:56 volumio sudo[5674]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:56 volumio sudo[5676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 12:04:56 volumio sudo[5676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:56 volumio volumio[5547]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:04:56 volumio volumio[5547]: info: [1745575496053] CoreMusicLibrary::Adding element Last_100 Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:04:56 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 12:04:56 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 12:04:56 volumio volumio[5547]: info: [1745575496060] CoreMusicLibrary::Adding element Webradio Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:04:56 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 12:04:56 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 12:04:56 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 12:04:56 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 12:04:56 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 12:04:56 volumio volumio[5547]: info: Initializing BBC Radios Apr 25 12:04:56 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 12:04:56 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: Volumio Calling Home Apr 25 12:04:56 volumio sudo[5694]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 12:04:56 volumio sudo[5694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 25 12:04:56 volumio sudo[5694]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:56 volumio volumio[5547]: info: MPD Permissions set Apr 25 12:04:56 volumio volumio[5547]: info: MPD Permissions set Apr 25 12:04:56 volumio volumio[5547]: Unable to parse: Apr 25 12:04:56 volumio volumio[5547]: Simple mixer control 'Master',0 Apr 25 12:04:56 volumio volumio[5547]: Capabilities: volume pswitch pswitch-joined Apr 25 12:04:56 volumio volumio[5547]: Playback channels: Front Left - Front Right Apr 25 12:04:56 volumio volumio[5547]: Capture channels: Front Left - Front Right Apr 25 12:04:56 volumio volumio[5547]: Limits: 0 - 128 Apr 25 12:04:56 volumio volumio[5547]: Front Left: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:56 volumio volumio[5547]: Front Right: 128 [100%] [0.00dB] Playback [on] Apr 25 12:04:56 volumio volumio[5547]: info: VolumeController:: Volume=undefined Mute =false Apr 25 12:04:56 volumio volumio[5547]: info: CoreStateMachine::pushState Apr 25 12:04:56 volumio volumio[5547]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::volumioPushState Apr 25 12:04:56 volumio volumio[5626]: Starting albumart workers Apr 25 12:04:56 volumio volumio[5547]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 12:04:56 volumio volumio[5628]: Starting albumart workers Apr 25 12:04:56 volumio volumio[5547]: info: Volumio called home Apr 25 12:04:56 volumio volumio[5632]: Starting albumart workers Apr 25 12:04:56 volumio volumio[5547]: info: Starting Shairport Sync Apr 25 12:04:56 volumio volumio[5547]: info: Starting Shairport Sync Apr 25 12:04:56 volumio sudo[5721]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:04:56 volumio sudo[5721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:56 volumio volumio[5547]: info: Starting Shairport Sync Apr 25 12:04:56 volumio sudo[5730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:04:56 volumio sudo[5730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:56 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 12:04:56 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 12:04:56 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:04:56 volumio systemd[1]: shairport-sync.service: Consumed 1.562s CPU time. Apr 25 12:04:56 volumio sudo[5733]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 12:04:56 volumio sudo[5733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 12:04:56 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:04:56 volumio sudo[5721]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:56 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 12:04:56 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 12:04:56 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:04:56 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:04:56 volumio sudo[5733]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:56 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 12:04:56 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 12:04:56 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:04:56 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 12:04:56 volumio sudo[5730]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:56 volumio volumio[5547]: Searching plugin music_service/webradio Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::volumioGetVisibleSources Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:56 volumio volumio[5547]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:56 volumio volumio[5547]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 25 12:04:56 volumio volumio[5547]: info: Received Get System Info Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 12:04:56 volumio volumio[5547]: info: Discovery: Getting this device information Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:56 volumio volumio[5547]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:56 volumio volumio[5547]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:56 volumio volumio[5547]: info: Listing playlists Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::volumioGetQueue Apr 25 12:04:56 volumio volumio[5547]: info: CoreStateMachine::getQueue Apr 25 12:04:56 volumio volumio[5547]: info: CorePlayQueue::getQueue Apr 25 12:04:56 volumio volumio[5547]: info: Shairport-Sync Started Apr 25 12:04:56 volumio volumio[5547]: Error adding Membership: Error: addMembership EINVAL Apr 25 12:04:56 volumio volumio[5547]: info: Shairport-Sync Started Apr 25 12:04:56 volumio volumio[5547]: info: Shairport-Sync Started Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:56 volumio volumio[5547]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:56 volumio mpd[5707]: 2025-04-25T12:04:56 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 25 12:04:56 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 25 12:04:56 volumio sudo[5676]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:56 volumio sudo[5666]: pam_unix(sudo:session): session closed for user root Apr 25 12:04:56 volumio volumio[5547]: info: Completed starting Core Plugins Apr 25 12:04:56 volumio volumio[5547]: info: ------------------------------------------- Apr 25 12:04:56 volumio volumio[5547]: info: ----- MyVolumio plugins startup ---- Apr 25 12:04:56 volumio volumio[5547]: info: ------------------------------------------- Apr 25 12:04:56 volumio volumio[5547]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 25 12:04:56 volumio volumio[5547]: error: MPD error: The expression evaluated to a falsy value: Apr 25 12:04:56 volumio volumio[5547]: assert.ok(self.idling) Apr 25 12:04:56 volumio volumio[5547]: error: The expression evaluated to a falsy value: Apr 25 12:04:56 volumio volumio[5547]: assert.ok(self.idling) Apr 25 12:04:56 volumio volumio[5547]: error: updateQueue error: null Apr 25 12:04:56 volumio volumio[5547]: info: MPD running with PID5707 Apr 25 12:04:56 volumio volumio[5547]: ,establishing connection Apr 25 12:04:56 volumio volumio[5547]: error: updateQueue error: null Apr 25 12:04:56 volumio volumio[5547]: info: Discovery: adding 7254fb98-9cc9-453e-b80a-02d2215a0256 Apr 25 12:04:56 volumio volumio[5547]: info: Discovery: Found device Volumio Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:56 volumio volumio[5547]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:56 volumio volumio[5547]: info: Discovery: this is already registered, 7254fb98-9cc9-453e-b80a-02d2215a0256 Apr 25 12:04:56 volumio volumio[5547]: info: Discovery: Found device Volumio Apr 25 12:04:56 volumio volumio[5547]: info: CoreCommandRouter::volumioGetState Apr 25 12:04:56 volumio volumio[5547]: info: CorePlayQueue::getTrack 0 Apr 25 12:04:56 volumio volumio[5547]: Use XMLElement.setAttribute instead Apr 25 12:04:56 volumio volumio[5547]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 12:04:56 volumio volumio[5547]: TypeError: children[i].attr(...).value is not a function Apr 25 12:04:56 volumio volumio[5547]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Apr 25 12:04:56 volumio volumio[5547]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 25 12:04:56 volumio volumio[5547]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 12:04:57 volumio sudo[5798]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-04-25 12:03' Apr 25 12:04:57 volumio sudo[5798]: 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="5431d94b4ec10a9432d7232ad6f9406bf7be8de2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Apr 24 12:55:04 UTC 2025" VOLUMIO_VERSION="0.060" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="96943b9514b750671d63915838f6ba9a"