-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2024-10-22 14:44:23 CEST. -- Oct 22 14:43:00 volumio volumio[1979]: info: MYVOLUMIO Environment detected Oct 22 14:43:00 volumio volumio[1979]: info: Plugin folders cleanup Oct 22 14:43:00 volumio volumio[1979]: info: Scanning into folder /volumio/app/plugins/ Oct 22 14:43:00 volumio volumio[1979]: info: Scanning category audio_interface Oct 22 14:43:00 volumio volumio[1979]: info: Scanning category miscellanea Oct 22 14:43:00 volumio volumio[1979]: info: Scanning category music_service Oct 22 14:43:00 volumio volumio[1979]: info: Scanning category plugins.json Oct 22 14:43:00 volumio volumio[1979]: info: Scanning category system_controller Oct 22 14:43:00 volumio volumio[1979]: info: Scanning category user_interface Oct 22 14:43:00 volumio volumio[1979]: info: Scanning into folder /data/plugins/ Oct 22 14:43:00 volumio volumio[1979]: info: Plugin folders cleanup completed Oct 22 14:43:00 volumio volumio[1979]: info: ------------------------------------------- Oct 22 14:43:00 volumio volumio[1979]: info: ----- Core plugins startup ---- Oct 22 14:43:00 volumio volumio[1979]: info: ------------------------------------------- Oct 22 14:43:00 volumio volumio[1979]: info: Loading plugins from folder /volumio/app/plugins/ Oct 22 14:43:00 volumio volumio[1979]: info: Adding plugin upnp to MyMusic Plugins Oct 22 14:43:00 volumio volumio[1979]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 22 14:43:00 volumio volumio[1979]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 22 14:43:00 volumio volumio[1979]: info: Loading plugins from folder /data/plugins/ Oct 22 14:43:00 volumio volumio[1979]: info: Loading plugin "system"... Oct 22 14:43:00 volumio volumio[1979]: info: Loading plugin "appearance"... Oct 22 14:43:00 volumio volumio[1979]: info: Loading plugin "network"... Oct 22 14:43:00 volumio volumio[1979]: info: Refreshing Cached IP Addresses Oct 22 14:43:00 volumio sudo[2007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 22 14:43:00 volumio sudo[2007]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:43:00 volumio sudo[2007]: pam_unix(sudo:session): session closed for user root Oct 22 14:43:00 volumio sudo[2009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 22 14:43:00 volumio volumio[1979]: info: Loading plugin "services"... Oct 22 14:43:00 volumio sudo[2009]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:43:00 volumio volumio[1979]: info: Loading plugin "alsa_controller"... Oct 22 14:43:00 volumio sudo[2009]: pam_unix(sudo:session): session closed for user root Oct 22 14:43:00 volumio sudo[2012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 22 14:43:00 volumio sudo[2012]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:43:00 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 22 14:43:00 volumio volumio[1979]: info: Loading plugin "wizard"... Oct 22 14:43:00 volumio volumio[1979]: info: Loading plugin "networkfs"... Oct 22 14:43:00 volumio volumio[1979]: info: Starting Udev Watcher for removable devices Oct 22 14:43:00 volumio volumio[1979]: info: Ignoring mount for partition: boot Oct 22 14:43:00 volumio volumio[1979]: info: Ignoring mount for partition: volumio Oct 22 14:43:00 volumio volumio[1979]: info: Ignoring mount for partition: volumio_data Oct 22 14:43:00 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 22 14:43:00 volumio volumio[1979]: info: Loading plugin "volumio_command_line_client"... Oct 22 14:43:00 volumio volumio[1979]: info: Loading plugin "upnp"... Oct 22 14:43:00 volumio volumio[1979]: info: [1729600980938] Starting Upmpd Daemon Oct 22 14:43:00 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 22 14:43:00 volumio volumio[1979]: info: Loading plugin "my_music"... Oct 22 14:43:00 volumio volumio[1979]: info: Loading plugin "mpd"... Oct 22 14:43:01 volumio volumio[1979]: info: Loading plugin "upnp_browser"... Oct 22 14:43:01 volumio volumio[1979]: info: Loading plugin "alarm-clock"... Oct 22 14:43:01 volumio volumio[1979]: info: Loading plugin "airplay_emulation"... Oct 22 14:43:01 volumio volumio[1979]: info: Starting Shairport Sync Oct 22 14:43:01 volumio volumio[1979]: info: Loading plugin "last_100"... Oct 22 14:43:01 volumio volumio[1979]: info: Loading plugin "webradio"... Oct 22 14:43:01 volumio volumio[1979]: info: Loading plugin "i2s_dacs"... Oct 22 14:43:01 volumio volumio[1979]: info: I2S DAC not set, start Auto-detection Oct 22 14:43:01 volumio volumio[1979]: info: Loading plugin "volumiodiscovery"... Oct 22 14:43:01 volumio volumio[1979]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 22 14:43:01 volumio volumio[1979]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 22 14:43:01 volumio volumio[1979]: *** WARNING *** For more information see Oct 22 14:43:01 volumio node[1979]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 22 14:43:01 volumio volumio[1979]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 22 14:43:01 volumio volumio[1979]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 22 14:43:01 volumio volumio[1979]: *** WARNING *** For more information see Oct 22 14:43:01 volumio node[1979]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 22 14:43:01 volumio node[1979]: *** WARNING *** For more information see Oct 22 14:43:01 volumio node[1979]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 22 14:43:01 volumio node[1979]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 22 14:43:01 volumio node[1979]: *** WARNING *** For more information see Oct 22 14:43:01 volumio volumio[1979]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 22 14:43:01 volumio volumio[1979]: info: Discovery: Started advertising with name: Volumio Oct 22 14:43:01 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 22 14:43:01 volumio volumio[1979]: info: Loading plugin "outputs"... Oct 22 14:43:01 volumio volumio[1979]: info: Loading plugin "albumart"... Oct 22 14:43:01 volumio volumio[1979]: info: Plugin example_plugin is not enabled Oct 22 14:43:01 volumio volumio[1979]: info: Loading plugin "inputs"... Oct 22 14:43:01 volumio volumio[1979]: info: Loading plugin "updater_comm"... Oct 22 14:43:01 volumio volumio[1979]: info: Plugin mpdemulation is not enabled Oct 22 14:43:01 volumio volumio[1979]: info: Loading plugin "rest_api"... Oct 22 14:43:01 volumio volumio[1979]: info: Loading plugin "websocket"... Oct 22 14:43:01 volumio volumio[1979]: info: Starting Socket.io Server version 2.3.0 Oct 22 14:43:01 volumio volumio[1979]: info: Loading i18n strings for locale de Oct 22 14:43:01 volumio volumio[1979]: Updating browse sources language Oct 22 14:43:01 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 22 14:43:01 volumio volumio[1979]: Forking 3 albumart workers Oct 22 14:43:01 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 14:43:01 volumio volumio[1979]: info: CoreCommandRouter::initPlayerControls Oct 22 14:43:01 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:01 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:01 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:01 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:01 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:01 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:01 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:01 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:01 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 14:43:01 volumio volumio[1979]: Express server listening on port 3000 Oct 22 14:43:01 volumio volumio[1979]: [Metrics] WebUI: 2s 429.88ms Oct 22 14:43:01 volumio volumio[1979]: info: CoreStateMachine::resetVolumioState Oct 22 14:43:01 volumio volumio[1979]: info: CoreStateMachine::getcurrentVolume Oct 22 14:43:01 volumio volumio[1979]: info: CoreCommandRouter::volumioRetrievevolume Oct 22 14:43:01 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:01 volumio volumio[1979]: info: Cannot read play queue from file Oct 22 14:43:02 volumio volumio[1979]: verbose: New Socket.io Connection to 192.168.178.84 from 192.168.178.17 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 22 14:43:02 volumio volumio[1979]: verbose: New Socket.io Connection to 192.168.178.84 from 192.168.178.17 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 22 14:43:02 volumio volumio[1979]: verbose: New Socket.io Connection to 192.168.178.84 from 192.168.178.17 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::volumioGetState Oct 22 14:43:02 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:02 volumio volumio[1979]: info: VolumeController:: Volume=86 Mute =false Oct 22 14:43:02 volumio volumio[1979]: info: CoreStateMachine::pushState Oct 22 14:43:02 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::volumioPushState Oct 22 14:43:02 volumio volumio[1979]: info: CoreStateMachine::updateTrackBlock Oct 22 14:43:02 volumio volumio[1979]: info: CorePlayQueue::getTrackBlock Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::volumioRetrievevolume Oct 22 14:43:02 volumio volumio[1979]: info: CoreStateMachine::setRepeat null single undefined Oct 22 14:43:02 volumio volumio[1979]: info: CoreStateMachine::pushState Oct 22 14:43:02 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::volumioPushState Oct 22 14:43:02 volumio volumio[1979]: info: CoreStateMachine::setRandom null Oct 22 14:43:02 volumio volumio[1979]: info: CoreStateMachine::pushState Oct 22 14:43:02 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::volumioPushState Oct 22 14:43:02 volumio volumio[1979]: info: Setting Device type: Raspberry PI Oct 22 14:43:02 volumio volumio[1979]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 22 14:43:02 volumio volumio[1979]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Oct 22 14:43:02 volumio volumio[1979]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 22 14:43:02 volumio volumio[1979]: info: Received Get System Info Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 22 14:43:02 volumio volumio[1979]: info: Discovery: Getting this device information Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::volumioGetState Oct 22 14:43:02 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::volumioGetState Oct 22 14:43:02 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::volumioGetVisibleSources Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::volumioGetState Oct 22 14:43:02 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::volumioGetState Oct 22 14:43:02 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 22 14:43:02 volumio volumio[1979]: info: Received Get System Info Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 22 14:43:02 volumio volumio[1979]: info: Discovery: Getting this device information Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::volumioGetState Oct 22 14:43:02 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::volumioGetState Oct 22 14:43:02 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:02 volumio volumio[1979]: info: Listing playlists Oct 22 14:43:02 volumio volumio[1979]: Starting albumart workers Oct 22 14:43:02 volumio volumio[1979]: info: VolumeController::SetAlsaVolume0 Oct 22 14:43:02 volumio volumio[1979]: Starting albumart workers Oct 22 14:43:02 volumio volumio[1979]: info: CoreStateMachine::pushState Oct 22 14:43:02 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::volumioPushState Oct 22 14:43:02 volumio volumio[1979]: info: VolumeController:: Volume=86 Mute =false Oct 22 14:43:02 volumio volumio[1979]: info: CoreStateMachine::pushState Oct 22 14:43:02 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::volumioPushState Oct 22 14:43:02 volumio volumio[1979]: Starting albumart workers Oct 22 14:43:02 volumio volumio[1979]: info: Completed loading Core Plugins Oct 22 14:43:02 volumio volumio[1979]: info: Preparing to generate the ALSA configuration file Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:43:02 volumio volumio[1979]: info: Asound.conf file unchanged, so no further update is needed Oct 22 14:43:02 volumio volumio[1979]: info: Output device has changed, restarting MPD Oct 22 14:43:02 volumio volumio[1979]: info: Output device has changed, restarting Shairport Sync Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:02 volumio sudo[2064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 22 14:43:02 volumio sudo[2064]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:43:02 volumio sudo[2066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 22 14:43:02 volumio sudo[2066]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:43:02 volumio sudo[2064]: pam_unix(sudo:session): session closed for user root Oct 22 14:43:02 volumio systemd[1]: Stopping Music Player Daemon... Oct 22 14:43:02 volumio systemd[1]: mpd.service: Succeeded. Oct 22 14:43:02 volumio systemd[1]: Stopped Music Player Daemon. Oct 22 14:43:02 volumio volumio[1979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 22 14:43:02 volumio volumio[1979]: info: ___________ START PLUGINS ___________ Oct 22 14:43:02 volumio volumio[1979]: info: ControllerMpd::onStart: Initializing MPD Oct 22 14:43:02 volumio volumio[1979]: info: Creating MPD Configuration file Oct 22 14:43:02 volumio systemd[1]: Starting Music Player Daemon... Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 14:43:02 volumio sudo[2077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 22 14:43:02 volumio sudo[2079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 22 14:43:02 volumio sudo[2079]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:43:02 volumio sudo[2077]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:43:02 volumio volumio[1979]: info: [1729600982740] CoreMusicLibrary::Adding element Medienserver Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 22 14:43:02 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 22 14:43:02 volumio systemd[1]: mpd.service: Succeeded. Oct 22 14:43:02 volumio systemd[1]: Stopped Music Player Daemon. Oct 22 14:43:02 volumio sudo[2077]: pam_unix(sudo:session): session closed for user root Oct 22 14:43:02 volumio systemd[1]: Starting Music Player Daemon... Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:02 volumio sudo[2086]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 22 14:43:02 volumio sudo[2086]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:43:02 volumio sudo[2086]: pam_unix(sudo:session): session closed for user root Oct 22 14:43:02 volumio volumio[1979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:02 volumio volumio[1979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 22 14:43:02 volumio volumio[1979]: info: [1729600982952] CoreMusicLibrary::Adding element Last_100 Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 22 14:43:02 volumio volumio[1979]: info: [1729600982957] CoreMusicLibrary::Adding element Webradio Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 22 14:43:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 22 14:43:02 volumio volumio[1979]: info: Initializing BBC Radios Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: Volumio Calling Home Oct 22 14:43:03 volumio volumio[1979]: info: MPD Permissions set Oct 22 14:43:03 volumio volumio[1979]: info: MPD Permissions set Oct 22 14:43:03 volumio volumio[1979]: info: Volumio called home Oct 22 14:43:03 volumio volumio[1979]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:03 volumio volumio[1979]: info: Discovery: adding e5929a6e-ab4c-41a2-ac00-e21aec15ecb6 Oct 22 14:43:03 volumio volumio[1979]: info: Discovery: Found device Volumio Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::volumioGetState Oct 22 14:43:03 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:03 volumio volumio-remote-updater[655]: [2024-10-22 12:43:03] [connect] Successful connection Oct 22 14:43:03 volumio volumio[1979]: info: Discovery: this is already registered, e5929a6e-ab4c-41a2-ac00-e21aec15ecb6 Oct 22 14:43:03 volumio volumio[1979]: info: Discovery: Found device Volumio Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::volumioGetState Oct 22 14:43:03 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:03 volumio volumio-remote-updater[655]: [2024-10-22 12:43:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729600983 101 Oct 22 14:43:03 volumio volumio[1979]: 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: 5 Oct 22 14:43:03 volumio volumio[1979]: info: Starting Shairport Sync Oct 22 14:43:03 volumio volumio[1979]: info: Starting Shairport Sync Oct 22 14:43:03 volumio sudo[2134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 22 14:43:03 volumio sudo[2134]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:43:03 volumio volumio[1979]: info: Starting Shairport Sync Oct 22 14:43:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 22 14:43:03 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 22 14:43:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 22 14:43:03 volumio volumio[1979]: info: CoreCommandRouter::volumioGetState Oct 22 14:43:03 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:03 volumio sudo[2139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 22 14:43:03 volumio sudo[2139]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:43:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 22 14:43:03 volumio sudo[2137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 22 14:43:03 volumio sudo[2137]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:43:03 volumio sudo[2134]: pam_unix(sudo:session): session closed for user root Oct 22 14:43:03 volumio volumio[1979]: info: Shairport-Sync Started Oct 22 14:43:03 volumio volumio[1979]: Error adding Membership: Error: addMembership EINVAL Oct 22 14:43:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 22 14:43:03 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 22 14:43:03 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 22 14:43:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 22 14:43:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 22 14:43:03 volumio sudo[2137]: pam_unix(sudo:session): session closed for user root Oct 22 14:43:03 volumio volumio[1979]: info: Shairport-Sync Started Oct 22 14:43:03 volumio sudo[2139]: pam_unix(sudo:session): session closed for user root Oct 22 14:43:03 volumio volumio[1979]: info: Shairport-Sync Started Oct 22 14:43:03 volumio mpd[2095]: Oct 22 14:43 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 22 14:43:03 volumio systemd[1]: Started Music Player Daemon. Oct 22 14:43:03 volumio sudo[2066]: pam_unix(sudo:session): session closed for user root Oct 22 14:43:03 volumio sudo[2079]: pam_unix(sudo:session): session closed for user root Oct 22 14:43:03 volumio volumio[1979]: info: Completed starting Core Plugins Oct 22 14:43:03 volumio volumio[1979]: info: ------------------------------------------- Oct 22 14:43:03 volumio volumio[1979]: info: ----- MyVolumio plugins startup ---- Oct 22 14:43:03 volumio volumio[1979]: info: ------------------------------------------- Oct 22 14:43:03 volumio volumio[1979]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 22 14:43:03 volumio volumio[1979]: error: MPD error: The expression evaluated to a falsy value: Oct 22 14:43:03 volumio volumio[1979]: assert.ok(self.idling) Oct 22 14:43:03 volumio volumio[1979]: error: The expression evaluated to a falsy value: Oct 22 14:43:03 volumio volumio[1979]: assert.ok(self.idling) Oct 22 14:43:03 volumio volumio[1979]: info: MPD running with PID2095 Oct 22 14:43:03 volumio volumio[1979]: ,establishing connection Oct 22 14:43:03 volumio volumio[1979]: error: updateQueue error: null Oct 22 14:43:03 volumio volumio[1979]: error: updateQueue error: null Oct 22 14:43:04 volumio sudo[2012]: pam_unix(sudo:session): session closed for user root Oct 22 14:43:06 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:43:08 volumio volumio[1979]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 22 14:43:10 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 22 14:43:10 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:43:10 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 22 14:43:10 volumio sudo[2152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 22 14:43:10 volumio sudo[2152]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:43:10 volumio sudo[2152]: pam_unix(sudo:session): session closed for user root Oct 22 14:43:10 volumio sudo[2155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 22 14:43:10 volumio sudo[2155]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:43:11 volumio sudo[2155]: pam_unix(sudo:session): session closed for user root Oct 22 14:43:11 volumio sudo[2157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 22 14:43:11 volumio sudo[2157]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:43:11 volumio sudo[2157]: pam_unix(sudo:session): session closed for user root Oct 22 14:43:11 volumio volumio[1979]: info: Upmpdcli Daemon Started Oct 22 14:43:11 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 22 14:43:11 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 22 14:43:13 volumio volumio[1979]: info: Adding plugin bluetooth to MyMusic Plugins Oct 22 14:43:13 volumio volumio[1979]: info: Adding plugin multiroom to MyMusic Plugins Oct 22 14:43:13 volumio volumio[1979]: info: Adding plugin metavolumio to MyMusic Plugins Oct 22 14:43:13 volumio volumio[1979]: info: Adding plugin cd_controller to MyMusic Plugins Oct 22 14:43:13 volumio volumio[1979]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 22 14:43:13 volumio volumio[1979]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 22 14:43:13 volumio volumio[1979]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 22 14:43:14 volumio volumio[1979]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 22 14:43:14 volumio volumio[1979]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 22 14:43:14 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:14 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:14 volumio volumio[1979]: info: Starting MyVolumio Remote Streaming Endpoints Oct 22 14:43:14 volumio volumio[1979]: info: MyVolumio login type: Token Oct 22 14:43:14 volumio volumio[1979]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 22 14:43:14 volumio volumio[1979]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 22 14:43:14 volumio volumio[1979]: info: Starting Streaming Service Transparent Proxy Oct 22 14:43:14 volumio volumio[1979]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 22 14:43:14 volumio volumio[1979]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 22 14:43:14 volumio volumio[1979]: info: Streaming services startup Oct 22 14:43:14 volumio volumio[1979]: info: Starting Streaming Daemon Oct 22 14:43:15 volumio sudo[2167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 22 14:43:15 volumio sudo[2167]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:43:15 volumio sudo[2167]: pam_unix(sudo:session): session closed for user root Oct 22 14:43:15 volumio volumio[1979]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 22 14:43:15 volumio volumio[1979]: info: CoreCommandRouter::Close All Modals sent Oct 22 14:43:15 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Oct 22 14:43:15 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:43:15 volumio volumio[1979]: error: Cannot start Volumio Streaming Daemon Oct 22 14:43:15 volumio volumio[1979]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 22 14:43:15 volumio volumio[1979]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 22 14:43:15 volumio volumio[1979]: STREAMING PROXY: Starting server on port 3245 Oct 22 14:43:15 volumio volumio[1979]: Node JS runtime: 14 Oct 22 14:43:15 volumio volumio[1979]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 22 14:43:16 volumio volumio[1979]: info: MyVolumio token set successfully Oct 22 14:43:16 volumio volumio[1979]: info: MYVOLUMIO: Adding device Oct 22 14:43:16 volumio volumio[1979]: info: MYVOLUMIO: Evaluating Server Oct 22 14:43:16 volumio volumio[1979]: info: MyVolumio status changed Oct 22 14:43:16 volumio volumio[1979]: info: Streaming services startup Oct 22 14:43:16 volumio volumio[1979]: info: Starting Streaming Daemon Oct 22 14:43:16 volumio volumio[1979]: info: Removing browser output: myVolumio user plan is not superstar Oct 22 14:43:16 volumio volumio[1979]: info: Removing audio output: Oct 22 14:43:16 volumio volumio[1979]: info: Stoppping Tunnel 1 Oct 22 14:43:16 volumio sudo[2195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 22 14:43:16 volumio sudo[2195]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:43:16 volumio sudo[2198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 22 14:43:16 volumio sudo[2195]: pam_unix(sudo:session): session closed for user root Oct 22 14:43:16 volumio sudo[2198]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:43:16 volumio volumio[1979]: error: Cannot start Volumio Streaming Daemon Oct 22 14:43:16 volumio volumio[1979]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 22 14:43:16 volumio volumio[1979]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 22 14:43:17 volumio sudo[2198]: pam_unix(sudo:session): session closed for user root Oct 22 14:43:17 volumio volumio[1979]: info: Remote SSH Stopped Oct 22 14:43:17 volumio volumio[1979]: info: Setting Geolocation for MyVolumio to eu7 Oct 22 14:43:17 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:17 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:17 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:17 volumio volumio[1979]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 22 14:43:18 volumio volumio[1979]: info: Updating MyVolumio device info Oct 22 14:43:18 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:18 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:18 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:18 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:43:19 volumio volumio[1979]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 22 14:43:21 volumio volumio[1979]: info: MYVOLUMIO: Adding device Oct 22 14:43:21 volumio volumio[1979]: info: MYVOLUMIO: Evaluating Server Oct 22 14:43:22 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:43:22 volumio volumio[1979]: info: Setting Geolocation for MyVolumio to eu7 Oct 22 14:43:22 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:22 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:22 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:23 volumio volumio[1979]: info: Updating MyVolumio device info Oct 22 14:43:23 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:24 volumio volumio[1979]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 22 14:43:24 volumio volumio[1979]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 22 14:43:26 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:43:29 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 22 14:43:29 volumio volumio[1979]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 22 14:43:29 volumio volumio[1979]: info: CoreCommandRouter::volumioGetState Oct 22 14:43:29 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:30 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:43:33 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 22 14:43:33 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:33 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 22 14:43:33 volumio volumio-remote-updater[655]: No test mode Oct 22 14:43:33 volumio volumio-remote-updater[655]: No alpha test mode Oct 22 14:43:33 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 22 14:43:33 volumio volumio[1979]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 22 14:43:33 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 22 14:43:34 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:34 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:34 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 22 14:43:34 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 22 14:43:34 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 22 14:43:34 volumio volumio[1979]: info: CoreCommandRouter::volumioGetBrowseSources Oct 22 14:43:34 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 22 14:43:34 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:43:35 volumio volumio[1979]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 22 14:43:35 volumio volumio[1979]: info: Completed starting MyVolumio Plugin Oct 22 14:43:35 volumio volumio[1979]: info: BOOT COMPLETED Oct 22 14:43:35 volumio volumio[1979]: [Metrics] CommandRouter: 35s 306.05ms Oct 22 14:43:35 volumio volumio[1979]: info: CoreCommandRouter::volumiosetStartupVolume Oct 22 14:43:35 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:43:35 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:43:35 volumio volumio[1979]: info: CoreCommandRouter::Close All Modals sent Oct 22 14:43:35 volumio volumio[1979]: info: CoreCommandRouter::Close All Modals sent Oct 22 14:43:36 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 22 14:43:36 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 22 14:43:36 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 22 14:43:38 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:43:38 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Oct 22 14:43:38 volumio volumio[1979]: info: Adding a new share Oct 22 14:43:38 volumio volumio[1979]: info: No correspondence found in configuration for share Media on IP 192.168.178.79 Oct 22 14:43:38 volumio sudo[2265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.79/Media\\Musik /mnt/NAS/Media Oct 22 14:43:38 volumio sudo[2265]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:43:38 volumio kernel: Key type cifs.spnego registered Oct 22 14:43:38 volumio kernel: Key type cifs.idmap registered Oct 22 14:43:38 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Oct 22 14:43:38 volumio kernel: CIFS: Attempting to mount \\192.168.178.79\Media Oct 22 14:43:39 volumio sudo[2265]: pam_unix(sudo:session): session closed for user root Oct 22 14:43:39 volumio volumio[1979]: verbose: ControllerMpd::sendMpdCommand status Oct 22 14:43:39 volumio volumio[1979]: info: Oct 22 14:43:39 volumio volumio[1979]: ---------------------------- MPD announces state update: update Oct 22 14:43:39 volumio volumio[1979]: info: ControllerMpd::getState Oct 22 14:43:39 volumio volumio[1979]: verbose: ControllerMpd::sendMpdCommand status Oct 22 14:43:39 volumio volumio[1979]: verbose: ControllerMpd::sendMpdCommand status Oct 22 14:43:39 volumio volumio[1979]: info: Oct 22 14:43:39 volumio volumio[1979]: ---------------------------- MPD announces state update: update Oct 22 14:43:39 volumio volumio[1979]: info: ControllerMpd::getState Oct 22 14:43:39 volumio volumio[1979]: verbose: ControllerMpd::sendMpdCommand status Oct 22 14:43:39 volumio volumio[1979]: verbose: ControllerMpd::sendMpdCommand status Oct 22 14:43:39 volumio volumio[1979]: info: Oct 22 14:43:39 volumio volumio[1979]: ---------------------------- MPD announces state update: update Oct 22 14:43:39 volumio volumio[1979]: info: ControllerMpd::getState Oct 22 14:43:39 volumio volumio[1979]: verbose: ControllerMpd::sendMpdCommand status Oct 22 14:43:39 volumio volumio[1979]: info: sendMpdCommand status took 11 milliseconds Oct 22 14:43:39 volumio volumio[1979]: info: sendMpdCommand status took 8 milliseconds Oct 22 14:43:39 volumio volumio[1979]: info: sendMpdCommand status took 7 milliseconds Oct 22 14:43:39 volumio volumio[1979]: info: sendMpdCommand status took 6 milliseconds Oct 22 14:43:39 volumio volumio[1979]: info: sendMpdCommand status took 5 milliseconds Oct 22 14:43:39 volumio volumio[1979]: info: sendMpdCommand status took 4 milliseconds Oct 22 14:43:39 volumio volumio[1979]: verbose: ControllerMpd::parseState Oct 22 14:43:39 volumio volumio[1979]: info: Command Router : Notfying DB Updatetrue Oct 22 14:43:39 volumio volumio[1979]: verbose: ControllerMpd::parseState Oct 22 14:43:39 volumio volumio[1979]: verbose: ControllerMpd::parseState Oct 22 14:43:39 volumio volumio[1979]: info: Command Router : Notfying DB Updatetrue Oct 22 14:43:39 volumio volumio[1979]: info: CoreCommandRouter::Close All Modals sent Oct 22 14:43:39 volumio volumio[1979]: verbose: ControllerMpd::parseState Oct 22 14:43:39 volumio volumio[1979]: verbose: ControllerMpd::parseState Oct 22 14:43:39 volumio volumio[1979]: info: Command Router : Notfying DB Updatetrue Oct 22 14:43:39 volumio volumio[1979]: info: CoreCommandRouter::Close All Modals sent Oct 22 14:43:39 volumio volumio[1979]: verbose: ControllerMpd::parseState Oct 22 14:43:39 volumio volumio[1979]: info: ControllerMpd::pushState Oct 22 14:43:39 volumio volumio[1979]: info: CoreCommandRouter::servicePushState Oct 22 14:43:39 volumio volumio[1979]: info: CoreStateMachine::pushState Oct 22 14:43:39 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:39 volumio volumio[1979]: info: CoreCommandRouter::volumioPushState Oct 22 14:43:39 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:39 volumio volumio[1979]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 14:43:39 volumio volumio[1979]: verbose: CURRENT POSITION 0 Oct 22 14:43:39 volumio volumio[1979]: info: CoreStateMachine::syncState stateService stop Oct 22 14:43:39 volumio volumio[1979]: info: CoreStateMachine::syncState currentStatus stop Oct 22 14:43:39 volumio volumio[1979]: info: CoreStateMachine::pushState Oct 22 14:43:39 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:39 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 14:43:39 volumio volumio[1979]: info: CoreCommandRouter::volumioPushState Oct 22 14:43:39 volumio volumio[1979]: info: No code Oct 22 14:43:39 volumio volumio[1979]: info: CoreStateMachine::pushState Oct 22 14:43:39 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:39 volumio volumio[1979]: info: CoreCommandRouter::volumioPushState Oct 22 14:43:39 volumio volumio[1979]: info: ControllerMpd::pushState Oct 22 14:43:39 volumio volumio[1979]: info: CoreCommandRouter::servicePushState Oct 22 14:43:39 volumio volumio[1979]: info: CoreStateMachine::pushState Oct 22 14:43:39 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:39 volumio volumio[1979]: info: CoreCommandRouter::volumioPushState Oct 22 14:43:39 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:39 volumio volumio[1979]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 14:43:39 volumio volumio[1979]: verbose: CURRENT POSITION 0 Oct 22 14:43:39 volumio volumio[1979]: info: CoreStateMachine::syncState stateService stop Oct 22 14:43:39 volumio volumio[1979]: info: CoreStateMachine::syncState currentStatus stop Oct 22 14:43:39 volumio volumio[1979]: info: CoreStateMachine::pushState Oct 22 14:43:39 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:39 volumio volumio[1979]: info: CoreCommandRouter::volumioPushState Oct 22 14:43:39 volumio volumio[1979]: info: No code Oct 22 14:43:39 volumio volumio[1979]: info: CoreStateMachine::pushState Oct 22 14:43:39 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:39 volumio volumio[1979]: info: CoreCommandRouter::volumioPushState Oct 22 14:43:39 volumio volumio[1979]: info: ControllerMpd::pushState Oct 22 14:43:39 volumio volumio[1979]: info: CoreCommandRouter::servicePushState Oct 22 14:43:39 volumio volumio[1979]: info: CoreStateMachine::pushState Oct 22 14:43:39 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:39 volumio volumio[1979]: info: CoreCommandRouter::volumioPushState Oct 22 14:43:39 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:39 volumio volumio[1979]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 14:43:39 volumio volumio[1979]: verbose: CURRENT POSITION 0 Oct 22 14:43:39 volumio volumio[1979]: info: CoreStateMachine::syncState stateService stop Oct 22 14:43:39 volumio volumio[1979]: info: CoreStateMachine::syncState currentStatus stop Oct 22 14:43:39 volumio volumio[1979]: info: CoreStateMachine::pushState Oct 22 14:43:39 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:39 volumio volumio[1979]: info: CoreCommandRouter::volumioPushState Oct 22 14:43:39 volumio volumio[1979]: info: No code Oct 22 14:43:39 volumio volumio[1979]: info: CoreStateMachine::pushState Oct 22 14:43:39 volumio volumio[1979]: info: CorePlayQueue::getTrack 0 Oct 22 14:43:39 volumio volumio[1979]: info: CoreCommandRouter::volumioPushState Oct 22 14:43:39 volumio volumio[1979]: info: ------------------------------ 167ms Oct 22 14:43:39 volumio volumio[1979]: info: ------------------------------ 164ms Oct 22 14:43:39 volumio volumio[1979]: info: ------------------------------ 162ms Oct 22 14:43:39 volumio volumio[1979]: info: [1729601019215] Database update started Oct 22 14:43:40 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 22 14:43:42 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:43:46 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:43:50 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:43:54 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:43:58 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:44:02 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:44:06 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:44:08 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 22 14:44:08 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 22 14:44:10 volumio volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:44:13 volumio volumio[1979]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 14:44:13 volumio volumio[1979]: Error: dns service error: unknown Oct 22 14:44:13 volumio volumio[1979]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Oct 22 14:44:13 volumio volumio[1979]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Oct 22 14:44:13 volumio volumio[1979]: errorCode: -65537 Oct 22 14:44:13 volumio volumio[1979]: } Oct 22 14:44:13 volumio volumio[1979]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 14:44:13 volumio sudo[2327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-22 14:43 Oct 22 14:44:13 volumio sudo[2327]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:44:13 volumio sudo[2327]: pam_unix(sudo:session): session closed for user root Oct 22 14:44:13 volumio volumio-remote-updater[655]: [2024-10-22 12:44:13] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 22 14:44:13 volumio volumio-remote-updater[655]: [2024-10-22 12:44:13] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 22 14:44:13 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 22 14:44:13 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 22 14:44:13 volumio systemd[1]: Started dynamicswap service. Oct 22 14:44:13 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 22 14:44:13 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 22 14:44:13 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Oct 22 14:44:13 volumio systemd[1]: Stopped Volumio Backend Module. Oct 22 14:44:13 volumio systemd[1]: Started Volumio Backend Module. Oct 22 14:44:13 volumio systemd[1]: Started dynamicswap service. Oct 22 14:44:13 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 22 14:44:14 volumio volumio[2337]: info: ------------------------------------------- Oct 22 14:44:14 volumio volumio[2337]: info: ----- Volumio3 ---- Oct 22 14:44:14 volumio volumio[2337]: info: ------------------------------------------- Oct 22 14:44:14 volumio volumio[2337]: info: ----- System startup ---- Oct 22 14:44:14 volumio volumio[2337]: info: ------------------------------------------- Oct 22 14:44:15 volumio volumio[2337]: info: MYVOLUMIO Environment detected Oct 22 14:44:15 volumio volumio[2337]: info: Plugin folders cleanup Oct 22 14:44:15 volumio volumio[2337]: info: Scanning into folder /volumio/app/plugins/ Oct 22 14:44:15 volumio volumio[2337]: info: Scanning category audio_interface Oct 22 14:44:15 volumio volumio[2337]: info: Scanning category miscellanea Oct 22 14:44:15 volumio volumio[2337]: info: Scanning category music_service Oct 22 14:44:15 volumio volumio[2337]: info: Scanning category plugins.json Oct 22 14:44:15 volumio volumio[2337]: info: Scanning category system_controller Oct 22 14:44:15 volumio volumio[2337]: info: Scanning category user_interface Oct 22 14:44:15 volumio volumio[2337]: info: Scanning into folder /data/plugins/ Oct 22 14:44:15 volumio volumio[2337]: info: Plugin folders cleanup completed Oct 22 14:44:15 volumio volumio[2337]: info: ------------------------------------------- Oct 22 14:44:15 volumio volumio[2337]: info: ----- Core plugins startup ---- Oct 22 14:44:15 volumio volumio[2337]: info: ------------------------------------------- Oct 22 14:44:15 volumio volumio[2337]: info: Loading plugins from folder /volumio/app/plugins/ Oct 22 14:44:15 volumio volumio[2337]: info: Adding plugin upnp to MyMusic Plugins Oct 22 14:44:15 volumio volumio[2337]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 22 14:44:15 volumio volumio[2337]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 22 14:44:15 volumio volumio[2337]: info: Loading plugins from folder /data/plugins/ Oct 22 14:44:15 volumio volumio[2337]: info: Loading plugin "system"... Oct 22 14:44:15 volumio volumio[2337]: info: Loading plugin "appearance"... Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "network"... Oct 22 14:44:16 volumio volumio[2337]: info: Refreshing Cached IP Addresses Oct 22 14:44:16 volumio sudo[2364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 22 14:44:16 volumio sudo[2364]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:44:16 volumio sudo[2364]: pam_unix(sudo:session): session closed for user root Oct 22 14:44:16 volumio sudo[2366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "services"... Oct 22 14:44:16 volumio sudo[2366]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "alsa_controller"... Oct 22 14:44:16 volumio sudo[2366]: pam_unix(sudo:session): session closed for user root Oct 22 14:44:16 volumio sudo[2369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 22 14:44:16 volumio sudo[2369]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:44:16 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "wizard"... Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "networkfs"... Oct 22 14:44:16 volumio volumio[2337]: info: Cannot mount NAS Media at system boot, trial number 1 ,retrying in 5 seconds Oct 22 14:44:16 volumio volumio[2337]: info: Starting Udev Watcher for removable devices Oct 22 14:44:16 volumio volumio[2337]: info: Ignoring mount for partition: boot Oct 22 14:44:16 volumio volumio[2337]: info: Ignoring mount for partition: volumio Oct 22 14:44:16 volumio volumio[2337]: info: Ignoring mount for partition: volumio_data Oct 22 14:44:16 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "volumio_command_line_client"... Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "upnp"... Oct 22 14:44:16 volumio volumio[2337]: info: [1729601056175] Starting Upmpd Daemon Oct 22 14:44:16 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "my_music"... Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "mpd"... Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "upnp_browser"... Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "alarm-clock"... Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "airplay_emulation"... Oct 22 14:44:16 volumio volumio[2337]: info: Starting Shairport Sync Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "last_100"... Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "webradio"... Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "i2s_dacs"... Oct 22 14:44:16 volumio volumio[2337]: info: I2S DAC not set, start Auto-detection Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "volumiodiscovery"... Oct 22 14:44:16 volumio volumio[2337]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 22 14:44:16 volumio node[2337]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 22 14:44:16 volumio node[2337]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 22 14:44:16 volumio volumio[2337]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 22 14:44:16 volumio volumio[2337]: *** WARNING *** For more information see Oct 22 14:44:16 volumio volumio[2337]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 22 14:44:16 volumio volumio[2337]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 22 14:44:16 volumio volumio[2337]: *** WARNING *** For more information see Oct 22 14:44:16 volumio node[2337]: *** WARNING *** For more information see Oct 22 14:44:16 volumio node[2337]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 22 14:44:16 volumio node[2337]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 22 14:44:16 volumio node[2337]: *** WARNING *** For more information see Oct 22 14:44:16 volumio volumio[2337]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 22 14:44:16 volumio volumio[2337]: info: Discovery: Started advertising with name: Volumio Oct 22 14:44:16 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "outputs"... Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "albumart"... Oct 22 14:44:16 volumio volumio[2337]: info: Plugin example_plugin is not enabled Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "inputs"... Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "updater_comm"... Oct 22 14:44:16 volumio volumio[2337]: info: Plugin mpdemulation is not enabled Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "rest_api"... Oct 22 14:44:16 volumio volumio[2337]: info: Loading plugin "websocket"... Oct 22 14:44:16 volumio volumio[2337]: info: Starting Socket.io Server version 2.3.0 Oct 22 14:44:16 volumio volumio[2337]: info: Loading i18n strings for locale de Oct 22 14:44:16 volumio volumio[2337]: Updating browse sources language Oct 22 14:44:16 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 22 14:44:17 volumio volumio[2337]: Forking 3 albumart workers Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::initPlayerControls Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 14:44:17 volumio volumio[2337]: Express server listening on port 3000 Oct 22 14:44:17 volumio volumio[2337]: [Metrics] WebUI: 2s 451.62ms Oct 22 14:44:17 volumio volumio[2337]: info: CoreStateMachine::resetVolumioState Oct 22 14:44:17 volumio volumio[2337]: info: CoreStateMachine::getcurrentVolume Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::volumioRetrievevolume Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:44:17 volumio volumio[2337]: info: Cannot read play queue from file Oct 22 14:44:17 volumio volumio[2337]: verbose: New Socket.io Connection to 192.168.178.84 from 192.168.178.17 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 22 14:44:17 volumio volumio[2337]: verbose: New Socket.io Connection to 192.168.178.84 from 192.168.178.17 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 22 14:44:17 volumio volumio[2337]: verbose: New Socket.io Connection to 192.168.178.84 from 192.168.178.17 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 22 14:44:17 volumio volumio[2337]: info: CoreStateMachine::setRepeat null single undefined Oct 22 14:44:17 volumio volumio[2337]: info: CoreStateMachine::pushState Oct 22 14:44:17 volumio volumio[2337]: info: CorePlayQueue::getTrack 0 Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::volumioPushState Oct 22 14:44:17 volumio volumio[2337]: info: CoreStateMachine::setRandom null Oct 22 14:44:17 volumio volumio[2337]: info: CoreStateMachine::pushState Oct 22 14:44:17 volumio volumio[2337]: info: CorePlayQueue::getTrack 0 Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::volumioPushState Oct 22 14:44:17 volumio volumio[2337]: info: Setting Device type: Raspberry PI Oct 22 14:44:17 volumio volumio[2337]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 22 14:44:17 volumio volumio[2337]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Oct 22 14:44:17 volumio volumio[2337]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 22 14:44:17 volumio volumio[2337]: info: VolumeController:: Volume=0 Mute =false Oct 22 14:44:17 volumio volumio[2337]: info: CoreStateMachine::pushState Oct 22 14:44:17 volumio volumio[2337]: info: CorePlayQueue::getTrack 0 Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::volumioPushState Oct 22 14:44:17 volumio volumio[2337]: info: CoreStateMachine::updateTrackBlock Oct 22 14:44:17 volumio volumio[2337]: info: CorePlayQueue::getTrackBlock Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::volumioRetrievevolume Oct 22 14:44:17 volumio volumio[2337]: info: Completed loading Core Plugins Oct 22 14:44:17 volumio volumio[2337]: info: Preparing to generate the ALSA configuration file Oct 22 14:44:17 volumio volumio[2337]: info: Asound.conf file unchanged, so no further update is needed Oct 22 14:44:17 volumio volumio[2337]: info: Output device has changed, restarting MPD Oct 22 14:44:17 volumio volumio[2337]: info: Output device has changed, restarting Shairport Sync Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:44:17 volumio sudo[2419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 22 14:44:17 volumio sudo[2419]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:44:17 volumio sudo[2420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 22 14:44:17 volumio sudo[2420]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:44:17 volumio sudo[2419]: pam_unix(sudo:session): session closed for user root Oct 22 14:44:17 volumio volumio[2337]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 22 14:44:17 volumio systemd[1]: Stopping Music Player Daemon... Oct 22 14:44:17 volumio volumio[2337]: info: ___________ START PLUGINS ___________ Oct 22 14:44:17 volumio volumio[2337]: Starting albumart workers Oct 22 14:44:17 volumio volumio[2337]: info: ControllerMpd::onStart: Initializing MPD Oct 22 14:44:17 volumio volumio[2337]: info: Creating MPD Configuration file Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 22 14:44:17 volumio volumio[2337]: info: [1729601057664] CoreMusicLibrary::Adding element Medienserver Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 22 14:44:17 volumio volumio[2337]: Starting albumart workers Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:44:17 volumio sudo[2428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 22 14:44:17 volumio sudo[2428]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:44:17 volumio sudo[2428]: pam_unix(sudo:session): session closed for user root Oct 22 14:44:17 volumio volumio[2337]: Starting albumart workers Oct 22 14:44:17 volumio sudo[2429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 22 14:44:17 volumio sudo[2429]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:44:17 volumio volumio[2337]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:44:17 volumio volumio[2337]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 22 14:44:17 volumio volumio[2337]: info: [1729601057814] CoreMusicLibrary::Adding element Last_100 Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 22 14:44:17 volumio volumio[2337]: info: [1729601057817] CoreMusicLibrary::Adding element Webradio Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 22 14:44:17 volumio volumio[2337]: info: Initializing BBC Radios Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 22 14:44:17 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:44:17 volumio volumio[2337]: info: Volumio Calling Home Oct 22 14:44:18 volumio volumio[2337]: verbose: New Socket.io Connection to 192.168.178.84 from 192.168.178.17 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 22 14:44:18 volumio volumio[2337]: info: MPD Permissions set Oct 22 14:44:18 volumio volumio[2337]: info: MPD Permissions set Oct 22 14:44:18 volumio volumio[2337]: info: VolumeController:: Volume=0 Mute =false Oct 22 14:44:18 volumio volumio[2337]: info: CoreStateMachine::pushState Oct 22 14:44:18 volumio volumio[2337]: info: CorePlayQueue::getTrack 0 Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::volumioPushState Oct 22 14:44:18 volumio volumio[2337]: info: Volumio called home Oct 22 14:44:18 volumio volumio[2337]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 14:44:18 volumio volumio[2337]: info: Discovery: adding e5929a6e-ab4c-41a2-ac00-e21aec15ecb6 Oct 22 14:44:18 volumio volumio[2337]: info: Discovery: Found device Volumio Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::volumioGetState Oct 22 14:44:18 volumio volumio[2337]: info: CorePlayQueue::getTrack 0 Oct 22 14:44:18 volumio volumio[2337]: info: Starting Shairport Sync Oct 22 14:44:18 volumio volumio[2337]: info: Starting Shairport Sync Oct 22 14:44:18 volumio sudo[2486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 22 14:44:18 volumio sudo[2486]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:44:18 volumio volumio[2337]: info: Starting Shairport Sync Oct 22 14:44:18 volumio sudo[2488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 22 14:44:18 volumio sudo[2488]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:44:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 22 14:44:18 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 22 14:44:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 22 14:44:18 volumio volumio[2337]: info: Discovery: this is already registered, e5929a6e-ab4c-41a2-ac00-e21aec15ecb6 Oct 22 14:44:18 volumio volumio[2337]: info: Discovery: Found device Volumio Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::volumioGetState Oct 22 14:44:18 volumio volumio[2337]: info: CorePlayQueue::getTrack 0 Oct 22 14:44:18 volumio sudo[2492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 22 14:44:18 volumio sudo[2492]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::volumioGetState Oct 22 14:44:18 volumio volumio[2337]: info: CorePlayQueue::getTrack 0 Oct 22 14:44:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 22 14:44:18 volumio volumio[2337]: verbose: New Socket.io Connection to 192.168.178.84 from 192.168.178.17 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 22 14:44:18 volumio sudo[2486]: pam_unix(sudo:session): session closed for user root Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::volumioGetState Oct 22 14:44:18 volumio volumio[2337]: info: CorePlayQueue::getTrack 0 Oct 22 14:44:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 22 14:44:18 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 22 14:44:18 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 22 14:44:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 22 14:44:18 volumio volumio[2337]: info: Shairport-Sync Started Oct 22 14:44:18 volumio volumio[2337]: Error adding Membership: Error: addMembership EINVAL Oct 22 14:44:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 22 14:44:18 volumio volumio[2337]: info: Received Get System Info Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 22 14:44:18 volumio volumio[2337]: info: Discovery: Getting this device information Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::volumioGetState Oct 22 14:44:18 volumio volumio[2337]: info: CorePlayQueue::getTrack 0 Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 22 14:44:18 volumio sudo[2492]: pam_unix(sudo:session): session closed for user root Oct 22 14:44:18 volumio sudo[2488]: pam_unix(sudo:session): session closed for user root Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::volumioGetState Oct 22 14:44:18 volumio volumio[2337]: info: CorePlayQueue::getTrack 0 Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::volumioGetVisibleSources Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::volumioGetState Oct 22 14:44:18 volumio volumio[2337]: info: CorePlayQueue::getTrack 0 Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::volumioGetState Oct 22 14:44:18 volumio volumio[2337]: info: CorePlayQueue::getTrack 0 Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 22 14:44:18 volumio volumio[2337]: info: Received Get System Info Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 22 14:44:18 volumio volumio[2337]: info: Discovery: Getting this device information Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::volumioGetState Oct 22 14:44:18 volumio volumio[2337]: info: CorePlayQueue::getTrack 0 Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::volumioGetState Oct 22 14:44:18 volumio volumio[2337]: info: CorePlayQueue::getTrack 0 Oct 22 14:44:18 volumio volumio[2337]: info: Listing playlists Oct 22 14:44:18 volumio volumio[2337]: info: Shairport-Sync Started Oct 22 14:44:18 volumio volumio[2337]: info: Shairport-Sync Started Oct 22 14:44:18 volumio volumio[2337]: info: VolumeController::SetAlsaVolume0 Oct 22 14:44:18 volumio volumio[2337]: info: CoreStateMachine::pushState Oct 22 14:44:18 volumio volumio[2337]: info: CorePlayQueue::getTrack 0 Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 14:44:18 volumio volumio[2337]: info: CoreCommandRouter::volumioPushState Oct 22 14:44:18 volumio volumio-remote-updater[655]: [2024-10-22 12:44:18] [connect] Successful connection Oct 22 14:44:18 volumio volumio-remote-updater[655]: [2024-10-22 12:44:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729601058 101 Oct 22 14:44:18 volumio volumio[2337]: 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: 7 Oct 22 14:44:19 volumio sudo[2369]: pam_unix(sudo:session): session closed for user root Oct 22 14:44:21 volumio volumio[2337]: info: Cannot mount NAS Media at system boot, trial number 2 ,retrying in 5 seconds Oct 22 14:44:22 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 14:44:23 volumio volumio[2337]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 22 14:44:23 volumio volumio[2337]: verbose: ControllerMpd::sendMpdCommand rescan Oct 22 14:44:23 volumio volumio[2337]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 14:44:23 volumio volumio[2337]: TypeError: Cannot read property 'then' of undefined Oct 22 14:44:23 volumio volumio[2337]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6) Oct 22 14:44:23 volumio volumio[2337]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1885:15) Oct 22 14:44:23 volumio volumio[2337]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32) Oct 22 14:44:23 volumio volumio[2337]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1073:26) Oct 22 14:44:23 volumio volumio[2337]: at Socket.emit (events.js:315:20) Oct 22 14:44:23 volumio volumio[2337]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 22 14:44:23 volumio volumio[2337]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 22 14:44:23 volumio volumio[2337]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 14:44:23 volumio sudo[2510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-22 14:43 Oct 22 14:44:23 volumio sudo[2510]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"