-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2024-04-08 04:02:14 CEST. -- Apr 08 04:01:01 volumio volumio[26276]: info: ------------------------------------------- Apr 08 04:01:01 volumio volumio[26276]: info: ----- Volumio3 ---- Apr 08 04:01:01 volumio volumio[26276]: info: ------------------------------------------- Apr 08 04:01:01 volumio volumio[26276]: info: ----- System startup ---- Apr 08 04:01:01 volumio volumio[26276]: info: ------------------------------------------- Apr 08 04:01:02 volumio volumio[26276]: info: MYVOLUMIO Environment detected Apr 08 04:01:02 volumio volumio[26276]: info: Plugin folders cleanup Apr 08 04:01:02 volumio volumio[26276]: info: Scanning into folder /volumio/app/plugins/ Apr 08 04:01:02 volumio volumio[26276]: info: Scanning category audio_interface Apr 08 04:01:02 volumio volumio[26276]: info: Scanning category miscellanea Apr 08 04:01:02 volumio volumio[26276]: info: Scanning category music_service Apr 08 04:01:02 volumio volumio[26276]: info: Scanning category plugins.json Apr 08 04:01:02 volumio volumio[26276]: info: Scanning category system_controller Apr 08 04:01:02 volumio volumio[26276]: info: Scanning category user_interface Apr 08 04:01:02 volumio volumio[26276]: info: Scanning into folder /data/plugins/ Apr 08 04:01:02 volumio volumio[26276]: info: Plugin folders cleanup completed Apr 08 04:01:02 volumio volumio[26276]: info: ------------------------------------------- Apr 08 04:01:02 volumio volumio[26276]: info: ----- Core plugins startup ---- Apr 08 04:01:02 volumio volumio[26276]: info: ------------------------------------------- Apr 08 04:01:02 volumio volumio[26276]: info: Loading plugins from folder /volumio/app/plugins/ Apr 08 04:01:02 volumio volumio[26276]: info: Adding plugin upnp to MyMusic Plugins Apr 08 04:01:02 volumio volumio[26276]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 08 04:01:02 volumio volumio[26276]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 08 04:01:02 volumio volumio[26276]: info: Loading plugins from folder /data/plugins/ Apr 08 04:01:02 volumio volumio[26276]: info: Loading plugin "system"... Apr 08 04:01:02 volumio volumio[26276]: info: Loading plugin "appearance"... Apr 08 04:01:03 volumio volumio[26276]: info: Loading plugin "network"... Apr 08 04:01:03 volumio volumio[26276]: info: Refreshing Cached IP Addresses Apr 08 04:01:03 volumio sudo[26299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 08 04:01:03 volumio sudo[26299]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:03 volumio sudo[26299]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:03 volumio sudo[26301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 08 04:01:03 volumio sudo[26301]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:03 volumio volumio[26276]: info: Loading plugin "services"... Apr 08 04:01:03 volumio volumio[26276]: info: Loading plugin "alsa_controller"... Apr 08 04:01:03 volumio sudo[26301]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:03 volumio sudo[26304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 08 04:01:03 volumio sudo[26304]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:03 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 08 04:01:03 volumio volumio[26276]: info: Loading plugin "wizard"... Apr 08 04:01:03 volumio volumio[26276]: info: Loading plugin "volumio_command_line_client"... Apr 08 04:01:03 volumio volumio[26276]: info: Loading plugin "upnp"... Apr 08 04:01:03 volumio volumio[26276]: info: [1712541663850] Starting Upmpd Daemon Apr 08 04:01:03 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 04:01:03 volumio volumio[26276]: info: Loading plugin "my_music"... Apr 08 04:01:03 volumio volumio[26276]: info: Loading plugin "mpd"... Apr 08 04:01:04 volumio volumio[26276]: info: Creating MPD Configuration file Apr 08 04:01:04 volumio volumio[26276]: info: Loading plugin "upnp_browser"... Apr 08 04:01:04 volumio sudo[26312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 08 04:01:04 volumio sudo[26312]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:04 volumio sudo[26312]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:04 volumio sudo[26314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 08 04:01:04 volumio sudo[26314]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:04 volumio systemd[1]: Stopping Music Player Daemon... Apr 08 04:01:04 volumio systemd[1]: mpd.service: Succeeded. Apr 08 04:01:04 volumio systemd[1]: Stopped Music Player Daemon. Apr 08 04:01:04 volumio systemd[1]: Starting Music Player Daemon... Apr 08 04:01:04 volumio volumio[26276]: info: Loading plugin "networkfs"... Apr 08 04:01:04 volumio volumio-remote-updater[535]: [2024-04-08 04:01:04] [connect] Successful connection Apr 08 04:01:04 volumio volumio[26276]: info: Starting Udev Watcher for removable devices Apr 08 04:01:05 volumio volumio[26276]: info: Ignoring mount for partition: boot Apr 08 04:01:05 volumio volumio[26276]: info: Ignoring mount for partition: volumio Apr 08 04:01:05 volumio volumio[26276]: info: Ignoring mount for partition: volumio_data Apr 08 04:01:05 volumio volumio[26276]: info: Mounting Device INTENSO Apr 08 04:01:05 volumio sudo[26325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Apr 08 04:01:05 volumio sudo[26325]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:05 volumio sudo[26325]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:05 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 04:01:05 volumio volumio[26276]: info: Loading plugin "alarm-clock"... Apr 08 04:01:05 volumio volumio[26276]: info: Loading plugin "airplay_emulation"... Apr 08 04:01:05 volumio volumio[26276]: info: Starting Shairport Sync Apr 08 04:01:05 volumio volumio[26276]: info: Loading plugin "last_100"... Apr 08 04:01:05 volumio volumio[26276]: info: Loading plugin "webradio"... Apr 08 04:01:05 volumio volumio[26276]: info: Loading plugin "i2s_dacs"... Apr 08 04:01:05 volumio volumio[26276]: info: Loading plugin "volumiodiscovery"... Apr 08 04:01:05 volumio volumio[26276]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 08 04:01:05 volumio node[26276]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 08 04:01:05 volumio volumio[26276]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:01:05 volumio node[26276]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:01:05 volumio volumio[26276]: *** WARNING *** For more information see Apr 08 04:01:05 volumio node[26276]: *** WARNING *** For more information see Apr 08 04:01:05 volumio volumio[26276]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 08 04:01:05 volumio node[26276]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 08 04:01:05 volumio volumio[26276]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:01:05 volumio node[26276]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:01:05 volumio volumio[26276]: *** WARNING *** For more information see Apr 08 04:01:05 volumio node[26276]: *** WARNING *** For more information see Apr 08 04:01:05 volumio volumio[26276]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 08 04:01:05 volumio volumio[26276]: info: Discovery: Started advertising with name: undefined Apr 08 04:01:05 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 04:01:05 volumio volumio[26276]: info: Loading plugin "outputs"... Apr 08 04:01:05 volumio volumio[26276]: info: Loading plugin "albumart"... Apr 08 04:01:05 volumio volumio[26276]: info: Plugin example_plugin is not enabled Apr 08 04:01:05 volumio volumio[26276]: info: Loading plugin "inputs"... Apr 08 04:01:05 volumio volumio[26276]: info: Loading plugin "updater_comm"... Apr 08 04:01:05 volumio mpd[26319]: Apr 08 04:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 08 04:01:05 volumio mpd[26319]: Apr 08 04:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 08 04:01:05 volumio mpd[26319]: Apr 08 04:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 08 04:01:05 volumio volumio[26276]: info: Plugin mpdemulation is not enabled Apr 08 04:01:05 volumio volumio[26276]: info: Loading plugin "rest_api"... Apr 08 04:01:05 volumio systemd[1]: Started Music Player Daemon. Apr 08 04:01:06 volumio sudo[26314]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:06 volumio volumio[26276]: info: Loading plugin "websocket"... Apr 08 04:01:06 volumio volumio[26276]: info: Loading i18n strings for locale de Apr 08 04:01:06 volumio volumio[26276]: Updating browse sources language Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:01:06 volumio volumio[26276]: Forking 3 albumart workers Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::initPlayerControls Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: Express server listening on port 3000 Apr 08 04:01:06 volumio volumio[26276]: [Metrics] WebUI: 5s 28.57ms Apr 08 04:01:06 volumio volumio[26276]: info: CoreStateMachine::resetVolumioState Apr 08 04:01:06 volumio volumio[26276]: info: CoreStateMachine::getcurrentVolume Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::volumioRetrievevolume Apr 08 04:01:06 volumio volumio[26276]: info: MPD Permissions set Apr 08 04:01:06 volumio volumio-remote-updater[535]: [2024-04-08 04:01:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712541664 101 Apr 08 04:01:06 volumio volumio[26276]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 08 04:01:06 volumio volumio[26276]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 08 04:01:06 volumio volumio[26276]: info: MPD running with PID26319 Apr 08 04:01:06 volumio volumio[26276]: ,establishing connection Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: Reloading queue from file Apr 08 04:01:06 volumio volumio[26276]: error: updateQueue error: null Apr 08 04:01:06 volumio volumio[26276]: info: Setting Device type: Raspberry PI Apr 08 04:01:06 volumio volumio[26276]: info: CoreStateMachine::setRepeat false single undefined Apr 08 04:01:06 volumio volumio[26276]: info: CoreStateMachine::pushState Apr 08 04:01:06 volumio volumio[26276]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::volumioPushState Apr 08 04:01:06 volumio volumio[26276]: info: CoreStateMachine::setRandom null Apr 08 04:01:06 volumio volumio[26276]: info: CoreStateMachine::pushState Apr 08 04:01:06 volumio volumio[26276]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::volumioPushState Apr 08 04:01:06 volumio volumio[26276]: info: Completed loading Core Plugins Apr 08 04:01:06 volumio volumio[26276]: info: Preparing to generate the ALSA configuration file Apr 08 04:01:06 volumio volumio[26276]: info: VolumeController:: Volume=undefined Mute =false Apr 08 04:01:06 volumio volumio[26276]: info: CoreStateMachine::pushState Apr 08 04:01:06 volumio volumio[26276]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::volumioPushState Apr 08 04:01:06 volumio volumio[26276]: info: CoreStateMachine::updateTrackBlock Apr 08 04:01:06 volumio volumio[26276]: info: CorePlayQueue::getTrackBlock Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::volumioRetrievevolume Apr 08 04:01:06 volumio volumio[26276]: error: updateQueue error: null Apr 08 04:01:06 volumio volumio[26276]: info: Asound.conf file unchanged, so no further update is needed Apr 08 04:01:06 volumio volumio[26276]: info: Output device has changed, restarting MPD Apr 08 04:01:06 volumio volumio[26276]: info: Output device has changed, restarting Shairport Sync Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:06 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:06 volumio sudo[26378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 08 04:01:06 volumio sudo[26378]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:07 volumio sudo[26381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 08 04:01:07 volumio sudo[26381]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:07 volumio sudo[26378]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:07 volumio sudo[26304]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:07 volumio systemd[1]: Stopping Music Player Daemon... Apr 08 04:01:07 volumio volumio[26276]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 08 04:01:07 volumio systemd[1]: mpd.service: Succeeded. Apr 08 04:01:07 volumio volumio[26276]: info: ___________ START PLUGINS ___________ Apr 08 04:01:07 volumio systemd[1]: Stopped Music Player Daemon. Apr 08 04:01:07 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 04:01:07 volumio volumio[26276]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 08 04:01:07 volumio volumio[26276]: info: [1712541667121] CoreMusicLibrary::Adding element Media Servers Apr 08 04:01:07 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:01:07 volumio systemd[1]: Starting Music Player Daemon... Apr 08 04:01:07 volumio volumio[26276]: Starting albumart workers Apr 08 04:01:07 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:07 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:07 volumio volumio[26276]: Starting albumart workers Apr 08 04:01:07 volumio volumio[26276]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 08 04:01:07 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:07 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:07 volumio volumio[26276]: Starting albumart workers Apr 08 04:01:07 volumio volumio[26276]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 08 04:01:07 volumio volumio[26276]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 08 04:01:07 volumio volumio[26276]: info: [1712541667461] CoreMusicLibrary::Adding element Last_100 Apr 08 04:01:07 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:01:07 volumio volumio[26276]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 08 04:01:07 volumio volumio[26276]: info: [1712541667467] CoreMusicLibrary::Adding element Webradio Apr 08 04:01:07 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:01:07 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 08 04:01:07 volumio volumio[26276]: info: Volumio Calling Home Apr 08 04:01:07 volumio volumio[26276]: info: Completed starting Core Plugins Apr 08 04:01:07 volumio volumio[26276]: info: ------------------------------------------- Apr 08 04:01:07 volumio volumio[26276]: info: ----- MyVolumio plugins startup ---- Apr 08 04:01:07 volumio volumio[26276]: info: ------------------------------------------- Apr 08 04:01:07 volumio volumio[26276]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 08 04:01:07 volumio volumio[26276]: info: MPD Permissions set Apr 08 04:01:07 volumio volumio[26276]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 08 04:01:08 volumio volumio[26276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 08 04:01:08 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:08 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:08 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:08 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:08 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:08 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:08 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:08 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:08 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:08 volumio volumio[26276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:08 volumio volumio[26276]: info: VolumeController:: Volume=undefined Mute =false Apr 08 04:01:08 volumio volumio[26276]: info: CoreStateMachine::pushState Apr 08 04:01:08 volumio volumio[26276]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:08 volumio volumio[26276]: info: CoreCommandRouter::volumioPushState Apr 08 04:01:08 volumio volumio[26276]: info: Starting Shairport Sync Apr 08 04:01:08 volumio volumio[26276]: info: Starting Shairport Sync Apr 08 04:01:08 volumio volumio[26276]: info: Starting Shairport Sync Apr 08 04:01:08 volumio sudo[26423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 08 04:01:08 volumio sudo[26421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 08 04:01:08 volumio sudo[26423]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:08 volumio sudo[26421]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:08 volumio sudo[26431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 08 04:01:08 volumio sudo[26431]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 08 04:01:08 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 08 04:01:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 08 04:01:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 08 04:01:08 volumio sudo[26423]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:08 volumio sudo[26421]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 08 04:01:08 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 08 04:01:08 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 08 04:01:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 08 04:01:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 08 04:01:08 volumio volumio[26276]: info: CoreCommandRouter::volumioGetState Apr 08 04:01:08 volumio volumio[26276]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:08 volumio volumio[26276]: info: Shairport-Sync Started Apr 08 04:01:08 volumio volumio[26276]: Error adding Membership: Error: addMembership EINVAL Apr 08 04:01:08 volumio sudo[26431]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:08 volumio volumio[26276]: info: Shairport-Sync Started Apr 08 04:01:08 volumio volumio[26276]: info: Shairport-Sync Started Apr 08 04:01:09 volumio mpd[26395]: Apr 08 04:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 08 04:01:09 volumio mpd[26395]: Apr 08 04:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 08 04:01:09 volumio mpd[26395]: Apr 08 04:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 08 04:01:09 volumio systemd[1]: Started Music Player Daemon. Apr 08 04:01:09 volumio sudo[26381]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:09 volumio volumio[26276]: error: updateQueue error: null Apr 08 04:01:10 volumio volumio[26276]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Apr 08 04:01:10 volumio volumio[26276]: info: Discovery: Started advertising with name: undefined Apr 08 04:01:13 volumio volumio[26276]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 04:01:13 volumio volumio[26276]: TypeError: Cannot read property 'charAt' of undefined Apr 08 04:01:13 volumio volumio[26276]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 08 04:01:13 volumio volumio[26276]: at listOnTimeout (internal/timers.js:554:17) Apr 08 04:01:13 volumio volumio[26276]: at processTimers (internal/timers.js:497:7) Apr 08 04:01:13 volumio volumio[26276]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 04:01:14 volumio sudo[26452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-08 04:00 Apr 08 04:01:14 volumio sudo[26452]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:14 volumio sudo[26452]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:14 volumio volumio-remote-updater[535]: [2024-04-08 04:01:14] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 08 04:01:14 volumio volumio-remote-updater[535]: [2024-04-08 04:01:14] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 08 04:01:14 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 08 04:01:14 volumio systemd[1]: media-INTENSO.mount: Succeeded. Apr 08 04:01:14 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 08 04:01:14 volumio systemd[1]: Started dynamicswap service. Apr 08 04:01:14 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 08 04:01:14 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 08 04:01:14 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 94. Apr 08 04:01:14 volumio systemd[1]: Stopped Volumio Backend Module. Apr 08 04:01:14 volumio systemd[1]: Started Volumio Backend Module. Apr 08 04:01:14 volumio systemd[1]: Started dynamicswap service. Apr 08 04:01:15 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 08 04:01:16 volumio volumio[26466]: info: ------------------------------------------- Apr 08 04:01:16 volumio volumio[26466]: info: ----- Volumio3 ---- Apr 08 04:01:16 volumio volumio[26466]: info: ------------------------------------------- Apr 08 04:01:16 volumio volumio[26466]: info: ----- System startup ---- Apr 08 04:01:16 volumio volumio[26466]: info: ------------------------------------------- Apr 08 04:01:17 volumio volumio[26466]: info: MYVOLUMIO Environment detected Apr 08 04:01:17 volumio volumio[26466]: info: Plugin folders cleanup Apr 08 04:01:17 volumio volumio[26466]: info: Scanning into folder /volumio/app/plugins/ Apr 08 04:01:17 volumio volumio[26466]: info: Scanning category audio_interface Apr 08 04:01:17 volumio volumio[26466]: info: Scanning category miscellanea Apr 08 04:01:17 volumio volumio[26466]: info: Scanning category music_service Apr 08 04:01:17 volumio volumio[26466]: info: Scanning category plugins.json Apr 08 04:01:17 volumio volumio[26466]: info: Scanning category system_controller Apr 08 04:01:17 volumio volumio[26466]: info: Scanning category user_interface Apr 08 04:01:17 volumio volumio[26466]: info: Scanning into folder /data/plugins/ Apr 08 04:01:17 volumio volumio[26466]: info: Plugin folders cleanup completed Apr 08 04:01:17 volumio volumio[26466]: info: ------------------------------------------- Apr 08 04:01:17 volumio volumio[26466]: info: ----- Core plugins startup ---- Apr 08 04:01:17 volumio volumio[26466]: info: ------------------------------------------- Apr 08 04:01:17 volumio volumio[26466]: info: Loading plugins from folder /volumio/app/plugins/ Apr 08 04:01:17 volumio volumio[26466]: info: Adding plugin upnp to MyMusic Plugins Apr 08 04:01:17 volumio volumio[26466]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 08 04:01:17 volumio volumio[26466]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 08 04:01:17 volumio volumio[26466]: info: Loading plugins from folder /data/plugins/ Apr 08 04:01:17 volumio volumio[26466]: info: Loading plugin "system"... Apr 08 04:01:17 volumio volumio[26466]: info: Loading plugin "appearance"... Apr 08 04:01:18 volumio volumio[26466]: info: Loading plugin "network"... Apr 08 04:01:18 volumio volumio[26466]: info: Refreshing Cached IP Addresses Apr 08 04:01:18 volumio sudo[26490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 08 04:01:18 volumio sudo[26490]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:18 volumio sudo[26490]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:18 volumio sudo[26492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 08 04:01:18 volumio volumio[26466]: info: Loading plugin "services"... Apr 08 04:01:18 volumio sudo[26492]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:18 volumio volumio[26466]: info: Loading plugin "alsa_controller"... Apr 08 04:01:18 volumio sudo[26492]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:18 volumio sudo[26495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 08 04:01:18 volumio sudo[26495]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:18 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 08 04:01:18 volumio volumio[26466]: info: Loading plugin "wizard"... Apr 08 04:01:18 volumio volumio[26466]: info: Loading plugin "volumio_command_line_client"... Apr 08 04:01:18 volumio volumio[26466]: info: Loading plugin "upnp"... Apr 08 04:01:18 volumio volumio[26466]: info: [1712541678886] Starting Upmpd Daemon Apr 08 04:01:18 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 04:01:18 volumio volumio[26466]: info: Loading plugin "my_music"... Apr 08 04:01:18 volumio volumio[26466]: info: Loading plugin "mpd"... Apr 08 04:01:19 volumio volumio[26466]: info: Creating MPD Configuration file Apr 08 04:01:19 volumio volumio[26466]: info: Loading plugin "upnp_browser"... Apr 08 04:01:19 volumio sudo[26503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 08 04:01:19 volumio sudo[26503]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:19 volumio sudo[26503]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:19 volumio sudo[26505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 08 04:01:19 volumio sudo[26505]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:19 volumio systemd[1]: Stopping Music Player Daemon... Apr 08 04:01:19 volumio systemd[1]: mpd.service: Succeeded. Apr 08 04:01:19 volumio systemd[1]: Stopped Music Player Daemon. Apr 08 04:01:19 volumio systemd[1]: Starting Music Player Daemon... Apr 08 04:01:19 volumio volumio[26466]: info: Loading plugin "networkfs"... Apr 08 04:01:19 volumio volumio-remote-updater[535]: [2024-04-08 04:01:19] [connect] Successful connection Apr 08 04:01:19 volumio volumio[26466]: info: Starting Udev Watcher for removable devices Apr 08 04:01:20 volumio volumio[26466]: info: Ignoring mount for partition: boot Apr 08 04:01:20 volumio volumio[26466]: info: Ignoring mount for partition: volumio Apr 08 04:01:20 volumio volumio[26466]: info: Ignoring mount for partition: volumio_data Apr 08 04:01:20 volumio volumio[26466]: info: Mounting Device INTENSO Apr 08 04:01:20 volumio sudo[26516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Apr 08 04:01:20 volumio sudo[26516]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:20 volumio sudo[26516]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:20 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 04:01:20 volumio volumio[26466]: info: Loading plugin "alarm-clock"... Apr 08 04:01:20 volumio volumio[26466]: info: Loading plugin "airplay_emulation"... Apr 08 04:01:20 volumio volumio[26466]: info: Starting Shairport Sync Apr 08 04:01:20 volumio volumio[26466]: info: Loading plugin "last_100"... Apr 08 04:01:20 volumio volumio[26466]: info: Loading plugin "webradio"... Apr 08 04:01:20 volumio volumio[26466]: info: Loading plugin "i2s_dacs"... Apr 08 04:01:20 volumio volumio[26466]: info: Loading plugin "volumiodiscovery"... Apr 08 04:01:20 volumio volumio[26466]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 08 04:01:20 volumio volumio[26466]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:01:20 volumio node[26466]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 08 04:01:20 volumio volumio[26466]: *** WARNING *** For more information see Apr 08 04:01:20 volumio volumio[26466]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 08 04:01:20 volumio volumio[26466]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:01:20 volumio volumio[26466]: *** WARNING *** For more information see Apr 08 04:01:20 volumio node[26466]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:01:20 volumio node[26466]: *** WARNING *** For more information see Apr 08 04:01:20 volumio node[26466]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 08 04:01:20 volumio node[26466]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:01:20 volumio node[26466]: *** WARNING *** For more information see Apr 08 04:01:20 volumio volumio[26466]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 08 04:01:20 volumio volumio[26466]: info: Discovery: Started advertising with name: undefined Apr 08 04:01:20 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 04:01:20 volumio volumio[26466]: info: Loading plugin "outputs"... Apr 08 04:01:20 volumio volumio[26466]: info: Loading plugin "albumart"... Apr 08 04:01:20 volumio volumio[26466]: info: Plugin example_plugin is not enabled Apr 08 04:01:20 volumio volumio[26466]: info: Loading plugin "inputs"... Apr 08 04:01:20 volumio volumio[26466]: info: Loading plugin "updater_comm"... Apr 08 04:01:20 volumio mpd[26510]: Apr 08 04:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 08 04:01:21 volumio volumio[26466]: info: Plugin mpdemulation is not enabled Apr 08 04:01:21 volumio volumio[26466]: info: Loading plugin "rest_api"... Apr 08 04:01:21 volumio mpd[26510]: Apr 08 04:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 08 04:01:21 volumio mpd[26510]: Apr 08 04:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 08 04:01:21 volumio systemd[1]: Started Music Player Daemon. Apr 08 04:01:21 volumio volumio[26466]: info: Loading plugin "websocket"... Apr 08 04:01:21 volumio sudo[26505]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:21 volumio volumio[26466]: info: Loading i18n strings for locale de Apr 08 04:01:21 volumio volumio[26466]: Updating browse sources language Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:01:21 volumio volumio[26466]: Forking 3 albumart workers Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::initPlayerControls Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: Express server listening on port 3000 Apr 08 04:01:21 volumio volumio[26466]: [Metrics] WebUI: 5s 25.89ms Apr 08 04:01:21 volumio volumio[26466]: info: CoreStateMachine::resetVolumioState Apr 08 04:01:21 volumio volumio[26466]: info: CoreStateMachine::getcurrentVolume Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::volumioRetrievevolume Apr 08 04:01:21 volumio volumio[26466]: info: MPD Permissions set Apr 08 04:01:21 volumio volumio-remote-updater[535]: [2024-04-08 04:01:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712541679 101 Apr 08 04:01:21 volumio volumio[26466]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 08 04:01:21 volumio volumio[26466]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 08 04:01:21 volumio volumio[26466]: info: MPD running with PID26510 Apr 08 04:01:21 volumio volumio[26466]: ,establishing connection Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: Reloading queue from file Apr 08 04:01:21 volumio volumio[26466]: error: updateQueue error: null Apr 08 04:01:21 volumio volumio[26466]: info: Setting Device type: Raspberry PI Apr 08 04:01:21 volumio volumio[26466]: info: CoreStateMachine::setRepeat false single undefined Apr 08 04:01:21 volumio volumio[26466]: info: CoreStateMachine::pushState Apr 08 04:01:21 volumio volumio[26466]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::volumioPushState Apr 08 04:01:21 volumio volumio[26466]: info: CoreStateMachine::setRandom null Apr 08 04:01:21 volumio volumio[26466]: info: CoreStateMachine::pushState Apr 08 04:01:21 volumio volumio[26466]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::volumioPushState Apr 08 04:01:21 volumio volumio[26466]: info: Completed loading Core Plugins Apr 08 04:01:21 volumio volumio[26466]: info: Preparing to generate the ALSA configuration file Apr 08 04:01:21 volumio volumio[26466]: info: VolumeController:: Volume=undefined Mute =false Apr 08 04:01:21 volumio volumio[26466]: info: CoreStateMachine::pushState Apr 08 04:01:21 volumio volumio[26466]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::volumioPushState Apr 08 04:01:21 volumio volumio[26466]: info: CoreStateMachine::updateTrackBlock Apr 08 04:01:21 volumio volumio[26466]: info: CorePlayQueue::getTrackBlock Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::volumioRetrievevolume Apr 08 04:01:21 volumio volumio[26466]: error: updateQueue error: null Apr 08 04:01:21 volumio volumio[26466]: info: Asound.conf file unchanged, so no further update is needed Apr 08 04:01:21 volumio volumio[26466]: info: Output device has changed, restarting MPD Apr 08 04:01:21 volumio volumio[26466]: info: Output device has changed, restarting Shairport Sync Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:21 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:21 volumio sudo[26571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 08 04:01:21 volumio sudo[26571]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:21 volumio sudo[26570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 08 04:01:21 volumio sudo[26570]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:21 volumio systemd[1]: Stopping Music Player Daemon... Apr 08 04:01:21 volumio sudo[26570]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:22 volumio systemd[1]: mpd.service: Succeeded. Apr 08 04:01:22 volumio systemd[1]: Stopped Music Player Daemon. Apr 08 04:01:22 volumio sudo[26495]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:22 volumio volumio[26466]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 08 04:01:22 volumio volumio[26466]: info: ___________ START PLUGINS ___________ Apr 08 04:01:22 volumio systemd[1]: Starting Music Player Daemon... Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 08 04:01:22 volumio volumio[26466]: info: [1712541682080] CoreMusicLibrary::Adding element Media Servers Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:22 volumio volumio[26466]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:22 volumio volumio[26466]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 08 04:01:22 volumio volumio[26466]: info: [1712541682265] CoreMusicLibrary::Adding element Last_100 Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 08 04:01:22 volumio volumio[26466]: info: [1712541682270] CoreMusicLibrary::Adding element Webradio Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 08 04:01:22 volumio volumio[26466]: info: Volumio Calling Home Apr 08 04:01:22 volumio volumio[26466]: Starting albumart workers Apr 08 04:01:22 volumio volumio[26466]: Starting albumart workers Apr 08 04:01:22 volumio volumio[26466]: Starting albumart workers Apr 08 04:01:22 volumio volumio[26466]: info: Completed starting Core Plugins Apr 08 04:01:22 volumio volumio[26466]: info: ------------------------------------------- Apr 08 04:01:22 volumio volumio[26466]: info: ----- MyVolumio plugins startup ---- Apr 08 04:01:22 volumio volumio[26466]: info: ------------------------------------------- Apr 08 04:01:22 volumio volumio[26466]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 08 04:01:22 volumio volumio[26466]: info: MPD Permissions set Apr 08 04:01:22 volumio volumio[26466]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 08 04:01:22 volumio volumio[26466]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:22 volumio volumio[26466]: info: VolumeController:: Volume=undefined Mute =false Apr 08 04:01:22 volumio volumio[26466]: info: CoreStateMachine::pushState Apr 08 04:01:22 volumio volumio[26466]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:22 volumio volumio[26466]: info: CoreCommandRouter::volumioPushState Apr 08 04:01:22 volumio volumio[26466]: info: Starting Shairport Sync Apr 08 04:01:22 volumio volumio[26466]: info: Starting Shairport Sync Apr 08 04:01:22 volumio volumio[26466]: info: Starting Shairport Sync Apr 08 04:01:22 volumio sudo[26595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 08 04:01:22 volumio sudo[26595]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:23 volumio sudo[26598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 08 04:01:23 volumio sudo[26598]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 08 04:01:23 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 08 04:01:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 08 04:01:23 volumio sudo[26600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 08 04:01:23 volumio sudo[26600]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 08 04:01:23 volumio sudo[26595]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 08 04:01:23 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 08 04:01:23 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 08 04:01:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 08 04:01:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 08 04:01:23 volumio sudo[26600]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:23 volumio sudo[26598]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:23 volumio volumio[26466]: info: Shairport-Sync Started Apr 08 04:01:23 volumio volumio[26466]: Error adding Membership: Error: addMembership EINVAL Apr 08 04:01:23 volumio volumio[26466]: info: Shairport-Sync Started Apr 08 04:01:23 volumio volumio[26466]: info: Shairport-Sync Started Apr 08 04:01:23 volumio volumio[26466]: info: CoreCommandRouter::volumioGetState Apr 08 04:01:23 volumio volumio[26466]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:23 volumio mpd[26588]: Apr 08 04:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 08 04:01:24 volumio mpd[26588]: Apr 08 04:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 08 04:01:24 volumio mpd[26588]: Apr 08 04:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 08 04:01:24 volumio systemd[1]: Started Music Player Daemon. Apr 08 04:01:24 volumio sudo[26571]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:24 volumio volumio[26466]: error: updateQueue error: null Apr 08 04:01:25 volumio volumio[26466]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Apr 08 04:01:25 volumio volumio[26466]: info: Discovery: Started advertising with name: undefined Apr 08 04:01:28 volumio volumio[26466]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 04:01:28 volumio volumio[26466]: TypeError: Cannot read property 'charAt' of undefined Apr 08 04:01:28 volumio volumio[26466]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 08 04:01:28 volumio volumio[26466]: at listOnTimeout (internal/timers.js:554:17) Apr 08 04:01:28 volumio volumio[26466]: at processTimers (internal/timers.js:497:7) Apr 08 04:01:28 volumio volumio[26466]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 04:01:29 volumio sudo[26643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-08 04:00 Apr 08 04:01:29 volumio sudo[26643]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:29 volumio sudo[26643]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:29 volumio volumio-remote-updater[535]: [2024-04-08 04:01:29] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 08 04:01:29 volumio volumio-remote-updater[535]: [2024-04-08 04:01:29] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 08 04:01:29 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 08 04:01:29 volumio systemd[1]: media-INTENSO.mount: Succeeded. Apr 08 04:01:29 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 08 04:01:29 volumio systemd[1]: Started dynamicswap service. Apr 08 04:01:29 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 08 04:01:29 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 08 04:01:29 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 95. Apr 08 04:01:29 volumio systemd[1]: Started dynamicswap service. Apr 08 04:01:29 volumio systemd[1]: Stopped Volumio Backend Module. Apr 08 04:01:29 volumio systemd[1]: Started Volumio Backend Module. Apr 08 04:01:29 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 08 04:01:31 volumio volumio[26658]: info: ------------------------------------------- Apr 08 04:01:31 volumio volumio[26658]: info: ----- Volumio3 ---- Apr 08 04:01:31 volumio volumio[26658]: info: ------------------------------------------- Apr 08 04:01:31 volumio volumio[26658]: info: ----- System startup ---- Apr 08 04:01:31 volumio volumio[26658]: info: ------------------------------------------- Apr 08 04:01:32 volumio volumio[26658]: info: MYVOLUMIO Environment detected Apr 08 04:01:32 volumio volumio[26658]: info: Plugin folders cleanup Apr 08 04:01:32 volumio volumio[26658]: info: Scanning into folder /volumio/app/plugins/ Apr 08 04:01:32 volumio volumio[26658]: info: Scanning category audio_interface Apr 08 04:01:32 volumio volumio[26658]: info: Scanning category miscellanea Apr 08 04:01:32 volumio volumio[26658]: info: Scanning category music_service Apr 08 04:01:32 volumio volumio[26658]: info: Scanning category plugins.json Apr 08 04:01:32 volumio volumio[26658]: info: Scanning category system_controller Apr 08 04:01:32 volumio volumio[26658]: info: Scanning category user_interface Apr 08 04:01:32 volumio volumio[26658]: info: Scanning into folder /data/plugins/ Apr 08 04:01:32 volumio volumio[26658]: info: Plugin folders cleanup completed Apr 08 04:01:32 volumio volumio[26658]: info: ------------------------------------------- Apr 08 04:01:32 volumio volumio[26658]: info: ----- Core plugins startup ---- Apr 08 04:01:32 volumio volumio[26658]: info: ------------------------------------------- Apr 08 04:01:32 volumio volumio[26658]: info: Loading plugins from folder /volumio/app/plugins/ Apr 08 04:01:32 volumio volumio[26658]: info: Adding plugin upnp to MyMusic Plugins Apr 08 04:01:32 volumio volumio[26658]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 08 04:01:32 volumio volumio[26658]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 08 04:01:32 volumio volumio[26658]: info: Loading plugins from folder /data/plugins/ Apr 08 04:01:32 volumio volumio[26658]: info: Loading plugin "system"... Apr 08 04:01:32 volumio volumio[26658]: info: Loading plugin "appearance"... Apr 08 04:01:33 volumio volumio[26658]: info: Loading plugin "network"... Apr 08 04:01:33 volumio volumio[26658]: info: Refreshing Cached IP Addresses Apr 08 04:01:33 volumio sudo[26681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 08 04:01:33 volumio sudo[26681]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:33 volumio sudo[26681]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:33 volumio sudo[26683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 08 04:01:33 volumio volumio[26658]: info: Loading plugin "services"... Apr 08 04:01:33 volumio sudo[26683]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:33 volumio volumio[26658]: info: Loading plugin "alsa_controller"... Apr 08 04:01:33 volumio sudo[26683]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:33 volumio sudo[26686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 08 04:01:33 volumio sudo[26686]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:33 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 08 04:01:33 volumio volumio[26658]: info: Loading plugin "wizard"... Apr 08 04:01:33 volumio volumio[26658]: info: Loading plugin "volumio_command_line_client"... Apr 08 04:01:33 volumio volumio[26658]: info: Loading plugin "upnp"... Apr 08 04:01:33 volumio volumio[26658]: info: [1712541693796] Starting Upmpd Daemon Apr 08 04:01:33 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 04:01:33 volumio volumio[26658]: info: Loading plugin "my_music"... Apr 08 04:01:33 volumio volumio[26658]: info: Loading plugin "mpd"... Apr 08 04:01:34 volumio volumio[26658]: info: Creating MPD Configuration file Apr 08 04:01:34 volumio volumio[26658]: info: Loading plugin "upnp_browser"... Apr 08 04:01:34 volumio sudo[26694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 08 04:01:34 volumio sudo[26694]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:34 volumio sudo[26694]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:34 volumio sudo[26696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 08 04:01:34 volumio sudo[26696]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:34 volumio systemd[1]: Stopping Music Player Daemon... Apr 08 04:01:34 volumio systemd[1]: mpd.service: Succeeded. Apr 08 04:01:34 volumio systemd[1]: Stopped Music Player Daemon. Apr 08 04:01:34 volumio systemd[1]: Starting Music Player Daemon... Apr 08 04:01:34 volumio volumio[26658]: info: Loading plugin "networkfs"... Apr 08 04:01:34 volumio volumio-remote-updater[535]: [2024-04-08 04:01:34] [connect] Successful connection Apr 08 04:01:34 volumio volumio[26658]: info: Starting Udev Watcher for removable devices Apr 08 04:01:35 volumio volumio[26658]: info: Ignoring mount for partition: boot Apr 08 04:01:35 volumio volumio[26658]: info: Ignoring mount for partition: volumio Apr 08 04:01:35 volumio volumio[26658]: info: Ignoring mount for partition: volumio_data Apr 08 04:01:35 volumio volumio[26658]: info: Mounting Device INTENSO Apr 08 04:01:35 volumio sudo[26707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Apr 08 04:01:35 volumio sudo[26707]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:35 volumio sudo[26707]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:35 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 04:01:35 volumio volumio[26658]: info: Loading plugin "alarm-clock"... Apr 08 04:01:35 volumio volumio[26658]: info: Loading plugin "airplay_emulation"... Apr 08 04:01:35 volumio volumio[26658]: info: Starting Shairport Sync Apr 08 04:01:35 volumio volumio[26658]: info: Loading plugin "last_100"... Apr 08 04:01:35 volumio volumio[26658]: info: Loading plugin "webradio"... Apr 08 04:01:35 volumio volumio[26658]: info: Loading plugin "i2s_dacs"... Apr 08 04:01:35 volumio volumio[26658]: info: Loading plugin "volumiodiscovery"... Apr 08 04:01:35 volumio volumio[26658]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 08 04:01:35 volumio volumio[26658]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:01:35 volumio volumio[26658]: *** WARNING *** For more information see Apr 08 04:01:35 volumio node[26658]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 08 04:01:35 volumio volumio[26658]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 08 04:01:35 volumio volumio[26658]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:01:35 volumio volumio[26658]: *** WARNING *** For more information see Apr 08 04:01:35 volumio node[26658]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:01:35 volumio node[26658]: *** WARNING *** For more information see Apr 08 04:01:35 volumio node[26658]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 08 04:01:35 volumio node[26658]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:01:35 volumio node[26658]: *** WARNING *** For more information see Apr 08 04:01:35 volumio volumio[26658]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 08 04:01:35 volumio volumio[26658]: info: Discovery: Started advertising with name: undefined Apr 08 04:01:35 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 04:01:35 volumio volumio[26658]: info: Loading plugin "outputs"... Apr 08 04:01:35 volumio volumio[26658]: info: Loading plugin "albumart"... Apr 08 04:01:35 volumio volumio[26658]: info: Plugin example_plugin is not enabled Apr 08 04:01:35 volumio volumio[26658]: info: Loading plugin "inputs"... Apr 08 04:01:35 volumio volumio[26658]: info: Loading plugin "updater_comm"... Apr 08 04:01:35 volumio mpd[26701]: Apr 08 04:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 08 04:01:35 volumio mpd[26701]: Apr 08 04:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 08 04:01:35 volumio mpd[26701]: Apr 08 04:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 08 04:01:35 volumio systemd[1]: Started Music Player Daemon. Apr 08 04:01:35 volumio sudo[26696]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:35 volumio volumio[26658]: info: Plugin mpdemulation is not enabled Apr 08 04:01:35 volumio volumio[26658]: info: Loading plugin "rest_api"... Apr 08 04:01:36 volumio volumio[26658]: info: Loading plugin "websocket"... Apr 08 04:01:36 volumio volumio[26658]: info: Loading i18n strings for locale de Apr 08 04:01:36 volumio volumio[26658]: Updating browse sources language Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:01:36 volumio volumio[26658]: Forking 3 albumart workers Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::initPlayerControls Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: Express server listening on port 3000 Apr 08 04:01:36 volumio volumio[26658]: [Metrics] WebUI: 5s 75.55ms Apr 08 04:01:36 volumio volumio[26658]: info: CoreStateMachine::resetVolumioState Apr 08 04:01:36 volumio volumio[26658]: info: CoreStateMachine::getcurrentVolume Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::volumioRetrievevolume Apr 08 04:01:36 volumio volumio[26658]: info: MPD Permissions set Apr 08 04:01:36 volumio volumio-remote-updater[535]: [2024-04-08 04:01:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712541694 101 Apr 08 04:01:36 volumio volumio[26658]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 08 04:01:36 volumio volumio[26658]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 08 04:01:36 volumio volumio[26658]: info: MPD running with PID26701 Apr 08 04:01:36 volumio volumio[26658]: ,establishing connection Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: Reloading queue from file Apr 08 04:01:36 volumio volumio[26658]: error: updateQueue error: null Apr 08 04:01:36 volumio volumio[26658]: info: Setting Device type: Raspberry PI Apr 08 04:01:36 volumio volumio[26658]: info: CoreStateMachine::setRepeat false single undefined Apr 08 04:01:36 volumio volumio[26658]: info: CoreStateMachine::pushState Apr 08 04:01:36 volumio volumio[26658]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::volumioPushState Apr 08 04:01:36 volumio volumio[26658]: info: CoreStateMachine::setRandom null Apr 08 04:01:36 volumio volumio[26658]: info: CoreStateMachine::pushState Apr 08 04:01:36 volumio volumio[26658]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::volumioPushState Apr 08 04:01:36 volumio volumio[26658]: info: Completed loading Core Plugins Apr 08 04:01:36 volumio volumio[26658]: info: Preparing to generate the ALSA configuration file Apr 08 04:01:36 volumio volumio[26658]: info: VolumeController:: Volume=undefined Mute =false Apr 08 04:01:36 volumio volumio[26658]: info: CoreStateMachine::pushState Apr 08 04:01:36 volumio volumio[26658]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::volumioPushState Apr 08 04:01:36 volumio volumio[26658]: info: CoreStateMachine::updateTrackBlock Apr 08 04:01:36 volumio volumio[26658]: info: CorePlayQueue::getTrackBlock Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::volumioRetrievevolume Apr 08 04:01:36 volumio volumio[26658]: error: updateQueue error: null Apr 08 04:01:36 volumio volumio[26658]: info: Asound.conf file unchanged, so no further update is needed Apr 08 04:01:36 volumio volumio[26658]: info: Output device has changed, restarting MPD Apr 08 04:01:36 volumio volumio[26658]: info: Output device has changed, restarting Shairport Sync Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:36 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:36 volumio sudo[26761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 08 04:01:36 volumio sudo[26761]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:36 volumio sudo[26761]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:36 volumio sudo[26763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 08 04:01:36 volumio sudo[26763]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:36 volumio volumio[26658]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 08 04:01:37 volumio systemd[1]: Stopping Music Player Daemon... Apr 08 04:01:37 volumio volumio[26658]: info: ___________ START PLUGINS ___________ Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 08 04:01:37 volumio volumio[26658]: info: [1712541697015] CoreMusicLibrary::Adding element Media Servers Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:01:37 volumio systemd[1]: mpd.service: Succeeded. Apr 08 04:01:37 volumio systemd[1]: Stopped Music Player Daemon. Apr 08 04:01:37 volumio sudo[26686]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:37 volumio systemd[1]: Starting Music Player Daemon... Apr 08 04:01:37 volumio volumio[26658]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:37 volumio volumio[26658]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 08 04:01:37 volumio volumio[26658]: info: [1712541697228] CoreMusicLibrary::Adding element Last_100 Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 08 04:01:37 volumio volumio[26658]: info: [1712541697236] CoreMusicLibrary::Adding element Webradio Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 08 04:01:37 volumio volumio[26658]: info: Volumio Calling Home Apr 08 04:01:37 volumio volumio[26658]: Starting albumart workers Apr 08 04:01:37 volumio volumio[26658]: Starting albumart workers Apr 08 04:01:37 volumio volumio[26658]: Starting albumart workers Apr 08 04:01:37 volumio volumio[26658]: info: Completed starting Core Plugins Apr 08 04:01:37 volumio volumio[26658]: info: ------------------------------------------- Apr 08 04:01:37 volumio volumio[26658]: info: ----- MyVolumio plugins startup ---- Apr 08 04:01:37 volumio volumio[26658]: info: ------------------------------------------- Apr 08 04:01:37 volumio volumio[26658]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 08 04:01:37 volumio volumio[26658]: info: MPD Permissions set Apr 08 04:01:37 volumio volumio[26658]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 08 04:01:37 volumio volumio[26658]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:37 volumio volumio[26658]: info: VolumeController:: Volume=undefined Mute =false Apr 08 04:01:37 volumio volumio[26658]: info: CoreStateMachine::pushState Apr 08 04:01:37 volumio volumio[26658]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:37 volumio volumio[26658]: info: CoreCommandRouter::volumioPushState Apr 08 04:01:38 volumio volumio[26658]: info: Starting Shairport Sync Apr 08 04:01:38 volumio volumio[26658]: info: Starting Shairport Sync Apr 08 04:01:38 volumio volumio[26658]: info: Starting Shairport Sync Apr 08 04:01:38 volumio sudo[26787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 08 04:01:38 volumio sudo[26787]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:38 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 08 04:01:38 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 08 04:01:38 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 08 04:01:38 volumio sudo[26789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 08 04:01:38 volumio sudo[26789]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:38 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 08 04:01:38 volumio sudo[26787]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:38 volumio sudo[26791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 08 04:01:38 volumio sudo[26791]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:38 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 08 04:01:38 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 08 04:01:38 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 08 04:01:38 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 08 04:01:38 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 08 04:01:38 volumio sudo[26789]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:38 volumio volumio[26658]: info: Shairport-Sync Started Apr 08 04:01:38 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 08 04:01:38 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 08 04:01:38 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 08 04:01:38 volumio volumio[26658]: Error adding Membership: Error: addMembership EINVAL Apr 08 04:01:38 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 08 04:01:38 volumio sudo[26791]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:38 volumio volumio[26658]: info: Shairport-Sync Started Apr 08 04:01:38 volumio volumio[26658]: info: Shairport-Sync Started Apr 08 04:01:38 volumio volumio[26658]: info: CoreCommandRouter::volumioGetState Apr 08 04:01:38 volumio volumio[26658]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:39 volumio mpd[26779]: Apr 08 04:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 08 04:01:39 volumio mpd[26779]: Apr 08 04:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 08 04:01:39 volumio mpd[26779]: Apr 08 04:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 08 04:01:39 volumio systemd[1]: Started Music Player Daemon. Apr 08 04:01:39 volumio sudo[26763]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:39 volumio volumio[26658]: error: updateQueue error: null Apr 08 04:01:40 volumio volumio[26658]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Apr 08 04:01:40 volumio volumio[26658]: info: Discovery: Started advertising with name: undefined Apr 08 04:01:43 volumio volumio[26658]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 04:01:43 volumio volumio[26658]: TypeError: Cannot read property 'charAt' of undefined Apr 08 04:01:43 volumio volumio[26658]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 08 04:01:43 volumio volumio[26658]: at listOnTimeout (internal/timers.js:554:17) Apr 08 04:01:43 volumio volumio[26658]: at processTimers (internal/timers.js:497:7) Apr 08 04:01:43 volumio volumio[26658]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 04:01:44 volumio sudo[26835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-08 04:00 Apr 08 04:01:44 volumio sudo[26835]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:44 volumio sudo[26835]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:44 volumio volumio-remote-updater[535]: [2024-04-08 04:01:44] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 08 04:01:44 volumio volumio-remote-updater[535]: [2024-04-08 04:01:44] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 08 04:01:44 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 08 04:01:44 volumio systemd[1]: media-INTENSO.mount: Succeeded. Apr 08 04:01:44 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 08 04:01:44 volumio systemd[1]: Started dynamicswap service. Apr 08 04:01:44 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 08 04:01:44 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 08 04:01:44 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 96. Apr 08 04:01:44 volumio systemd[1]: Stopped Volumio Backend Module. Apr 08 04:01:44 volumio systemd[1]: Started Volumio Backend Module. Apr 08 04:01:44 volumio systemd[1]: Started dynamicswap service. Apr 08 04:01:45 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 08 04:01:46 volumio volumio[26849]: info: ------------------------------------------- Apr 08 04:01:46 volumio volumio[26849]: info: ----- Volumio3 ---- Apr 08 04:01:46 volumio volumio[26849]: info: ------------------------------------------- Apr 08 04:01:46 volumio volumio[26849]: info: ----- System startup ---- Apr 08 04:01:46 volumio volumio[26849]: info: ------------------------------------------- Apr 08 04:01:47 volumio volumio[26849]: info: MYVOLUMIO Environment detected Apr 08 04:01:47 volumio volumio[26849]: info: Plugin folders cleanup Apr 08 04:01:47 volumio volumio[26849]: info: Scanning into folder /volumio/app/plugins/ Apr 08 04:01:47 volumio volumio[26849]: info: Scanning category audio_interface Apr 08 04:01:47 volumio volumio[26849]: info: Scanning category miscellanea Apr 08 04:01:47 volumio volumio[26849]: info: Scanning category music_service Apr 08 04:01:47 volumio volumio[26849]: info: Scanning category plugins.json Apr 08 04:01:47 volumio volumio[26849]: info: Scanning category system_controller Apr 08 04:01:47 volumio volumio[26849]: info: Scanning category user_interface Apr 08 04:01:47 volumio volumio[26849]: info: Scanning into folder /data/plugins/ Apr 08 04:01:47 volumio volumio[26849]: info: Plugin folders cleanup completed Apr 08 04:01:47 volumio volumio[26849]: info: ------------------------------------------- Apr 08 04:01:47 volumio volumio[26849]: info: ----- Core plugins startup ---- Apr 08 04:01:47 volumio volumio[26849]: info: ------------------------------------------- Apr 08 04:01:47 volumio volumio[26849]: info: Loading plugins from folder /volumio/app/plugins/ Apr 08 04:01:47 volumio volumio[26849]: info: Adding plugin upnp to MyMusic Plugins Apr 08 04:01:47 volumio volumio[26849]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 08 04:01:47 volumio volumio[26849]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 08 04:01:47 volumio volumio[26849]: info: Loading plugins from folder /data/plugins/ Apr 08 04:01:47 volumio volumio[26849]: info: Loading plugin "system"... Apr 08 04:01:47 volumio volumio[26849]: info: Loading plugin "appearance"... Apr 08 04:01:48 volumio volumio[26849]: info: Loading plugin "network"... Apr 08 04:01:48 volumio volumio[26849]: info: Refreshing Cached IP Addresses Apr 08 04:01:48 volumio sudo[26874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 08 04:01:48 volumio sudo[26874]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:48 volumio sudo[26874]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:48 volumio volumio[26849]: info: Loading plugin "services"... Apr 08 04:01:48 volumio sudo[26876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 08 04:01:48 volumio sudo[26876]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:48 volumio volumio[26849]: info: Loading plugin "alsa_controller"... Apr 08 04:01:48 volumio sudo[26876]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:48 volumio sudo[26879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 08 04:01:48 volumio sudo[26879]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:48 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 08 04:01:48 volumio volumio[26849]: info: Loading plugin "wizard"... Apr 08 04:01:48 volumio volumio[26849]: info: Loading plugin "volumio_command_line_client"... Apr 08 04:01:48 volumio volumio[26849]: info: Loading plugin "upnp"... Apr 08 04:01:48 volumio volumio[26849]: info: [1712541708872] Starting Upmpd Daemon Apr 08 04:01:48 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 04:01:48 volumio volumio[26849]: info: Loading plugin "my_music"... Apr 08 04:01:48 volumio volumio[26849]: info: Loading plugin "mpd"... Apr 08 04:01:49 volumio volumio[26849]: info: Creating MPD Configuration file Apr 08 04:01:49 volumio volumio[26849]: info: Loading plugin "upnp_browser"... Apr 08 04:01:49 volumio sudo[26887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 08 04:01:49 volumio sudo[26887]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:49 volumio sudo[26887]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:49 volumio sudo[26889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 08 04:01:49 volumio sudo[26889]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:49 volumio systemd[1]: Stopping Music Player Daemon... Apr 08 04:01:49 volumio systemd[1]: mpd.service: Succeeded. Apr 08 04:01:49 volumio systemd[1]: Stopped Music Player Daemon. Apr 08 04:01:49 volumio systemd[1]: Starting Music Player Daemon... Apr 08 04:01:49 volumio volumio[26849]: info: Loading plugin "networkfs"... Apr 08 04:01:49 volumio volumio-remote-updater[535]: [2024-04-08 04:01:49] [connect] Successful connection Apr 08 04:01:49 volumio volumio[26849]: info: Starting Udev Watcher for removable devices Apr 08 04:01:50 volumio volumio[26849]: info: Ignoring mount for partition: boot Apr 08 04:01:50 volumio volumio[26849]: info: Ignoring mount for partition: volumio Apr 08 04:01:50 volumio volumio[26849]: info: Ignoring mount for partition: volumio_data Apr 08 04:01:50 volumio volumio[26849]: info: Mounting Device INTENSO Apr 08 04:01:50 volumio sudo[26900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Apr 08 04:01:50 volumio sudo[26900]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:50 volumio sudo[26900]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:50 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 04:01:50 volumio volumio[26849]: info: Loading plugin "alarm-clock"... Apr 08 04:01:50 volumio volumio[26849]: info: Loading plugin "airplay_emulation"... Apr 08 04:01:50 volumio volumio[26849]: info: Starting Shairport Sync Apr 08 04:01:50 volumio volumio[26849]: info: Loading plugin "last_100"... Apr 08 04:01:50 volumio volumio[26849]: info: Loading plugin "webradio"... Apr 08 04:01:50 volumio volumio[26849]: info: Loading plugin "i2s_dacs"... Apr 08 04:01:50 volumio volumio[26849]: info: Loading plugin "volumiodiscovery"... Apr 08 04:01:50 volumio volumio[26849]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 08 04:01:50 volumio node[26849]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 08 04:01:50 volumio volumio[26849]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:01:50 volumio node[26849]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:01:50 volumio volumio[26849]: *** WARNING *** For more information see Apr 08 04:01:50 volumio node[26849]: *** WARNING *** For more information see Apr 08 04:01:50 volumio volumio[26849]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 08 04:01:50 volumio node[26849]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 08 04:01:50 volumio volumio[26849]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:01:50 volumio node[26849]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:01:50 volumio volumio[26849]: *** WARNING *** For more information see Apr 08 04:01:50 volumio node[26849]: *** WARNING *** For more information see Apr 08 04:01:50 volumio volumio[26849]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 08 04:01:50 volumio volumio[26849]: info: Discovery: Started advertising with name: undefined Apr 08 04:01:50 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 04:01:50 volumio volumio[26849]: info: Loading plugin "outputs"... Apr 08 04:01:50 volumio volumio[26849]: info: Loading plugin "albumart"... Apr 08 04:01:50 volumio volumio[26849]: info: Plugin example_plugin is not enabled Apr 08 04:01:50 volumio volumio[26849]: info: Loading plugin "inputs"... Apr 08 04:01:50 volumio volumio[26849]: info: Loading plugin "updater_comm"... Apr 08 04:01:50 volumio mpd[26894]: Apr 08 04:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 08 04:01:51 volumio mpd[26894]: Apr 08 04:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 08 04:01:51 volumio mpd[26894]: Apr 08 04:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 08 04:01:51 volumio systemd[1]: Started Music Player Daemon. Apr 08 04:01:51 volumio sudo[26889]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:51 volumio volumio[26849]: info: Plugin mpdemulation is not enabled Apr 08 04:01:51 volumio volumio[26849]: info: Loading plugin "rest_api"... Apr 08 04:01:51 volumio volumio[26849]: info: Loading plugin "websocket"... Apr 08 04:01:51 volumio volumio[26849]: info: Loading i18n strings for locale de Apr 08 04:01:51 volumio volumio[26849]: Updating browse sources language Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:01:51 volumio volumio[26849]: Forking 3 albumart workers Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::initPlayerControls Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: Express server listening on port 3000 Apr 08 04:01:51 volumio volumio[26849]: [Metrics] WebUI: 5s 86.26ms Apr 08 04:01:51 volumio volumio[26849]: info: CoreStateMachine::resetVolumioState Apr 08 04:01:51 volumio volumio[26849]: info: CoreStateMachine::getcurrentVolume Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::volumioRetrievevolume Apr 08 04:01:51 volumio volumio[26849]: info: MPD Permissions set Apr 08 04:01:51 volumio volumio-remote-updater[535]: [2024-04-08 04:01:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712541709 101 Apr 08 04:01:51 volumio volumio[26849]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 08 04:01:51 volumio volumio[26849]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 08 04:01:51 volumio volumio[26849]: info: MPD running with PID26894 Apr 08 04:01:51 volumio volumio[26849]: ,establishing connection Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:51 volumio volumio[26849]: info: Reloading queue from file Apr 08 04:01:51 volumio volumio[26849]: error: updateQueue error: null Apr 08 04:01:51 volumio volumio[26849]: info: CoreStateMachine::setRepeat false single undefined Apr 08 04:01:51 volumio volumio[26849]: info: CoreStateMachine::pushState Apr 08 04:01:51 volumio volumio[26849]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::volumioPushState Apr 08 04:01:51 volumio volumio[26849]: info: CoreStateMachine::setRandom null Apr 08 04:01:51 volumio volumio[26849]: info: CoreStateMachine::pushState Apr 08 04:01:51 volumio volumio[26849]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::volumioPushState Apr 08 04:01:51 volumio volumio[26849]: info: Setting Device type: Raspberry PI Apr 08 04:01:51 volumio volumio[26849]: info: Completed loading Core Plugins Apr 08 04:01:51 volumio volumio[26849]: info: Preparing to generate the ALSA configuration file Apr 08 04:01:51 volumio volumio[26849]: info: VolumeController:: Volume=undefined Mute =false Apr 08 04:01:51 volumio volumio[26849]: info: CoreStateMachine::pushState Apr 08 04:01:51 volumio volumio[26849]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::volumioPushState Apr 08 04:01:51 volumio volumio[26849]: info: CoreStateMachine::updateTrackBlock Apr 08 04:01:51 volumio volumio[26849]: info: CorePlayQueue::getTrackBlock Apr 08 04:01:51 volumio volumio[26849]: info: CoreCommandRouter::volumioRetrievevolume Apr 08 04:01:51 volumio volumio[26849]: error: updateQueue error: null Apr 08 04:01:51 volumio volumio[26849]: info: Asound.conf file unchanged, so no further update is needed Apr 08 04:01:51 volumio volumio[26849]: info: Output device has changed, restarting MPD Apr 08 04:01:52 volumio volumio[26849]: info: Output device has changed, restarting Shairport Sync Apr 08 04:01:52 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:52 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:52 volumio sudo[26954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 08 04:01:52 volumio sudo[26954]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:52 volumio sudo[26954]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:52 volumio sudo[26956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 08 04:01:52 volumio sudo[26956]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:52 volumio volumio[26849]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 08 04:01:52 volumio volumio[26849]: info: ___________ START PLUGINS ___________ Apr 08 04:01:52 volumio sudo[26879]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:52 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 04:01:52 volumio volumio[26849]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 08 04:01:52 volumio volumio[26849]: info: [1712541712134] CoreMusicLibrary::Adding element Media Servers Apr 08 04:01:52 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:01:52 volumio systemd[1]: Stopping Music Player Daemon... Apr 08 04:01:52 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:52 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:52 volumio systemd[1]: mpd.service: Succeeded. Apr 08 04:01:52 volumio systemd[1]: Stopped Music Player Daemon. Apr 08 04:01:52 volumio volumio[26849]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 08 04:01:52 volumio systemd[1]: Starting Music Player Daemon... Apr 08 04:01:52 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:52 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:52 volumio volumio[26849]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 08 04:01:52 volumio volumio[26849]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 08 04:01:52 volumio volumio[26849]: info: [1712541712341] CoreMusicLibrary::Adding element Last_100 Apr 08 04:01:52 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:01:52 volumio volumio[26849]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 08 04:01:52 volumio volumio[26849]: info: [1712541712346] CoreMusicLibrary::Adding element Webradio Apr 08 04:01:52 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:01:52 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 08 04:01:52 volumio volumio[26849]: info: Volumio Calling Home Apr 08 04:01:52 volumio volumio[26849]: Starting albumart workers Apr 08 04:01:52 volumio volumio[26849]: Starting albumart workers Apr 08 04:01:52 volumio volumio[26849]: Starting albumart workers Apr 08 04:01:52 volumio volumio[26849]: info: Completed starting Core Plugins Apr 08 04:01:52 volumio volumio[26849]: info: ------------------------------------------- Apr 08 04:01:52 volumio volumio[26849]: info: ----- MyVolumio plugins startup ---- Apr 08 04:01:52 volumio volumio[26849]: info: ------------------------------------------- Apr 08 04:01:52 volumio volumio[26849]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 08 04:01:53 volumio volumio[26849]: info: MPD Permissions set Apr 08 04:01:53 volumio volumio[26849]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 08 04:01:53 volumio volumio[26849]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 08 04:01:53 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:53 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:53 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:53 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:53 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:53 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:53 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:53 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:53 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:01:53 volumio volumio[26849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:01:53 volumio volumio[26849]: info: VolumeController:: Volume=undefined Mute =false Apr 08 04:01:53 volumio volumio[26849]: info: CoreStateMachine::pushState Apr 08 04:01:53 volumio volumio[26849]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:53 volumio volumio[26849]: info: CoreCommandRouter::volumioPushState Apr 08 04:01:53 volumio volumio[26849]: info: Starting Shairport Sync Apr 08 04:01:53 volumio volumio[26849]: info: Starting Shairport Sync Apr 08 04:01:53 volumio volumio[26849]: info: Starting Shairport Sync Apr 08 04:01:53 volumio sudo[26988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 08 04:01:53 volumio sudo[26988]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:53 volumio sudo[26990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 08 04:01:53 volumio sudo[26990]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 08 04:01:53 volumio sudo[26992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 08 04:01:53 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 08 04:01:53 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 08 04:01:53 volumio sudo[26992]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 08 04:01:53 volumio sudo[26988]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:53 volumio sudo[26990]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:53 volumio volumio[26849]: info: CoreCommandRouter::volumioGetState Apr 08 04:01:53 volumio volumio[26849]: info: CorePlayQueue::getTrack 0 Apr 08 04:01:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 08 04:01:53 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 08 04:01:53 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 08 04:01:53 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 08 04:01:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 08 04:01:53 volumio sudo[26992]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:53 volumio volumio[26849]: info: Shairport-Sync Started Apr 08 04:01:53 volumio volumio[26849]: Error adding Membership: Error: addMembership EINVAL Apr 08 04:01:53 volumio volumio[26849]: info: Shairport-Sync Started Apr 08 04:01:53 volumio volumio[26849]: info: Shairport-Sync Started Apr 08 04:01:54 volumio mpd[26972]: Apr 08 04:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 08 04:01:54 volumio wpa_supplicant[810]: wlan0: CTRL-EVENT-DISCONNECTED bssid=3c:a6:2f:c6:e6:0d reason=3 Apr 08 04:01:54 volumio dhcpcd[599]: wlan0: carrier lost Apr 08 04:01:54 volumio wpa_supplicant[810]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Apr 08 04:01:54 volumio wpa_supplicant[810]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Apr 08 04:01:54 volumio mpd[26972]: Apr 08 04:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 08 04:01:54 volumio mpd[26972]: Apr 08 04:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 08 04:01:54 volumio systemd[1]: Started Music Player Daemon. Apr 08 04:01:54 volumio sudo[26956]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:54 volumio volumio[26849]: error: updateQueue error: null Apr 08 04:01:54 volumio avahi-daemon[542]: Withdrawing address record for 192.168.178.24 on wlan0. Apr 08 04:01:54 volumio avahi-daemon[542]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.24. Apr 08 04:01:54 volumio avahi-daemon[542]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 08 04:01:54 volumio dhcpcd[599]: wlan0: deleting route to 192.168.178.0/24 Apr 08 04:01:54 volumio dhcpcd[599]: wlan0: deleting default route via 192.168.178.1 Apr 08 04:01:54 volumio wpa_supplicant[810]: wlan0: Trying to associate with SSID 'G Fritz Zintl' Apr 08 04:01:54 volumio ntpd[8462]: ntpd exiting on signal 15 (Terminated) Apr 08 04:01:54 volumio ntpd[8462]: 192.168.178.1 local addr 192.168.178.24 -> Apr 08 04:01:54 volumio systemd[1]: Stopping Network Time Service... Apr 08 04:01:54 volumio ntpd[8462]: 217.14.146.53 local addr 192.168.178.24 -> Apr 08 04:01:54 volumio ntpd[8462]: 144.76.76.107 local addr 192.168.178.24 -> Apr 08 04:01:54 volumio ntpd[8462]: 116.202.118.202 local addr 192.168.178.24 -> Apr 08 04:01:54 volumio ntpd[8462]: 185.252.140.126 local addr 192.168.178.24 -> Apr 08 04:01:54 volumio ntpd[8462]: 185.104.163.42 local addr 192.168.178.24 -> Apr 08 04:01:54 volumio ntpd[8462]: 78.47.168.188 local addr 192.168.178.24 -> Apr 08 04:01:54 volumio ntpd[8462]: 212.18.3.19 local addr 192.168.178.24 -> Apr 08 04:01:54 volumio systemd[1]: ntp.service: Succeeded. Apr 08 04:01:54 volumio systemd[1]: Stopped Network Time Service. Apr 08 04:01:54 volumio systemd[1]: Starting Network Time Service... Apr 08 04:01:54 volumio ntpd[27062]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 08 04:01:54 volumio ntpd[27062]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Apr 08 04:01:54 volumio systemd[1]: Started Network Time Service. Apr 08 04:01:54 volumio ntpd[27069]: proto: precision = 0.677 usec (-20) Apr 08 04:01:54 volumio ntpd[27069]: format error frequency file /var/lib/ntp/ntp.drift Apr 08 04:01:54 volumio ntpd[27069]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 08 04:01:54 volumio ntpd[27069]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 08 04:01:54 volumio ntpd[27069]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 103 days ago Apr 08 04:01:54 volumio ntpd[27069]: Listen and drop on 0 v6wildcard [::]:123 Apr 08 04:01:54 volumio ntpd[27069]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 08 04:01:54 volumio ntpd[27069]: Listen normally on 2 lo 127.0.0.1:123 Apr 08 04:01:54 volumio ntpd[27069]: Listening on routing socket on fd #19 for interface updates Apr 08 04:01:54 volumio ntpd[27069]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 08 04:01:54 volumio ntpd[27069]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 08 04:01:55 volumio ntpd[27069]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 08 04:01:55 volumio volumio[26849]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Apr 08 04:01:55 volumio volumio[26849]: info: Discovery: Started advertising with name: undefined Apr 08 04:01:56 volumio ntpd[27069]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 08 04:01:57 volumio wpa_supplicant[810]: wlan0: Associated with 3c:a6:2f:c6:e6:0e Apr 08 04:01:57 volumio wpa_supplicant[810]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:a6:2f:c6:e6:0e completed [id=0 id_str=] Apr 08 04:01:57 volumio dhcpcd[599]: wlan0: carrier acquired Apr 08 04:01:57 volumio wpa_supplicant[810]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 08 04:01:57 volumio wpa_supplicant[810]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Apr 08 04:01:57 volumio dhcpcd[599]: wlan0: IAID eb:0d:0b:6e Apr 08 04:01:57 volumio wpa_supplicant[810]: RRM: Ignoring radio measurement request: Not RRM network Apr 08 04:01:57 volumio dhcpcd[599]: wlan0: soliciting a DHCP lease Apr 08 04:01:57 volumio dhcpcd[599]: wlan0: offered 192.168.178.24 from 192.168.178.1 Apr 08 04:01:57 volumio dhcpcd[599]: wlan0: probing address 192.168.178.24/24 Apr 08 04:01:57 volumio ntpd[27069]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 08 04:01:57 volumio dhcpcd[599]: wlan0: soliciting an IPv6 router Apr 08 04:01:58 volumio ntpd[27069]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 08 04:01:58 volumio volumio[26849]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 04:01:58 volumio volumio[26849]: TypeError: Cannot read property 'charAt' of undefined Apr 08 04:01:58 volumio volumio[26849]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 08 04:01:58 volumio volumio[26849]: at listOnTimeout (internal/timers.js:554:17) Apr 08 04:01:58 volumio volumio[26849]: at processTimers (internal/timers.js:497:7) Apr 08 04:01:58 volumio volumio[26849]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 04:01:59 volumio wpa_supplicant[810]: RRM: Ignoring radio measurement request: Not RRM network Apr 08 04:01:59 volumio sudo[27081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-08 04:00 Apr 08 04:01:59 volumio sudo[27081]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:01:59 volumio sudo[27081]: pam_unix(sudo:session): session closed for user root Apr 08 04:01:59 volumio dhcpcd[599]: wlan0: carrier lost Apr 08 04:01:59 volumio wpa_supplicant[810]: wlan0: Associated with 3c:a6:2f:c6:e6:0d Apr 08 04:01:59 volumio wpa_supplicant[810]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:a6:2f:c6:e6:0d completed [id=0 id_str=] Apr 08 04:01:59 volumio wpa_supplicant[810]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 08 04:01:59 volumio volumio-remote-updater[535]: [2024-04-08 04:01:59] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 08 04:01:59 volumio volumio-remote-updater[535]: [2024-04-08 04:01:59] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 08 04:01:59 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 08 04:01:59 volumio systemd[1]: media-INTENSO.mount: Succeeded. Apr 08 04:01:59 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 08 04:01:59 volumio dhcpcd[599]: wlan0: carrier acquired Apr 08 04:01:59 volumio systemd[1]: Started dynamicswap service. Apr 08 04:01:59 volumio dhcpcd[599]: wlan0: IAID eb:0d:0b:6e Apr 08 04:01:59 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 08 04:01:59 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 08 04:01:59 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 97. Apr 08 04:01:59 volumio systemd[1]: Stopped Volumio Backend Module. Apr 08 04:01:59 volumio systemd[1]: Started Volumio Backend Module. Apr 08 04:01:59 volumio systemd[1]: Started dynamicswap service. Apr 08 04:01:59 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 08 04:01:59 volumio dhcpcd[599]: wlan0: soliciting a DHCP lease Apr 08 04:01:59 volumio dhcpcd[599]: wlan0: offered 192.168.178.24 from 192.168.178.1 Apr 08 04:01:59 volumio dhcpcd[599]: wlan0: probing address 192.168.178.24/24 Apr 08 04:02:00 volumio dhcpcd[599]: wlan0: soliciting an IPv6 router Apr 08 04:02:01 volumio volumio[27119]: info: ------------------------------------------- Apr 08 04:02:01 volumio volumio[27119]: info: ----- Volumio3 ---- Apr 08 04:02:01 volumio volumio[27119]: info: ------------------------------------------- Apr 08 04:02:01 volumio volumio[27119]: info: ----- System startup ---- Apr 08 04:02:01 volumio volumio[27119]: info: ------------------------------------------- Apr 08 04:02:02 volumio volumio[27119]: info: MYVOLUMIO Environment detected Apr 08 04:02:02 volumio volumio[27119]: info: Plugin folders cleanup Apr 08 04:02:02 volumio volumio[27119]: info: Scanning into folder /volumio/app/plugins/ Apr 08 04:02:02 volumio volumio[27119]: info: Scanning category audio_interface Apr 08 04:02:02 volumio volumio[27119]: info: Scanning category miscellanea Apr 08 04:02:02 volumio volumio[27119]: info: Scanning category music_service Apr 08 04:02:02 volumio volumio[27119]: info: Scanning category plugins.json Apr 08 04:02:02 volumio volumio[27119]: info: Scanning category system_controller Apr 08 04:02:02 volumio volumio[27119]: info: Scanning category user_interface Apr 08 04:02:02 volumio volumio[27119]: info: Scanning into folder /data/plugins/ Apr 08 04:02:02 volumio volumio[27119]: info: Plugin folders cleanup completed Apr 08 04:02:02 volumio volumio[27119]: info: ------------------------------------------- Apr 08 04:02:02 volumio volumio[27119]: info: ----- Core plugins startup ---- Apr 08 04:02:02 volumio volumio[27119]: info: ------------------------------------------- Apr 08 04:02:02 volumio volumio[27119]: info: Loading plugins from folder /volumio/app/plugins/ Apr 08 04:02:02 volumio volumio[27119]: info: Adding plugin upnp to MyMusic Plugins Apr 08 04:02:02 volumio volumio[27119]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 08 04:02:02 volumio volumio[27119]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 08 04:02:02 volumio volumio[27119]: info: Loading plugins from folder /data/plugins/ Apr 08 04:02:02 volumio volumio[27119]: info: Loading plugin "system"... Apr 08 04:02:02 volumio volumio[27119]: info: Loading plugin "appearance"... Apr 08 04:02:03 volumio volumio[27119]: info: Loading plugin "network"... Apr 08 04:02:03 volumio volumio[27119]: info: Refreshing Cached IP Addresses Apr 08 04:02:03 volumio sudo[27143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 08 04:02:03 volumio sudo[27143]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:02:03 volumio sudo[27143]: pam_unix(sudo:session): session closed for user root Apr 08 04:02:03 volumio sudo[27145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 08 04:02:03 volumio sudo[27145]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:02:03 volumio volumio[27119]: info: Loading plugin "services"... Apr 08 04:02:03 volumio volumio[27119]: info: Loading plugin "alsa_controller"... Apr 08 04:02:03 volumio sudo[27145]: pam_unix(sudo:session): session closed for user root Apr 08 04:02:03 volumio sudo[27148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 08 04:02:03 volumio sudo[27148]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:02:03 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 08 04:02:03 volumio volumio[27119]: info: Loading plugin "wizard"... Apr 08 04:02:03 volumio volumio[27119]: info: Loading plugin "volumio_command_line_client"... Apr 08 04:02:03 volumio volumio[27119]: info: Loading plugin "upnp"... Apr 08 04:02:03 volumio volumio[27119]: info: [1712541723822] Starting Upmpd Daemon Apr 08 04:02:03 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 04:02:03 volumio volumio[27119]: info: Loading plugin "my_music"... Apr 08 04:02:03 volumio volumio[27119]: info: Loading plugin "mpd"... Apr 08 04:02:04 volumio volumio[27119]: info: Creating MPD Configuration file Apr 08 04:02:04 volumio volumio[27119]: info: Loading plugin "upnp_browser"... Apr 08 04:02:04 volumio sudo[27156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 08 04:02:04 volumio sudo[27156]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:02:04 volumio sudo[27156]: pam_unix(sudo:session): session closed for user root Apr 08 04:02:04 volumio sudo[27158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 08 04:02:04 volumio sudo[27158]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:02:04 volumio systemd[1]: Stopping Music Player Daemon... Apr 08 04:02:04 volumio systemd[1]: mpd.service: Succeeded. Apr 08 04:02:04 volumio systemd[1]: Stopped Music Player Daemon. Apr 08 04:02:04 volumio systemd[1]: Starting Music Player Daemon... Apr 08 04:02:04 volumio dhcpcd[599]: wlan0: leased 192.168.178.24 for 864000 seconds Apr 08 04:02:04 volumio avahi-daemon[542]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.24. Apr 08 04:02:04 volumio dhcpcd[599]: wlan0: adding route to 192.168.178.0/24 Apr 08 04:02:04 volumio dhcpcd[599]: wlan0: adding default route via 192.168.178.1 Apr 08 04:02:04 volumio avahi-daemon[542]: New relevant interface wlan0.IPv4 for mDNS. Apr 08 04:02:04 volumio avahi-daemon[542]: Registering new address record for 192.168.178.24 on wlan0.IPv4. Apr 08 04:02:04 volumio volumio[27119]: info: Loading plugin "networkfs"... Apr 08 04:02:04 volumio ntpd[27069]: ntpd exiting on signal 15 (Terminated) Apr 08 04:02:04 volumio systemd[1]: Stopping Network Time Service... Apr 08 04:02:04 volumio systemd[1]: ntp.service: Succeeded. Apr 08 04:02:04 volumio systemd[1]: Stopped Network Time Service. Apr 08 04:02:04 volumio systemd[1]: Starting Network Time Service... Apr 08 04:02:04 volumio ntpd[27196]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 08 04:02:04 volumio ntpd[27196]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Apr 08 04:02:04 volumio systemd[1]: Started Network Time Service. Apr 08 04:02:04 volumio ntpd[27202]: proto: precision = 0.729 usec (-20) Apr 08 04:02:04 volumio ntpd[27202]: format error frequency file /var/lib/ntp/ntp.drift Apr 08 04:02:04 volumio ntpd[27202]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 08 04:02:04 volumio ntpd[27202]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 08 04:02:04 volumio ntpd[27202]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 103 days ago Apr 08 04:02:04 volumio ntpd[27202]: Listen and drop on 0 v6wildcard [::]:123 Apr 08 04:02:04 volumio ntpd[27202]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 08 04:02:04 volumio ntpd[27202]: Listen normally on 2 lo 127.0.0.1:123 Apr 08 04:02:04 volumio ntpd[27202]: Listen normally on 3 wlan0 192.168.178.24:123 Apr 08 04:02:04 volumio ntpd[27202]: Listening on routing socket on fd #20 for interface updates Apr 08 04:02:04 volumio ntpd[27202]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 08 04:02:04 volumio ntpd[27202]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 08 04:02:04 volumio volumio-remote-updater[535]: [2024-04-08 04:02:04] [connect] Successful connection Apr 08 04:02:04 volumio volumio[27119]: info: Starting Udev Watcher for removable devices Apr 08 04:02:05 volumio volumio[27119]: info: Ignoring mount for partition: boot Apr 08 04:02:05 volumio volumio[27119]: info: Ignoring mount for partition: volumio Apr 08 04:02:05 volumio volumio[27119]: info: Ignoring mount for partition: volumio_data Apr 08 04:02:05 volumio wpa_supplicant[810]: RRM: Ignoring radio measurement request: Not RRM network Apr 08 04:02:05 volumio volumio[27119]: info: Mounting Device INTENSO Apr 08 04:02:05 volumio sudo[27210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Apr 08 04:02:05 volumio sudo[27210]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:02:05 volumio sudo[27210]: pam_unix(sudo:session): session closed for user root Apr 08 04:02:05 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 04:02:05 volumio volumio[27119]: info: Loading plugin "alarm-clock"... Apr 08 04:02:05 volumio volumio[27119]: info: Loading plugin "airplay_emulation"... Apr 08 04:02:05 volumio volumio[27119]: info: Starting Shairport Sync Apr 08 04:02:05 volumio volumio[27119]: info: Loading plugin "last_100"... Apr 08 04:02:05 volumio volumio[27119]: info: Loading plugin "webradio"... Apr 08 04:02:05 volumio volumio[27119]: info: Loading plugin "i2s_dacs"... Apr 08 04:02:05 volumio volumio[27119]: info: Loading plugin "volumiodiscovery"... Apr 08 04:02:05 volumio volumio[27119]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 08 04:02:05 volumio volumio[27119]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:02:05 volumio volumio[27119]: *** WARNING *** For more information see Apr 08 04:02:05 volumio volumio[27119]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 08 04:02:05 volumio node[27119]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 08 04:02:05 volumio volumio[27119]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:02:05 volumio volumio[27119]: *** WARNING *** For more information see Apr 08 04:02:05 volumio node[27119]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:02:05 volumio node[27119]: *** WARNING *** For more information see Apr 08 04:02:05 volumio node[27119]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 08 04:02:05 volumio node[27119]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 04:02:05 volumio node[27119]: *** WARNING *** For more information see Apr 08 04:02:05 volumio volumio[27119]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 08 04:02:05 volumio volumio[27119]: info: Discovery: Started advertising with name: undefined Apr 08 04:02:05 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 04:02:05 volumio volumio[27119]: info: Loading plugin "outputs"... Apr 08 04:02:05 volumio volumio[27119]: info: Loading plugin "albumart"... Apr 08 04:02:05 volumio volumio[27119]: info: Plugin example_plugin is not enabled Apr 08 04:02:05 volumio volumio[27119]: info: Loading plugin "inputs"... Apr 08 04:02:05 volumio volumio[27119]: info: Loading plugin "updater_comm"... Apr 08 04:02:05 volumio ntpd[27202]: Soliciting pool server 194.25.134.196 Apr 08 04:02:05 volumio mpd[27204]: Apr 08 04:02 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 08 04:02:06 volumio volumio[27119]: info: Plugin mpdemulation is not enabled Apr 08 04:02:06 volumio volumio[27119]: info: Loading plugin "rest_api"... Apr 08 04:02:06 volumio volumio[27119]: info: Loading plugin "websocket"... Apr 08 04:02:06 volumio mpd[27204]: Apr 08 04:02 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 08 04:02:06 volumio mpd[27204]: Apr 08 04:02 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 08 04:02:06 volumio volumio[27119]: info: Loading i18n strings for locale de Apr 08 04:02:06 volumio systemd[1]: Started Music Player Daemon. Apr 08 04:02:06 volumio volumio[27119]: Updating browse sources language Apr 08 04:02:06 volumio sudo[27158]: pam_unix(sudo:session): session closed for user root Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::initPlayerControls Apr 08 04:02:06 volumio volumio[27119]: Forking 3 albumart workers Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: Express server listening on port 3000 Apr 08 04:02:06 volumio volumio[27119]: [Metrics] WebUI: 5s 57.79ms Apr 08 04:02:06 volumio volumio[27119]: info: CoreStateMachine::resetVolumioState Apr 08 04:02:06 volumio volumio[27119]: info: CoreStateMachine::getcurrentVolume Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::volumioRetrievevolume Apr 08 04:02:06 volumio volumio[27119]: info: MPD Permissions set Apr 08 04:02:06 volumio volumio-remote-updater[535]: [2024-04-08 04:02:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712541724 101 Apr 08 04:02:06 volumio volumio[27119]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 08 04:02:06 volumio volumio[27119]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 08 04:02:06 volumio volumio[27119]: info: MPD running with PID27204 Apr 08 04:02:06 volumio volumio[27119]: ,establishing connection Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:06 volumio ntpd[27202]: Soliciting pool server 130.61.44.4 Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: Reloading queue from file Apr 08 04:02:06 volumio volumio[27119]: error: updateQueue error: null Apr 08 04:02:06 volumio volumio[27119]: info: Setting Device type: Raspberry PI Apr 08 04:02:06 volumio volumio[27119]: info: CoreStateMachine::setRepeat false single undefined Apr 08 04:02:06 volumio volumio[27119]: info: CoreStateMachine::pushState Apr 08 04:02:06 volumio volumio[27119]: info: CorePlayQueue::getTrack 0 Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::volumioPushState Apr 08 04:02:06 volumio volumio[27119]: info: CoreStateMachine::setRandom null Apr 08 04:02:06 volumio volumio[27119]: info: CoreStateMachine::pushState Apr 08 04:02:06 volumio volumio[27119]: info: CorePlayQueue::getTrack 0 Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::volumioPushState Apr 08 04:02:06 volumio volumio[27119]: info: Completed loading Core Plugins Apr 08 04:02:06 volumio volumio[27119]: info: Preparing to generate the ALSA configuration file Apr 08 04:02:06 volumio volumio[27119]: info: VolumeController:: Volume=undefined Mute =false Apr 08 04:02:06 volumio volumio[27119]: info: CoreStateMachine::pushState Apr 08 04:02:06 volumio volumio[27119]: info: CorePlayQueue::getTrack 0 Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::volumioPushState Apr 08 04:02:06 volumio volumio[27119]: info: CoreStateMachine::updateTrackBlock Apr 08 04:02:06 volumio volumio[27119]: info: CorePlayQueue::getTrackBlock Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::volumioRetrievevolume Apr 08 04:02:06 volumio ntpd[27202]: Soliciting pool server 173.249.58.145 Apr 08 04:02:06 volumio volumio[27119]: error: updateQueue error: null Apr 08 04:02:06 volumio volumio[27119]: info: Asound.conf file unchanged, so no further update is needed Apr 08 04:02:06 volumio volumio[27119]: info: Output device has changed, restarting MPD Apr 08 04:02:06 volumio volumio[27119]: info: Output device has changed, restarting Shairport Sync Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:06 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:02:06 volumio sudo[27263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 08 04:02:06 volumio sudo[27263]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:02:06 volumio sudo[27263]: pam_unix(sudo:session): session closed for user root Apr 08 04:02:06 volumio sudo[27265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 08 04:02:06 volumio sudo[27265]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:02:06 volumio volumio[27119]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 08 04:02:07 volumio volumio[27119]: info: ___________ START PLUGINS ___________ Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 08 04:02:07 volumio volumio[27119]: info: [1712541727024] CoreMusicLibrary::Adding element Media Servers Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:02:07 volumio systemd[1]: Stopping Music Player Daemon... Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:02:07 volumio systemd[1]: mpd.service: Succeeded. Apr 08 04:02:07 volumio systemd[1]: Stopped Music Player Daemon. Apr 08 04:02:07 volumio wpa_supplicant[810]: RRM: Ignoring radio measurement request: Not RRM network Apr 08 04:02:07 volumio volumio[27119]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:02:07 volumio systemd[1]: Starting Music Player Daemon... Apr 08 04:02:07 volumio volumio[27119]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 08 04:02:07 volumio volumio[27119]: info: [1712541727231] CoreMusicLibrary::Adding element Last_100 Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 08 04:02:07 volumio volumio[27119]: info: [1712541727236] CoreMusicLibrary::Adding element Webradio Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 08 04:02:07 volumio volumio[27119]: info: Volumio Calling Home Apr 08 04:02:07 volumio volumio[27119]: Starting albumart workers Apr 08 04:02:07 volumio sudo[27148]: pam_unix(sudo:session): session closed for user root Apr 08 04:02:07 volumio volumio[27119]: info: Completed starting Core Plugins Apr 08 04:02:07 volumio volumio[27119]: info: ------------------------------------------- Apr 08 04:02:07 volumio volumio[27119]: info: ----- MyVolumio plugins startup ---- Apr 08 04:02:07 volumio volumio[27119]: info: ------------------------------------------- Apr 08 04:02:07 volumio volumio[27119]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 08 04:02:07 volumio volumio[27119]: Starting albumart workers Apr 08 04:02:07 volumio volumio[27119]: info: MPD Permissions set Apr 08 04:02:07 volumio volumio[27119]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 08 04:02:07 volumio volumio[27119]: Starting albumart workers Apr 08 04:02:07 volumio volumio[27119]: info: Volumio called home Apr 08 04:02:07 volumio volumio[27119]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:07 volumio ntpd[27202]: Soliciting pool server 144.76.76.107 Apr 08 04:02:07 volumio ntpd[27202]: Soliciting pool server 185.244.195.159 Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 04:02:07 volumio ntpd[27202]: Soliciting pool server 207.89.102.10 Apr 08 04:02:07 volumio volumio[27119]: info: VolumeController:: Volume=undefined Mute =false Apr 08 04:02:07 volumio volumio[27119]: info: CoreStateMachine::pushState Apr 08 04:02:07 volumio volumio[27119]: info: CorePlayQueue::getTrack 0 Apr 08 04:02:07 volumio volumio[27119]: info: CoreCommandRouter::volumioPushState Apr 08 04:02:07 volumio volumio[27119]: info: Starting Shairport Sync Apr 08 04:02:07 volumio volumio[27119]: info: Starting Shairport Sync Apr 08 04:02:07 volumio volumio[27119]: info: Starting Shairport Sync Apr 08 04:02:07 volumio sudo[27291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 08 04:02:07 volumio sudo[27291]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:02:07 volumio sudo[27294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 08 04:02:07 volumio sudo[27294]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:02:07 volumio sudo[27295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 08 04:02:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 08 04:02:07 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 08 04:02:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 08 04:02:08 volumio sudo[27295]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 04:02:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 08 04:02:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 08 04:02:08 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 08 04:02:08 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 08 04:02:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 08 04:02:08 volumio sudo[27291]: pam_unix(sudo:session): session closed for user root Apr 08 04:02:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 08 04:02:08 volumio sudo[27294]: pam_unix(sudo:session): session closed for user root Apr 08 04:02:08 volumio sudo[27295]: pam_unix(sudo:session): session closed for user root Apr 08 04:02:08 volumio volumio[27119]: info: Shairport-Sync Started Apr 08 04:02:08 volumio volumio[27119]: Error adding Membership: Error: addMembership EINVAL Apr 08 04:02:08 volumio volumio[27119]: info: Shairport-Sync Started Apr 08 04:02:08 volumio volumio[27119]: info: Shairport-Sync Started Apr 08 04:02:08 volumio volumio[27119]: info: CoreCommandRouter::volumioGetState Apr 08 04:02:08 volumio volumio[27119]: info: CorePlayQueue::getTrack 0 Apr 08 04:02:08 volumio ntpd[27202]: Soliciting pool server 159.69.64.189 Apr 08 04:02:08 volumio ntpd[27202]: Soliciting pool server 94.16.122.152 Apr 08 04:02:08 volumio ntpd[27202]: Soliciting pool server 178.63.166.157 Apr 08 04:02:08 volumio ntpd[27202]: Soliciting pool server 144.76.44.69 Apr 08 04:02:09 volumio mpd[27284]: Apr 08 04:02 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 08 04:02:09 volumio mpd[27284]: Apr 08 04:02 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 08 04:02:09 volumio mpd[27284]: Apr 08 04:02 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 08 04:02:09 volumio systemd[1]: Started Music Player Daemon. Apr 08 04:02:09 volumio sudo[27265]: pam_unix(sudo:session): session closed for user root Apr 08 04:02:09 volumio volumio[27119]: error: updateQueue error: null Apr 08 04:02:09 volumio ntpd[27202]: Soliciting pool server 144.76.59.106 Apr 08 04:02:09 volumio ntpd[27202]: Soliciting pool server 5.1.73.50 Apr 08 04:02:09 volumio ntpd[27202]: Soliciting pool server 78.47.168.188 Apr 08 04:02:10 volumio ntpd[27202]: Soliciting pool server 75.119.140.230 Apr 08 04:02:10 volumio ntpd[27202]: Soliciting pool server 79.133.44.140 Apr 08 04:02:10 volumio volumio[27119]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Apr 08 04:02:10 volumio volumio[27119]: info: Discovery: Started advertising with name: undefined Apr 08 04:02:11 volumio ntpd[27202]: Soliciting pool server 2001:a60::123:1 Apr 08 04:02:11 volumio ntpd[27202]: Soliciting pool server 161.97.164.152 Apr 08 04:02:13 volumio volumio[27119]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 04:02:13 volumio volumio[27119]: TypeError: Cannot read property 'charAt' of undefined Apr 08 04:02:13 volumio volumio[27119]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 08 04:02:13 volumio volumio[27119]: at listOnTimeout (internal/timers.js:554:17) Apr 08 04:02:13 volumio volumio[27119]: at processTimers (internal/timers.js:497:7) Apr 08 04:02:13 volumio volumio[27119]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 04:02:14 volumio sudo[27337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-08 04:01 Apr 08 04:02:14 volumio sudo[27337]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"