-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2024-11-02 05:08:11 CET. -- Nov 02 05:07:01 volumio-raspi3b volumio[6486]: info: ------------------------------------------- Nov 02 05:07:01 volumio-raspi3b volumio[6486]: info: ----- Volumio3 ---- Nov 02 05:07:01 volumio-raspi3b volumio[6486]: info: ------------------------------------------- Nov 02 05:07:01 volumio-raspi3b volumio[6486]: info: ----- System startup ---- Nov 02 05:07:01 volumio-raspi3b volumio[6486]: info: ------------------------------------------- Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: MYVOLUMIO Environment detected Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Plugin folders cleanup Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Scanning into folder /volumio/app/plugins/ Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Scanning category audio_interface Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Scanning category miscellanea Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Scanning category music_service Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Scanning category plugins.json Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Scanning category system_controller Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Scanning category user_interface Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Scanning into folder /data/plugins/ Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Scanning category audio_interface Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Scanning category music_service Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Scanning category system_controller Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Scanning category system_hardware Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Scanning category user_interface Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Plugin folders cleanup completed Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: ------------------------------------------- Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: ----- Core plugins startup ---- Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: ------------------------------------------- Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Loading plugins from folder /volumio/app/plugins/ Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Adding plugin upnp to MyMusic Plugins Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Loading plugins from folder /data/plugins/ Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Loading plugin "system"... Nov 02 05:07:02 volumio-raspi3b volumio[6486]: info: Loading plugin "appearance"... Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: Loading plugin "network"... Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: Refreshing Cached IP Addresses Nov 02 05:07:03 volumio-raspi3b sudo[6515]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:03 volumio-raspi3b sudo[6515]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:03 volumio-raspi3b sudo[6515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 05:07:03 volumio-raspi3b sudo[6515]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:03 volumio-raspi3b sudo[6517]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:03 volumio-raspi3b sudo[6517]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:03 volumio-raspi3b sudo[6515]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:03 volumio-raspi3b sudo[6517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: Loading plugin "services"... Nov 02 05:07:03 volumio-raspi3b sudo[6517]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: Loading plugin "alsa_controller"... Nov 02 05:07:03 volumio-raspi3b sudo[6517]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:03 volumio-raspi3b sudo[6520]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:03 volumio-raspi3b sudo[6520]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:03 volumio-raspi3b sudo[6520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 02 05:07:03 volumio-raspi3b sudo[6520]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: Loading plugin "wizard"... Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: Loading plugin "networkfs"... Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: Cannot mount NAS E-Home-Media-HighRes at system boot, trial number 1 ,retrying in 5 seconds Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: Starting Udev Watcher for removable devices Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: Ignoring mount for partition: boot Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: Ignoring mount for partition: volumio Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: Ignoring mount for partition: volumio_data Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: Loading plugin "volumio_command_line_client"... Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: Loading plugin "upnp"... Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: [1730520423729] Starting Upmpd Daemon Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: Loading plugin "my_music"... Nov 02 05:07:03 volumio-raspi3b volumio[6486]: info: Loading plugin "mpd"... Nov 02 05:07:04 volumio-raspi3b volumio[6486]: info: Loading plugin "upnp_browser"... Nov 02 05:07:04 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:04] [connect] Successful connection Nov 02 05:07:04 volumio-raspi3b sudo[6520]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:04 volumio-raspi3b volumio[6486]: info: Loading plugin "alarm-clock"... Nov 02 05:07:04 volumio-raspi3b volumio[6486]: info: Loading plugin "airplay_emulation"... Nov 02 05:07:04 volumio-raspi3b volumio[6486]: info: Starting Shairport Sync Nov 02 05:07:04 volumio-raspi3b volumio[6486]: info: Loading plugin "last_100"... Nov 02 05:07:04 volumio-raspi3b volumio[6486]: info: Loading plugin "webradio"... Nov 02 05:07:04 volumio-raspi3b volumio[6486]: info: Loading plugin "i2s_dacs"... Nov 02 05:07:04 volumio-raspi3b volumio[6486]: info: Loading plugin "volumiodiscovery"... Nov 02 05:07:04 volumio-raspi3b volumio[6486]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:04 volumio-raspi3b volumio[6486]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:04 volumio-raspi3b volumio[6486]: *** WARNING *** For more information see Nov 02 05:07:04 volumio-raspi3b node[6486]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:04 volumio-raspi3b volumio[6486]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:04 volumio-raspi3b volumio[6486]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:04 volumio-raspi3b volumio[6486]: *** WARNING *** For more information see Nov 02 05:07:04 volumio-raspi3b node[6486]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:04 volumio-raspi3b node[6486]: *** WARNING *** For more information see Nov 02 05:07:04 volumio-raspi3b node[6486]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:04 volumio-raspi3b node[6486]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:04 volumio-raspi3b node[6486]: *** WARNING *** For more information see Nov 02 05:07:04 volumio-raspi3b volumio[6486]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 05:07:04 volumio-raspi3b volumio[6486]: info: Discovery: Started advertising with name: Volumio-Raspi3b+ Nov 02 05:07:04 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 05:07:04 volumio-raspi3b volumio[6486]: info: Loading plugin "squeezelite_mc"... Nov 02 05:07:04 volumio-raspi3b autossh[1601]: starting ssh (count 14) Nov 02 05:07:04 volumio-raspi3b autossh[1601]: ssh child pid is 6527 Nov 02 05:07:04 volumio-raspi3b volumiossh-tunnel[1598]: ssh: Could not resolve hostname eu8.myvolumio.org: Device or resource busy Nov 02 05:07:04 volumio-raspi3b autossh[1601]: ssh exited with error status 255; restarting ssh Nov 02 05:07:05 volumio-raspi3b volumio[6486]: info: Loading plugin "now_playing"... Nov 02 05:07:07 volumio-raspi3b volumio[6486]: info: Loading plugin "outputs"... Nov 02 05:07:07 volumio-raspi3b volumio[6486]: info: Loading plugin "albumart"... Nov 02 05:07:07 volumio-raspi3b volumio[6486]: info: Plugin example_plugin is not enabled Nov 02 05:07:07 volumio-raspi3b volumio[6486]: info: Loading plugin "inputs"... Nov 02 05:07:07 volumio-raspi3b volumio[6486]: info: Loading plugin "updater_comm"... Nov 02 05:07:07 volumio-raspi3b volumio[6486]: info: Plugin mpdemulation is not enabled Nov 02 05:07:07 volumio-raspi3b volumio[6486]: info: Loading plugin "rest_api"... Nov 02 05:07:07 volumio-raspi3b volumio[6486]: info: Loading plugin "websocket"... Nov 02 05:07:07 volumio-raspi3b volumio[6486]: info: Starting Socket.io Server version 2.3.0 Nov 02 05:07:07 volumio-raspi3b volumio[6486]: info: Plugin fusiondsp is not enabled Nov 02 05:07:07 volumio-raspi3b volumio[6486]: info: Loading plugin "RoonBridge"... Nov 02 05:07:07 volumio-raspi3b volumio[6486]: Forking 3 albumart workers Nov 02 05:07:07 volumio-raspi3b volumio[6486]: info: Applying required configuration parameters for plugin RoonBridge Nov 02 05:07:07 volumio-raspi3b volumio[6486]: info: Loading plugin "minidlna"... Nov 02 05:07:07 volumio-raspi3b volumio[6486]: info: Applying required configuration parameters for plugin minidlna Nov 02 05:07:07 volumio-raspi3b volumio[6486]: info: Loading plugin "backup_restore"... Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: Applying required configuration parameters for plugin backup_restore Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: Plugin mpd_oled is not enabled Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: Plugin Systeminfo is not enabled Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: Plugin lastfm is not enabled Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: Plugin touch_display is not enabled Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: Loading i18n strings for locale de Nov 02 05:07:08 volumio-raspi3b volumio[6486]: Updating browse sources language Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::initPlayerControls Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 05:07:08 volumio-raspi3b volumio[6486]: Express server listening on port 3000 Nov 02 05:07:08 volumio-raspi3b volumio[6486]: [Metrics] WebUI: 7s 585.07ms Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreStateMachine::resetVolumioState Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreStateMachine::getcurrentVolume Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 05:07:08 volumio-raspi3b volumio[6486]: Starting albumart workers Nov 02 05:07:08 volumio-raspi3b volumio[6486]: Starting albumart workers Nov 02 05:07:08 volumio-raspi3b volumio[6486]: Starting albumart workers Nov 02 05:07:08 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1730520424 101 Nov 02 05:07:08 volumio-raspi3b volumio[6486]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreStateMachine::pushState Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreStateMachine::updateTrackBlock Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CorePlayQueue::getTrackBlock Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: Cannot mount NAS E-Home-Media-HighRes at system boot, trial number 2 ,retrying in 5 seconds Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: Reloading queue from file Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreStateMachine::setRepeat null single undefined Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreStateMachine::pushState Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreStateMachine::setRandom null Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreStateMachine::pushState Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: Setting Device type: Raspberry PI Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreStateMachine::pushState Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: Completed loading Core Plugins Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: Preparing to generate the ALSA configuration file Nov 02 05:07:08 volumio-raspi3b volumio[6486]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: Reading ALSA contributions from plugins. Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: Asound.conf file unchanged, so no further update is needed Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: Output device has changed, restarting MPD Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: Output device has changed, restarting Shairport Sync Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:09 volumio-raspi3b sudo[6577]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:09 volumio-raspi3b sudo[6577]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:09 volumio-raspi3b sudo[6577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 05:07:09 volumio-raspi3b sudo[6577]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:09 volumio-raspi3b sudo[6578]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:09 volumio-raspi3b sudo[6578]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: ___________ START PLUGINS ___________ Nov 02 05:07:09 volumio-raspi3b sudo[6578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 05:07:09 volumio-raspi3b sudo[6578]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:09 volumio-raspi3b sudo[6577]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: ControllerMpd::onStart: Initializing MPD Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: Creating MPD Configuration file Nov 02 05:07:09 volumio-raspi3b systemd[1]: Stopping Music Player Daemon... Nov 02 05:07:09 volumio-raspi3b sudo[6586]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:09 volumio-raspi3b sudo[6586]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:09 volumio-raspi3b sudo[6586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 05:07:09 volumio-raspi3b sudo[6586]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: [1730520429256] CoreMusicLibrary::Adding element Medienserver Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:09 volumio-raspi3b systemd[1]: mpd.service: Succeeded. Nov 02 05:07:09 volumio-raspi3b systemd[1]: Stopped Music Player Daemon. Nov 02 05:07:09 volumio-raspi3b sudo[6586]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:09 volumio-raspi3b sudo[6588]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:09 volumio-raspi3b sudo[6588]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:09 volumio-raspi3b systemd[1]: Starting Music Player Daemon... Nov 02 05:07:09 volumio-raspi3b sudo[6588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 05:07:09 volumio-raspi3b sudo[6588]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:09 volumio-raspi3b systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 02 05:07:09 volumio-raspi3b systemd[1]: mpd.service: Succeeded. Nov 02 05:07:09 volumio-raspi3b systemd[1]: Stopped Music Player Daemon. Nov 02 05:07:09 volumio-raspi3b systemd[1]: Starting Music Player Daemon... Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: [1730520429495] CoreMusicLibrary::Adding element Last_100 Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: [1730520429500] CoreMusicLibrary::Adding element Webradio Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: Initializing BBC Radios Nov 02 05:07:09 volumio-raspi3b sudo[6594]: root : unable to resolve host volumio-raspi3b Nov 02 05:07:09 volumio-raspi3b sudo[6594]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:09 volumio-raspi3b sudo[6594]: sudo: unable to resolve host volumio-raspi3b: System error Nov 02 05:07:09 volumio-raspi3b sudo[6594]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 05:07:09 volumio-raspi3b sudo[6594]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:09 volumio-raspi3b sudo[6594]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: [squeezelite_mc] Starting proxy server... Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: [now-playing] ConfigUpdater: config is up to date. Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: Loading i18n strings for locale de Nov 02 05:07:09 volumio-raspi3b sudo[6635]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:09 volumio-raspi3b sudo[6635]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:09 volumio-raspi3b sudo[6635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Nov 02 05:07:09 volumio-raspi3b sudo[6635]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: Volumio Calling Home Nov 02 05:07:09 volumio-raspi3b sudo[6635]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:09 volumio-raspi3b volumio[6486]: info: [squeezelite_mc] Proxy server started on port 34711 Nov 02 05:07:10 volumio-raspi3b volumio[6486]: info: [now-playing] Fetching API key... Nov 02 05:07:10 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 05:07:10 volumio-raspi3b volumio[6486]: info: [now-playing] App is listening on port 4004. Nov 02 05:07:10 volumio-raspi3b volumio[6486]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Nov 02 05:07:10 volumio-raspi3b volumio[6486]: Unhandled rejection Error: No sockets available, cannot start. Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: error: [now-playing] Error fetching https://openweathermap.org: FetchError: request to https://openweathermap.org/ failed, reason: getaddrinfo EBUSY openweathermap.org Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at ClientRequest. (/data/plugins/user_interface/now_playing/node_modules/node-fetch/lib/index.js:1505:11) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at ClientRequest.emit (events.js:315:20) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at TLSSocket.emit (events.js:315:20) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at emitErrorNT (internal/streams/destroy.js:106:8) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: (node:6486) UnhandledPromiseRejectionWarning: FetchError: request to https://openweathermap.org/ failed, reason: getaddrinfo EBUSY openweathermap.org Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at ClientRequest. (/data/plugins/user_interface/now_playing/node_modules/node-fetch/lib/index.js:1505:11) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at ClientRequest.emit (events.js:315:20) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at TLSSocket.emit (events.js:315:20) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at emitErrorNT (internal/streams/destroy.js:106:8) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: (Use `node --trace-warnings ...` to show where the warning was created) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: (node:6486) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 4) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: (node:6486) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Nov 02 05:07:10 volumio-raspi3b volumio[6486]: info: CoreStateMachine::pushState Nov 02 05:07:10 volumio-raspi3b volumio[6486]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:10 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:10 volumio-raspi3b volumio[6486]: info: [squeezelite_mc] Server discovery started Nov 02 05:07:10 volumio-raspi3b volumio[6486]: info: [squeezelite_mc] Player finder started Nov 02 05:07:10 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:10 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:10 volumio-raspi3b volumio[6486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:10 volumio-raspi3b volumio[6486]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 02 05:07:10 volumio-raspi3b volumio[6486]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 05:07:10 volumio-raspi3b volumio[6486]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at doSend (dgram.js:692:16) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at afterDns (dgram.js:638:5) Nov 02 05:07:10 volumio-raspi3b volumio[6486]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 02 05:07:10 volumio-raspi3b volumio[6486]: errno: -101, Nov 02 05:07:10 volumio-raspi3b volumio[6486]: code: 'ENETUNREACH', Nov 02 05:07:10 volumio-raspi3b volumio[6486]: syscall: 'send', Nov 02 05:07:10 volumio-raspi3b volumio[6486]: address: '255.255.255.255', Nov 02 05:07:10 volumio-raspi3b volumio[6486]: port: 3483 Nov 02 05:07:10 volumio-raspi3b volumio[6486]: } Nov 02 05:07:10 volumio-raspi3b volumio[6486]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 05:07:10 volumio-raspi3b sudo[6659]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:10 volumio-raspi3b sudo[6659]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:10 volumio-raspi3b sudo[6659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 05:06 Nov 02 05:07:10 volumio-raspi3b sudo[6659]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:11 volumio-raspi3b sudo[6659]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:11 volumio-raspi3b volumio[6486]: sudo: unable to resolve host volumio-raspi3b: System error Nov 02 05:07:11 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:11] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 02 05:07:11 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:11] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 02 05:07:11 volumio-raspi3b systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:07:11 volumio-raspi3b sudo[6588]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:11 volumio-raspi3b sudo[6578]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:11 volumio-raspi3b systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 02 05:07:11 volumio-raspi3b systemd[1]: Started dynamicswap service. Nov 02 05:07:11 volumio-raspi3b mpd[6629]: Nov 02 05:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 05:07:11 volumio-raspi3b systemd[1]: dynamicswap.service: Succeeded. Nov 02 05:07:11 volumio-raspi3b systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 05:07:11 volumio-raspi3b systemd[1]: volumio.service: Scheduled restart job, restart counter is at 34. Nov 02 05:07:11 volumio-raspi3b systemd[1]: Stopped Volumio Backend Module. Nov 02 05:07:11 volumio-raspi3b systemd[1]: Started Volumio Backend Module. Nov 02 05:07:11 volumio-raspi3b systemd[1]: Started dynamicswap service. Nov 02 05:07:11 volumio-raspi3b systemd[1]: Started Music Player Daemon. Nov 02 05:07:11 volumio-raspi3b systemd[1]: dynamicswap.service: Succeeded. Nov 02 05:07:13 volumio-raspi3b volumio[6714]: info: ------------------------------------------- Nov 02 05:07:13 volumio-raspi3b volumio[6714]: info: ----- Volumio3 ---- Nov 02 05:07:13 volumio-raspi3b volumio[6714]: info: ------------------------------------------- Nov 02 05:07:13 volumio-raspi3b volumio[6714]: info: ----- System startup ---- Nov 02 05:07:13 volumio-raspi3b volumio[6714]: info: ------------------------------------------- Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: MYVOLUMIO Environment detected Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Plugin folders cleanup Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Scanning into folder /volumio/app/plugins/ Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Scanning category audio_interface Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Scanning category miscellanea Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Scanning category music_service Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Scanning category plugins.json Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Scanning category system_controller Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Scanning category user_interface Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Scanning into folder /data/plugins/ Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Scanning category audio_interface Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Scanning category music_service Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Scanning category system_controller Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Scanning category system_hardware Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Scanning category user_interface Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Plugin folders cleanup completed Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: ------------------------------------------- Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: ----- Core plugins startup ---- Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: ------------------------------------------- Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Loading plugins from folder /volumio/app/plugins/ Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Adding plugin upnp to MyMusic Plugins Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Loading plugins from folder /data/plugins/ Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Loading plugin "system"... Nov 02 05:07:14 volumio-raspi3b volumio[6714]: info: Loading plugin "appearance"... Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: Loading plugin "network"... Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: Refreshing Cached IP Addresses Nov 02 05:07:15 volumio-raspi3b sudo[6743]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:15 volumio-raspi3b sudo[6743]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:15 volumio-raspi3b sudo[6743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 05:07:15 volumio-raspi3b sudo[6743]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:15 volumio-raspi3b sudo[6745]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:15 volumio-raspi3b sudo[6743]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:15 volumio-raspi3b sudo[6745]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: Loading plugin "services"... Nov 02 05:07:15 volumio-raspi3b sudo[6745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 05:07:15 volumio-raspi3b sudo[6745]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: Loading plugin "alsa_controller"... Nov 02 05:07:15 volumio-raspi3b sudo[6745]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:15 volumio-raspi3b sudo[6748]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:15 volumio-raspi3b sudo[6748]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:15 volumio-raspi3b sudo[6748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 02 05:07:15 volumio-raspi3b sudo[6748]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: Loading plugin "wizard"... Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: Loading plugin "networkfs"... Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: Cannot mount NAS E-Home-Media-HighRes at system boot, trial number 1 ,retrying in 5 seconds Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: Starting Udev Watcher for removable devices Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: Ignoring mount for partition: boot Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: Ignoring mount for partition: volumio Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: Ignoring mount for partition: volumio_data Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: Loading plugin "volumio_command_line_client"... Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: Loading plugin "upnp"... Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: [1730520435685] Starting Upmpd Daemon Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: Loading plugin "my_music"... Nov 02 05:07:15 volumio-raspi3b volumio[6714]: info: Loading plugin "mpd"... Nov 02 05:07:16 volumio-raspi3b volumio[6714]: info: Loading plugin "upnp_browser"... Nov 02 05:07:16 volumio-raspi3b sudo[6748]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:16 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:16] [connect] Successful connection Nov 02 05:07:16 volumio-raspi3b volumio[6714]: info: Loading plugin "alarm-clock"... Nov 02 05:07:16 volumio-raspi3b volumio[6714]: info: Loading plugin "airplay_emulation"... Nov 02 05:07:16 volumio-raspi3b volumio[6714]: info: Starting Shairport Sync Nov 02 05:07:16 volumio-raspi3b volumio[6714]: info: Loading plugin "last_100"... Nov 02 05:07:16 volumio-raspi3b volumio[6714]: info: Loading plugin "webradio"... Nov 02 05:07:16 volumio-raspi3b volumio[6714]: info: Loading plugin "i2s_dacs"... Nov 02 05:07:16 volumio-raspi3b volumio[6714]: info: Loading plugin "volumiodiscovery"... Nov 02 05:07:16 volumio-raspi3b volumio[6714]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:16 volumio-raspi3b volumio[6714]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:16 volumio-raspi3b volumio[6714]: *** WARNING *** For more information see Nov 02 05:07:16 volumio-raspi3b node[6714]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:16 volumio-raspi3b volumio[6714]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:16 volumio-raspi3b volumio[6714]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:16 volumio-raspi3b volumio[6714]: *** WARNING *** For more information see Nov 02 05:07:16 volumio-raspi3b node[6714]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:16 volumio-raspi3b node[6714]: *** WARNING *** For more information see Nov 02 05:07:16 volumio-raspi3b node[6714]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:16 volumio-raspi3b node[6714]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:16 volumio-raspi3b node[6714]: *** WARNING *** For more information see Nov 02 05:07:16 volumio-raspi3b volumio[6714]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 05:07:16 volumio-raspi3b volumio[6714]: info: Discovery: Started advertising with name: Volumio-Raspi3b+ Nov 02 05:07:16 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 05:07:16 volumio-raspi3b volumio[6714]: info: Loading plugin "squeezelite_mc"... Nov 02 05:07:17 volumio-raspi3b volumio[6714]: info: Loading plugin "now_playing"... Nov 02 05:07:18 volumio-raspi3b volumio[6714]: info: Loading plugin "outputs"... Nov 02 05:07:18 volumio-raspi3b volumio[6714]: info: Loading plugin "albumart"... Nov 02 05:07:18 volumio-raspi3b volumio[6714]: info: Plugin example_plugin is not enabled Nov 02 05:07:18 volumio-raspi3b volumio[6714]: info: Loading plugin "inputs"... Nov 02 05:07:18 volumio-raspi3b volumio[6714]: info: Loading plugin "updater_comm"... Nov 02 05:07:19 volumio-raspi3b volumio[6714]: info: Plugin mpdemulation is not enabled Nov 02 05:07:19 volumio-raspi3b volumio[6714]: info: Loading plugin "rest_api"... Nov 02 05:07:19 volumio-raspi3b volumio[6714]: info: Loading plugin "websocket"... Nov 02 05:07:19 volumio-raspi3b volumio[6714]: info: Starting Socket.io Server version 2.3.0 Nov 02 05:07:19 volumio-raspi3b volumio[6714]: info: Plugin fusiondsp is not enabled Nov 02 05:07:19 volumio-raspi3b volumio[6714]: info: Loading plugin "RoonBridge"... Nov 02 05:07:19 volumio-raspi3b volumio[6714]: Forking 3 albumart workers Nov 02 05:07:19 volumio-raspi3b volumio[6714]: info: Applying required configuration parameters for plugin RoonBridge Nov 02 05:07:19 volumio-raspi3b volumio[6714]: info: Loading plugin "minidlna"... Nov 02 05:07:19 volumio-raspi3b volumio[6714]: info: Applying required configuration parameters for plugin minidlna Nov 02 05:07:19 volumio-raspi3b volumio[6714]: info: Loading plugin "backup_restore"... Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: Applying required configuration parameters for plugin backup_restore Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: Plugin mpd_oled is not enabled Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: Plugin Systeminfo is not enabled Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: Plugin lastfm is not enabled Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: Plugin touch_display is not enabled Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: Loading i18n strings for locale de Nov 02 05:07:20 volumio-raspi3b volumio[6714]: Updating browse sources language Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::initPlayerControls Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 05:07:20 volumio-raspi3b volumio[6714]: Express server listening on port 3000 Nov 02 05:07:20 volumio-raspi3b volumio[6714]: [Metrics] WebUI: 7s 467.66ms Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreStateMachine::resetVolumioState Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreStateMachine::getcurrentVolume Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 05:07:20 volumio-raspi3b volumio[6714]: Starting albumart workers Nov 02 05:07:20 volumio-raspi3b volumio[6714]: Starting albumart workers Nov 02 05:07:20 volumio-raspi3b volumio[6714]: Starting albumart workers Nov 02 05:07:20 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1730520436 101 Nov 02 05:07:20 volumio-raspi3b volumio[6714]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreStateMachine::pushState Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreStateMachine::updateTrackBlock Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CorePlayQueue::getTrackBlock Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: Cannot mount NAS E-Home-Media-HighRes at system boot, trial number 2 ,retrying in 5 seconds Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: Reloading queue from file Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreStateMachine::setRepeat null single undefined Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreStateMachine::pushState Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreStateMachine::setRandom null Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreStateMachine::pushState Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: Setting Device type: Raspberry PI Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreStateMachine::pushState Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: Completed loading Core Plugins Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: Preparing to generate the ALSA configuration file Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: Reading ALSA contributions from plugins. Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: Asound.conf file unchanged, so no further update is needed Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: Output device has changed, restarting MPD Nov 02 05:07:20 volumio-raspi3b sudo[6804]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:20 volumio-raspi3b sudo[6804]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: Output device has changed, restarting Shairport Sync Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:20 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:21 volumio-raspi3b sudo[6804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 05:07:21 volumio-raspi3b sudo[6804]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:21 volumio-raspi3b sudo[6804]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:21 volumio-raspi3b sudo[6805]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:21 volumio-raspi3b sudo[6805]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:21 volumio-raspi3b sudo[6805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 05:07:21 volumio-raspi3b sudo[6805]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: ___________ START PLUGINS ___________ Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: ControllerMpd::onStart: Initializing MPD Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: Creating MPD Configuration file Nov 02 05:07:21 volumio-raspi3b systemd[1]: Stopping Music Player Daemon... Nov 02 05:07:21 volumio-raspi3b systemd[1]: mpd.service: Succeeded. Nov 02 05:07:21 volumio-raspi3b systemd[1]: Stopped Music Player Daemon. Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: [1730520441208] CoreMusicLibrary::Adding element Medienserver Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:21 volumio-raspi3b systemd[1]: Starting Music Player Daemon... Nov 02 05:07:21 volumio-raspi3b sudo[6823]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:21 volumio-raspi3b sudo[6823]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:21 volumio-raspi3b sudo[6823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 05:07:21 volumio-raspi3b sudo[6822]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:21 volumio-raspi3b sudo[6822]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:21 volumio-raspi3b sudo[6822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 05:07:21 volumio-raspi3b sudo[6823]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:21 volumio-raspi3b sudo[6822]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:21 volumio-raspi3b sudo[6822]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:21 volumio-raspi3b systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 02 05:07:21 volumio-raspi3b systemd[1]: mpd.service: Succeeded. Nov 02 05:07:21 volumio-raspi3b systemd[1]: Stopped Music Player Daemon. Nov 02 05:07:21 volumio-raspi3b systemd[1]: Starting Music Player Daemon... Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:21 volumio-raspi3b sudo[6826]: root : unable to resolve host volumio-raspi3b Nov 02 05:07:21 volumio-raspi3b sudo[6826]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:21 volumio-raspi3b sudo[6826]: sudo: unable to resolve host volumio-raspi3b: System error Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 05:07:21 volumio-raspi3b sudo[6826]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 05:07:21 volumio-raspi3b sudo[6826]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: [1730520441504] CoreMusicLibrary::Adding element Last_100 Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 05:07:21 volumio-raspi3b sudo[6826]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: [1730520441512] CoreMusicLibrary::Adding element Webradio Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: Initializing BBC Radios Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: [squeezelite_mc] Starting proxy server... Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: [now-playing] ConfigUpdater: config is up to date. Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: Loading i18n strings for locale de Nov 02 05:07:21 volumio-raspi3b sudo[6862]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:21 volumio-raspi3b sudo[6862]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:21 volumio-raspi3b sudo[6862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Nov 02 05:07:21 volumio-raspi3b sudo[6862]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:21 volumio-raspi3b sudo[6862]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: Volumio Calling Home Nov 02 05:07:21 volumio-raspi3b volumio[6714]: info: [squeezelite_mc] Proxy server started on port 34317 Nov 02 05:07:22 volumio-raspi3b volumio[6714]: info: [now-playing] Fetching API key... Nov 02 05:07:22 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 05:07:22 volumio-raspi3b volumio[6714]: info: [now-playing] App is listening on port 4004. Nov 02 05:07:22 volumio-raspi3b volumio[6714]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Nov 02 05:07:22 volumio-raspi3b volumio[6714]: Unhandled rejection Error: No sockets available, cannot start. Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: error: [now-playing] Error fetching https://openweathermap.org: FetchError: request to https://openweathermap.org/ failed, reason: getaddrinfo EBUSY openweathermap.org Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at ClientRequest. (/data/plugins/user_interface/now_playing/node_modules/node-fetch/lib/index.js:1505:11) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at ClientRequest.emit (events.js:315:20) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at TLSSocket.emit (events.js:315:20) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at emitErrorNT (internal/streams/destroy.js:106:8) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: (node:6714) UnhandledPromiseRejectionWarning: FetchError: request to https://openweathermap.org/ failed, reason: getaddrinfo EBUSY openweathermap.org Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at ClientRequest. (/data/plugins/user_interface/now_playing/node_modules/node-fetch/lib/index.js:1505:11) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at ClientRequest.emit (events.js:315:20) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at TLSSocket.emit (events.js:315:20) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at emitErrorNT (internal/streams/destroy.js:106:8) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: (Use `node --trace-warnings ...` to show where the warning was created) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: (node:6714) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 4) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: (node:6714) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Nov 02 05:07:22 volumio-raspi3b volumio[6714]: info: CoreStateMachine::pushState Nov 02 05:07:22 volumio-raspi3b volumio[6714]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:22 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:22 volumio-raspi3b volumio[6714]: info: [squeezelite_mc] Server discovery started Nov 02 05:07:22 volumio-raspi3b volumio[6714]: info: [squeezelite_mc] Player finder started Nov 02 05:07:22 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:22 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:22 volumio-raspi3b volumio[6714]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:22 volumio-raspi3b volumio[6714]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 02 05:07:22 volumio-raspi3b volumio[6714]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 05:07:22 volumio-raspi3b volumio[6714]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at doSend (dgram.js:692:16) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at afterDns (dgram.js:638:5) Nov 02 05:07:22 volumio-raspi3b volumio[6714]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 02 05:07:22 volumio-raspi3b volumio[6714]: errno: -101, Nov 02 05:07:22 volumio-raspi3b volumio[6714]: code: 'ENETUNREACH', Nov 02 05:07:22 volumio-raspi3b volumio[6714]: syscall: 'send', Nov 02 05:07:22 volumio-raspi3b volumio[6714]: address: '255.255.255.255', Nov 02 05:07:22 volumio-raspi3b volumio[6714]: port: 3483 Nov 02 05:07:22 volumio-raspi3b volumio[6714]: } Nov 02 05:07:22 volumio-raspi3b volumio[6714]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 05:07:22 volumio-raspi3b sudo[6886]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:22 volumio-raspi3b sudo[6886]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:22 volumio-raspi3b sudo[6886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 05:06 Nov 02 05:07:22 volumio-raspi3b sudo[6886]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:23 volumio-raspi3b sudo[6886]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:23 volumio-raspi3b volumio[6714]: sudo: unable to resolve host volumio-raspi3b: System error Nov 02 05:07:23 volumio-raspi3b mpd[6850]: Nov 02 05:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 05:07:23 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 02 05:07:23 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 02 05:07:23 volumio-raspi3b systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:07:23 volumio-raspi3b sudo[6805]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:23 volumio-raspi3b sudo[6823]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:23 volumio-raspi3b systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 02 05:07:23 volumio-raspi3b systemd[1]: Started dynamicswap service. Nov 02 05:07:23 volumio-raspi3b systemd[1]: dynamicswap.service: Succeeded. Nov 02 05:07:23 volumio-raspi3b systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 05:07:23 volumio-raspi3b systemd[1]: volumio.service: Scheduled restart job, restart counter is at 35. Nov 02 05:07:23 volumio-raspi3b systemd[1]: Started Music Player Daemon. Nov 02 05:07:23 volumio-raspi3b systemd[1]: Started dynamicswap service. Nov 02 05:07:23 volumio-raspi3b systemd[1]: Stopped Volumio Backend Module. Nov 02 05:07:23 volumio-raspi3b systemd[1]: Started Volumio Backend Module. Nov 02 05:07:23 volumio-raspi3b systemd[1]: dynamicswap.service: Succeeded. Nov 02 05:07:25 volumio-raspi3b volumio[6903]: info: ------------------------------------------- Nov 02 05:07:25 volumio-raspi3b volumio[6903]: info: ----- Volumio3 ---- Nov 02 05:07:25 volumio-raspi3b volumio[6903]: info: ------------------------------------------- Nov 02 05:07:25 volumio-raspi3b volumio[6903]: info: ----- System startup ---- Nov 02 05:07:25 volumio-raspi3b volumio[6903]: info: ------------------------------------------- Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: MYVOLUMIO Environment detected Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Plugin folders cleanup Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Scanning into folder /volumio/app/plugins/ Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Scanning category audio_interface Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Scanning category miscellanea Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Scanning category music_service Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Scanning category plugins.json Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Scanning category system_controller Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Scanning category user_interface Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Scanning into folder /data/plugins/ Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Scanning category audio_interface Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Scanning category music_service Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Scanning category system_controller Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Scanning category system_hardware Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Scanning category user_interface Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Plugin folders cleanup completed Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: ------------------------------------------- Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: ----- Core plugins startup ---- Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: ------------------------------------------- Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Loading plugins from folder /volumio/app/plugins/ Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Adding plugin upnp to MyMusic Plugins Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Loading plugins from folder /data/plugins/ Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Loading plugin "system"... Nov 02 05:07:26 volumio-raspi3b volumio[6903]: info: Loading plugin "appearance"... Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: Loading plugin "network"... Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: Refreshing Cached IP Addresses Nov 02 05:07:27 volumio-raspi3b sudo[6929]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:27 volumio-raspi3b sudo[6929]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:27 volumio-raspi3b sudo[6929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 05:07:27 volumio-raspi3b sudo[6929]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:27 volumio-raspi3b sudo[6931]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:27 volumio-raspi3b sudo[6931]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: Loading plugin "services"... Nov 02 05:07:27 volumio-raspi3b sudo[6931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 05:07:27 volumio-raspi3b sudo[6931]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: Loading plugin "alsa_controller"... Nov 02 05:07:27 volumio-raspi3b sudo[6929]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:27 volumio-raspi3b sudo[6931]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:27 volumio-raspi3b sudo[6933]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:27 volumio-raspi3b sudo[6933]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:27 volumio-raspi3b sudo[6933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 02 05:07:27 volumio-raspi3b sudo[6933]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: Loading plugin "wizard"... Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: Loading plugin "networkfs"... Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: Cannot mount NAS E-Home-Media-HighRes at system boot, trial number 1 ,retrying in 5 seconds Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: Starting Udev Watcher for removable devices Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: Ignoring mount for partition: boot Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: Ignoring mount for partition: volumio Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: Ignoring mount for partition: volumio_data Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: Loading plugin "volumio_command_line_client"... Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: Loading plugin "upnp"... Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: [1730520447653] Starting Upmpd Daemon Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: Loading plugin "my_music"... Nov 02 05:07:27 volumio-raspi3b volumio[6903]: info: Loading plugin "mpd"... Nov 02 05:07:28 volumio-raspi3b volumio[6903]: info: Loading plugin "upnp_browser"... Nov 02 05:07:28 volumio-raspi3b sudo[6933]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:28 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:28] [connect] Successful connection Nov 02 05:07:28 volumio-raspi3b volumio[6903]: info: Loading plugin "alarm-clock"... Nov 02 05:07:28 volumio-raspi3b volumio[6903]: info: Loading plugin "airplay_emulation"... Nov 02 05:07:28 volumio-raspi3b volumio[6903]: info: Starting Shairport Sync Nov 02 05:07:28 volumio-raspi3b volumio[6903]: info: Loading plugin "last_100"... Nov 02 05:07:28 volumio-raspi3b volumio[6903]: info: Loading plugin "webradio"... Nov 02 05:07:28 volumio-raspi3b volumio[6903]: info: Loading plugin "i2s_dacs"... Nov 02 05:07:28 volumio-raspi3b volumio[6903]: info: Loading plugin "volumiodiscovery"... Nov 02 05:07:28 volumio-raspi3b volumio[6903]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:28 volumio-raspi3b volumio[6903]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:28 volumio-raspi3b volumio[6903]: *** WARNING *** For more information see Nov 02 05:07:28 volumio-raspi3b node[6903]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:28 volumio-raspi3b volumio[6903]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:28 volumio-raspi3b volumio[6903]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:28 volumio-raspi3b volumio[6903]: *** WARNING *** For more information see Nov 02 05:07:28 volumio-raspi3b node[6903]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:28 volumio-raspi3b node[6903]: *** WARNING *** For more information see Nov 02 05:07:28 volumio-raspi3b node[6903]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:28 volumio-raspi3b node[6903]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:28 volumio-raspi3b node[6903]: *** WARNING *** For more information see Nov 02 05:07:28 volumio-raspi3b volumio[6903]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 05:07:28 volumio-raspi3b volumio[6903]: info: Discovery: Started advertising with name: Volumio-Raspi3b+ Nov 02 05:07:28 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 05:07:28 volumio-raspi3b volumio[6903]: info: Loading plugin "squeezelite_mc"... Nov 02 05:07:29 volumio-raspi3b volumio[6903]: info: Loading plugin "now_playing"... Nov 02 05:07:30 volumio-raspi3b volumio[6903]: info: Loading plugin "outputs"... Nov 02 05:07:30 volumio-raspi3b volumio[6903]: info: Loading plugin "albumart"... Nov 02 05:07:31 volumio-raspi3b volumio[6903]: info: Plugin example_plugin is not enabled Nov 02 05:07:31 volumio-raspi3b volumio[6903]: info: Loading plugin "inputs"... Nov 02 05:07:31 volumio-raspi3b volumio[6903]: info: Loading plugin "updater_comm"... Nov 02 05:07:31 volumio-raspi3b volumio[6903]: info: Plugin mpdemulation is not enabled Nov 02 05:07:31 volumio-raspi3b volumio[6903]: info: Loading plugin "rest_api"... Nov 02 05:07:31 volumio-raspi3b volumio[6903]: info: Loading plugin "websocket"... Nov 02 05:07:31 volumio-raspi3b volumio[6903]: info: Starting Socket.io Server version 2.3.0 Nov 02 05:07:31 volumio-raspi3b volumio[6903]: info: Plugin fusiondsp is not enabled Nov 02 05:07:31 volumio-raspi3b volumio[6903]: info: Loading plugin "RoonBridge"... Nov 02 05:07:31 volumio-raspi3b volumio[6903]: Forking 3 albumart workers Nov 02 05:07:31 volumio-raspi3b volumio[6903]: info: Applying required configuration parameters for plugin RoonBridge Nov 02 05:07:31 volumio-raspi3b volumio[6903]: info: Loading plugin "minidlna"... Nov 02 05:07:31 volumio-raspi3b volumio[6903]: info: Applying required configuration parameters for plugin minidlna Nov 02 05:07:31 volumio-raspi3b volumio[6903]: info: Loading plugin "backup_restore"... Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: Applying required configuration parameters for plugin backup_restore Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: Plugin mpd_oled is not enabled Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: Plugin Systeminfo is not enabled Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: Plugin lastfm is not enabled Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: Plugin touch_display is not enabled Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: Loading i18n strings for locale de Nov 02 05:07:32 volumio-raspi3b volumio[6903]: Updating browse sources language Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::initPlayerControls Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 05:07:32 volumio-raspi3b volumio[6903]: Express server listening on port 3000 Nov 02 05:07:32 volumio-raspi3b volumio[6903]: [Metrics] WebUI: 7s 542.77ms Nov 02 05:07:32 volumio-raspi3b volumio[6903]: Starting albumart workers Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreStateMachine::resetVolumioState Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreStateMachine::getcurrentVolume Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 05:07:32 volumio-raspi3b volumio[6903]: Starting albumart workers Nov 02 05:07:32 volumio-raspi3b volumio[6903]: Starting albumart workers Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: Cannot mount NAS E-Home-Media-HighRes at system boot, trial number 2 ,retrying in 5 seconds Nov 02 05:07:32 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1730520448 101 Nov 02 05:07:32 volumio-raspi3b volumio[6903]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreStateMachine::pushState Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreStateMachine::updateTrackBlock Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CorePlayQueue::getTrackBlock Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: Reloading queue from file Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreStateMachine::setRepeat null single undefined Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreStateMachine::pushState Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreStateMachine::setRandom null Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreStateMachine::pushState Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: Setting Device type: Raspberry PI Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: Completed loading Core Plugins Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: Preparing to generate the ALSA configuration file Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreStateMachine::pushState Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: Reading ALSA contributions from plugins. Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: Asound.conf file unchanged, so no further update is needed Nov 02 05:07:32 volumio-raspi3b volumio[6903]: info: Output device has changed, restarting MPD Nov 02 05:07:33 volumio-raspi3b sudo[6990]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:33 volumio-raspi3b sudo[6990]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: Output device has changed, restarting Shairport Sync Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:33 volumio-raspi3b sudo[6990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 05:07:33 volumio-raspi3b sudo[6990]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:33 volumio-raspi3b sudo[6990]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:33 volumio-raspi3b sudo[6991]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:33 volumio-raspi3b sudo[6991]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:33 volumio-raspi3b sudo[6991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 05:07:33 volumio-raspi3b sudo[6991]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: ___________ START PLUGINS ___________ Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: ControllerMpd::onStart: Initializing MPD Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: Creating MPD Configuration file Nov 02 05:07:33 volumio-raspi3b systemd[1]: Stopping Music Player Daemon... Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: [1730520453211] CoreMusicLibrary::Adding element Medienserver Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:33 volumio-raspi3b systemd[1]: mpd.service: Succeeded. Nov 02 05:07:33 volumio-raspi3b systemd[1]: Stopped Music Player Daemon. Nov 02 05:07:33 volumio-raspi3b sudo[6999]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:33 volumio-raspi3b sudo[6999]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:33 volumio-raspi3b sudo[6999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 05:07:33 volumio-raspi3b systemd[1]: Starting Music Player Daemon... Nov 02 05:07:33 volumio-raspi3b sudo[6999]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:33 volumio-raspi3b sudo[7004]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:33 volumio-raspi3b sudo[7004]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:33 volumio-raspi3b sudo[7004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 05:07:33 volumio-raspi3b sudo[7004]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:33 volumio-raspi3b sudo[6999]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:33 volumio-raspi3b systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 02 05:07:33 volumio-raspi3b systemd[1]: mpd.service: Succeeded. Nov 02 05:07:33 volumio-raspi3b systemd[1]: Stopped Music Player Daemon. Nov 02 05:07:33 volumio-raspi3b systemd[1]: Starting Music Player Daemon... Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: [1730520453503] CoreMusicLibrary::Adding element Last_100 Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: [1730520453508] CoreMusicLibrary::Adding element Webradio Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: Initializing BBC Radios Nov 02 05:07:33 volumio-raspi3b sudo[7015]: root : unable to resolve host volumio-raspi3b Nov 02 05:07:33 volumio-raspi3b sudo[7015]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:33 volumio-raspi3b sudo[7015]: sudo: unable to resolve host volumio-raspi3b: System error Nov 02 05:07:33 volumio-raspi3b sudo[7015]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 05:07:33 volumio-raspi3b sudo[7015]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:33 volumio-raspi3b sudo[7015]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: [squeezelite_mc] Starting proxy server... Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: [now-playing] ConfigUpdater: config is up to date. Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: Loading i18n strings for locale de Nov 02 05:07:33 volumio-raspi3b sudo[7048]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:33 volumio-raspi3b sudo[7048]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:33 volumio-raspi3b sudo[7048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Nov 02 05:07:33 volumio-raspi3b sudo[7048]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: Volumio Calling Home Nov 02 05:07:33 volumio-raspi3b sudo[7048]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:33 volumio-raspi3b volumio[6903]: info: [squeezelite_mc] Proxy server started on port 46373 Nov 02 05:07:34 volumio-raspi3b volumio[6903]: info: [now-playing] Fetching API key... Nov 02 05:07:34 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 05:07:34 volumio-raspi3b volumio[6903]: info: [now-playing] App is listening on port 4004. Nov 02 05:07:34 volumio-raspi3b volumio[6903]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Nov 02 05:07:34 volumio-raspi3b volumio[6903]: Unhandled rejection Error: No sockets available, cannot start. Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: error: [now-playing] Error fetching https://openweathermap.org: FetchError: request to https://openweathermap.org/ failed, reason: getaddrinfo EBUSY openweathermap.org Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at ClientRequest. (/data/plugins/user_interface/now_playing/node_modules/node-fetch/lib/index.js:1505:11) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at ClientRequest.emit (events.js:315:20) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at TLSSocket.emit (events.js:315:20) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at emitErrorNT (internal/streams/destroy.js:106:8) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: (node:6903) UnhandledPromiseRejectionWarning: FetchError: request to https://openweathermap.org/ failed, reason: getaddrinfo EBUSY openweathermap.org Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at ClientRequest. (/data/plugins/user_interface/now_playing/node_modules/node-fetch/lib/index.js:1505:11) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at ClientRequest.emit (events.js:315:20) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at TLSSocket.emit (events.js:315:20) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at emitErrorNT (internal/streams/destroy.js:106:8) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: (Use `node --trace-warnings ...` to show where the warning was created) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: (node:6903) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 4) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: (node:6903) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Nov 02 05:07:34 volumio-raspi3b volumio[6903]: info: CoreStateMachine::pushState Nov 02 05:07:34 volumio-raspi3b volumio[6903]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:34 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:34 volumio-raspi3b volumio[6903]: info: [squeezelite_mc] Server discovery started Nov 02 05:07:34 volumio-raspi3b volumio[6903]: info: [squeezelite_mc] Player finder started Nov 02 05:07:34 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:34 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:34 volumio-raspi3b volumio[6903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:34 volumio-raspi3b volumio[6903]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 02 05:07:34 volumio-raspi3b volumio[6903]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 05:07:34 volumio-raspi3b volumio[6903]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at doSend (dgram.js:692:16) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at afterDns (dgram.js:638:5) Nov 02 05:07:34 volumio-raspi3b volumio[6903]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 02 05:07:34 volumio-raspi3b volumio[6903]: errno: -101, Nov 02 05:07:34 volumio-raspi3b volumio[6903]: code: 'ENETUNREACH', Nov 02 05:07:34 volumio-raspi3b volumio[6903]: syscall: 'send', Nov 02 05:07:34 volumio-raspi3b volumio[6903]: address: '255.255.255.255', Nov 02 05:07:34 volumio-raspi3b volumio[6903]: port: 3483 Nov 02 05:07:34 volumio-raspi3b volumio[6903]: } Nov 02 05:07:34 volumio-raspi3b volumio[6903]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 05:07:34 volumio-raspi3b sudo[7072]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:34 volumio-raspi3b sudo[7072]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:34 volumio-raspi3b sudo[7072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 05:06 Nov 02 05:07:34 volumio-raspi3b sudo[7072]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:35 volumio-raspi3b sudo[7072]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:35 volumio-raspi3b volumio[6903]: sudo: unable to resolve host volumio-raspi3b: System error Nov 02 05:07:35 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:35] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 02 05:07:35 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:35] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 02 05:07:35 volumio-raspi3b systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:07:35 volumio-raspi3b sudo[6991]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:35 volumio-raspi3b mpd[7035]: Nov 02 05:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 05:07:35 volumio-raspi3b sudo[7004]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:35 volumio-raspi3b systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 02 05:07:35 volumio-raspi3b systemd[1]: Started dynamicswap service. Nov 02 05:07:35 volumio-raspi3b systemd[1]: dynamicswap.service: Succeeded. Nov 02 05:07:35 volumio-raspi3b systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 05:07:35 volumio-raspi3b systemd[1]: volumio.service: Scheduled restart job, restart counter is at 36. Nov 02 05:07:35 volumio-raspi3b systemd[1]: Started dynamicswap service. Nov 02 05:07:35 volumio-raspi3b systemd[1]: Stopped Volumio Backend Module. Nov 02 05:07:35 volumio-raspi3b systemd[1]: Started Volumio Backend Module. Nov 02 05:07:35 volumio-raspi3b systemd[1]: Started Music Player Daemon. Nov 02 05:07:35 volumio-raspi3b systemd[1]: dynamicswap.service: Succeeded. Nov 02 05:07:37 volumio-raspi3b volumio[7088]: info: ------------------------------------------- Nov 02 05:07:37 volumio-raspi3b volumio[7088]: info: ----- Volumio3 ---- Nov 02 05:07:37 volumio-raspi3b volumio[7088]: info: ------------------------------------------- Nov 02 05:07:37 volumio-raspi3b volumio[7088]: info: ----- System startup ---- Nov 02 05:07:37 volumio-raspi3b volumio[7088]: info: ------------------------------------------- Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: MYVOLUMIO Environment detected Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Plugin folders cleanup Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Scanning into folder /volumio/app/plugins/ Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Scanning category audio_interface Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Scanning category miscellanea Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Scanning category music_service Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Scanning category plugins.json Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Scanning category system_controller Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Scanning category user_interface Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Scanning into folder /data/plugins/ Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Scanning category audio_interface Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Scanning category music_service Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Scanning category system_controller Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Scanning category system_hardware Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Scanning category user_interface Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Plugin folders cleanup completed Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: ------------------------------------------- Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: ----- Core plugins startup ---- Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: ------------------------------------------- Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Loading plugins from folder /volumio/app/plugins/ Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Adding plugin upnp to MyMusic Plugins Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Loading plugins from folder /data/plugins/ Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Loading plugin "system"... Nov 02 05:07:38 volumio-raspi3b volumio[7088]: info: Loading plugin "appearance"... Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: Loading plugin "network"... Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: Refreshing Cached IP Addresses Nov 02 05:07:39 volumio-raspi3b sudo[7114]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:39 volumio-raspi3b sudo[7114]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:39 volumio-raspi3b sudo[7114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 05:07:39 volumio-raspi3b sudo[7114]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:39 volumio-raspi3b sudo[7116]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:39 volumio-raspi3b sudo[7116]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: Loading plugin "services"... Nov 02 05:07:39 volumio-raspi3b sudo[7114]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:39 volumio-raspi3b sudo[7116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: Loading plugin "alsa_controller"... Nov 02 05:07:39 volumio-raspi3b sudo[7116]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:39 volumio-raspi3b sudo[7119]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:39 volumio-raspi3b sudo[7119]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:39 volumio-raspi3b sudo[7116]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:39 volumio-raspi3b sudo[7119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 02 05:07:39 volumio-raspi3b sudo[7119]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: Loading plugin "wizard"... Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: Loading plugin "networkfs"... Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: Cannot mount NAS E-Home-Media-HighRes at system boot, trial number 1 ,retrying in 5 seconds Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: Starting Udev Watcher for removable devices Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: Ignoring mount for partition: boot Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: Ignoring mount for partition: volumio Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: Ignoring mount for partition: volumio_data Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: Loading plugin "volumio_command_line_client"... Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: Loading plugin "upnp"... Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: [1730520459635] Starting Upmpd Daemon Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: Loading plugin "my_music"... Nov 02 05:07:39 volumio-raspi3b volumio[7088]: info: Loading plugin "mpd"... Nov 02 05:07:40 volumio-raspi3b volumio[7088]: info: Loading plugin "upnp_browser"... Nov 02 05:07:40 volumio-raspi3b sudo[7119]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:40 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:40] [connect] Successful connection Nov 02 05:07:40 volumio-raspi3b volumio[7088]: info: Loading plugin "alarm-clock"... Nov 02 05:07:40 volumio-raspi3b volumio[7088]: info: Loading plugin "airplay_emulation"... Nov 02 05:07:40 volumio-raspi3b volumio[7088]: info: Starting Shairport Sync Nov 02 05:07:40 volumio-raspi3b volumio[7088]: info: Loading plugin "last_100"... Nov 02 05:07:40 volumio-raspi3b volumio[7088]: info: Loading plugin "webradio"... Nov 02 05:07:40 volumio-raspi3b volumio[7088]: info: Loading plugin "i2s_dacs"... Nov 02 05:07:40 volumio-raspi3b volumio[7088]: info: Loading plugin "volumiodiscovery"... Nov 02 05:07:40 volumio-raspi3b volumio[7088]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:40 volumio-raspi3b volumio[7088]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:40 volumio-raspi3b volumio[7088]: *** WARNING *** For more information see Nov 02 05:07:40 volumio-raspi3b volumio[7088]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:40 volumio-raspi3b node[7088]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:40 volumio-raspi3b volumio[7088]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:40 volumio-raspi3b volumio[7088]: *** WARNING *** For more information see Nov 02 05:07:40 volumio-raspi3b node[7088]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:40 volumio-raspi3b node[7088]: *** WARNING *** For more information see Nov 02 05:07:40 volumio-raspi3b node[7088]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:40 volumio-raspi3b node[7088]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:40 volumio-raspi3b node[7088]: *** WARNING *** For more information see Nov 02 05:07:40 volumio-raspi3b volumio[7088]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 05:07:40 volumio-raspi3b volumio[7088]: info: Discovery: Started advertising with name: Volumio-Raspi3b+ Nov 02 05:07:40 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 05:07:40 volumio-raspi3b volumio[7088]: info: Loading plugin "squeezelite_mc"... Nov 02 05:07:41 volumio-raspi3b volumio[7088]: info: Loading plugin "now_playing"... Nov 02 05:07:42 volumio-raspi3b volumio[7088]: info: Loading plugin "outputs"... Nov 02 05:07:42 volumio-raspi3b volumio[7088]: info: Loading plugin "albumart"... Nov 02 05:07:42 volumio-raspi3b volumio[7088]: info: Plugin example_plugin is not enabled Nov 02 05:07:42 volumio-raspi3b volumio[7088]: info: Loading plugin "inputs"... Nov 02 05:07:42 volumio-raspi3b volumio[7088]: info: Loading plugin "updater_comm"... Nov 02 05:07:43 volumio-raspi3b volumio[7088]: info: Plugin mpdemulation is not enabled Nov 02 05:07:43 volumio-raspi3b volumio[7088]: info: Loading plugin "rest_api"... Nov 02 05:07:43 volumio-raspi3b volumio[7088]: info: Loading plugin "websocket"... Nov 02 05:07:43 volumio-raspi3b volumio[7088]: info: Starting Socket.io Server version 2.3.0 Nov 02 05:07:43 volumio-raspi3b volumio[7088]: info: Plugin fusiondsp is not enabled Nov 02 05:07:43 volumio-raspi3b volumio[7088]: info: Loading plugin "RoonBridge"... Nov 02 05:07:43 volumio-raspi3b volumio[7088]: Forking 3 albumart workers Nov 02 05:07:43 volumio-raspi3b volumio[7088]: info: Applying required configuration parameters for plugin RoonBridge Nov 02 05:07:43 volumio-raspi3b volumio[7088]: info: Loading plugin "minidlna"... Nov 02 05:07:43 volumio-raspi3b volumio[7088]: info: Applying required configuration parameters for plugin minidlna Nov 02 05:07:43 volumio-raspi3b volumio[7088]: info: Loading plugin "backup_restore"... Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: Applying required configuration parameters for plugin backup_restore Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: Plugin mpd_oled is not enabled Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: Plugin Systeminfo is not enabled Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: Plugin lastfm is not enabled Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: Plugin touch_display is not enabled Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: Loading i18n strings for locale de Nov 02 05:07:44 volumio-raspi3b volumio[7088]: Updating browse sources language Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::initPlayerControls Nov 02 05:07:44 volumio-raspi3b volumio[7088]: Starting albumart workers Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 05:07:44 volumio-raspi3b volumio[7088]: Express server listening on port 3000 Nov 02 05:07:44 volumio-raspi3b volumio[7088]: [Metrics] WebUI: 7s 519.40ms Nov 02 05:07:44 volumio-raspi3b volumio[7088]: Starting albumart workers Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreStateMachine::resetVolumioState Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreStateMachine::getcurrentVolume Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 05:07:44 volumio-raspi3b volumio[7088]: Starting albumart workers Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: Cannot mount NAS E-Home-Media-HighRes at system boot, trial number 2 ,retrying in 5 seconds Nov 02 05:07:44 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1730520460 101 Nov 02 05:07:44 volumio-raspi3b volumio[7088]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreStateMachine::pushState Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreStateMachine::updateTrackBlock Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CorePlayQueue::getTrackBlock Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: Reloading queue from file Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreStateMachine::setRepeat null single undefined Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreStateMachine::pushState Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreStateMachine::setRandom null Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreStateMachine::pushState Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: Setting Device type: Raspberry PI Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: Completed loading Core Plugins Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: Preparing to generate the ALSA configuration file Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreStateMachine::pushState Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: Reading ALSA contributions from plugins. Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: Asound.conf file unchanged, so no further update is needed Nov 02 05:07:44 volumio-raspi3b volumio[7088]: info: Output device has changed, restarting MPD Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: Output device has changed, restarting Shairport Sync Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:45 volumio-raspi3b sudo[7174]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:45 volumio-raspi3b sudo[7174]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:45 volumio-raspi3b sudo[7174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 05:07:45 volumio-raspi3b sudo[7176]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:45 volumio-raspi3b sudo[7176]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:45 volumio-raspi3b sudo[7174]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:45 volumio-raspi3b sudo[7176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 05:07:45 volumio-raspi3b sudo[7176]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:45 volumio-raspi3b sudo[7174]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:45 volumio-raspi3b systemd[1]: Stopping Music Player Daemon... Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: ___________ START PLUGINS ___________ Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: ControllerMpd::onStart: Initializing MPD Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: Creating MPD Configuration file Nov 02 05:07:45 volumio-raspi3b systemd[1]: mpd.service: Succeeded. Nov 02 05:07:45 volumio-raspi3b systemd[1]: Stopped Music Player Daemon. Nov 02 05:07:45 volumio-raspi3b systemd[1]: Starting Music Player Daemon... Nov 02 05:07:45 volumio-raspi3b sudo[7196]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:45 volumio-raspi3b sudo[7196]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:45 volumio-raspi3b sudo[7196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 05:07:45 volumio-raspi3b sudo[7196]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:45 volumio-raspi3b sudo[7196]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: [1730520465334] CoreMusicLibrary::Adding element Medienserver Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:45 volumio-raspi3b sudo[7203]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:45 volumio-raspi3b sudo[7203]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:45 volumio-raspi3b sudo[7203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 05:07:45 volumio-raspi3b sudo[7203]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:45 volumio-raspi3b systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 02 05:07:45 volumio-raspi3b systemd[1]: mpd.service: Succeeded. Nov 02 05:07:45 volumio-raspi3b systemd[1]: Stopped Music Player Daemon. Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:45 volumio-raspi3b systemd[1]: Starting Music Player Daemon... Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: [1730520465515] CoreMusicLibrary::Adding element Last_100 Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: [1730520465520] CoreMusicLibrary::Adding element Webradio Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: Initializing BBC Radios Nov 02 05:07:45 volumio-raspi3b sudo[7216]: root : unable to resolve host volumio-raspi3b Nov 02 05:07:45 volumio-raspi3b sudo[7216]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:45 volumio-raspi3b sudo[7216]: sudo: unable to resolve host volumio-raspi3b: System error Nov 02 05:07:45 volumio-raspi3b sudo[7216]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 05:07:45 volumio-raspi3b sudo[7216]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:45 volumio-raspi3b sudo[7216]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: [squeezelite_mc] Starting proxy server... Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: [now-playing] ConfigUpdater: config is up to date. Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: Loading i18n strings for locale de Nov 02 05:07:45 volumio-raspi3b sudo[7233]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:45 volumio-raspi3b sudo[7233]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:45 volumio-raspi3b sudo[7233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Nov 02 05:07:45 volumio-raspi3b sudo[7233]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: Volumio Calling Home Nov 02 05:07:45 volumio-raspi3b sudo[7233]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:45 volumio-raspi3b volumio[7088]: info: [squeezelite_mc] Proxy server started on port 39057 Nov 02 05:07:46 volumio-raspi3b volumio[7088]: info: [now-playing] Fetching API key... Nov 02 05:07:46 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 05:07:46 volumio-raspi3b volumio[7088]: info: [now-playing] App is listening on port 4004. Nov 02 05:07:46 volumio-raspi3b volumio[7088]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Nov 02 05:07:46 volumio-raspi3b volumio[7088]: Unhandled rejection Error: No sockets available, cannot start. Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: error: [now-playing] Error fetching https://openweathermap.org: FetchError: request to https://openweathermap.org/ failed, reason: getaddrinfo EBUSY openweathermap.org Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at ClientRequest. (/data/plugins/user_interface/now_playing/node_modules/node-fetch/lib/index.js:1505:11) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at ClientRequest.emit (events.js:315:20) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at TLSSocket.emit (events.js:315:20) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at emitErrorNT (internal/streams/destroy.js:106:8) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: (node:7088) UnhandledPromiseRejectionWarning: FetchError: request to https://openweathermap.org/ failed, reason: getaddrinfo EBUSY openweathermap.org Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at ClientRequest. (/data/plugins/user_interface/now_playing/node_modules/node-fetch/lib/index.js:1505:11) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at ClientRequest.emit (events.js:315:20) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at TLSSocket.emit (events.js:315:20) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at emitErrorNT (internal/streams/destroy.js:106:8) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: (Use `node --trace-warnings ...` to show where the warning was created) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: (node:7088) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 4) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: (node:7088) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Nov 02 05:07:46 volumio-raspi3b volumio[7088]: info: CoreStateMachine::pushState Nov 02 05:07:46 volumio-raspi3b volumio[7088]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:46 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:46 volumio-raspi3b volumio[7088]: info: [squeezelite_mc] Server discovery started Nov 02 05:07:46 volumio-raspi3b volumio[7088]: info: [squeezelite_mc] Player finder started Nov 02 05:07:46 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:46 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:46 volumio-raspi3b volumio[7088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:46 volumio-raspi3b volumio[7088]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 02 05:07:46 volumio-raspi3b volumio[7088]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 05:07:46 volumio-raspi3b volumio[7088]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at doSend (dgram.js:692:16) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at afterDns (dgram.js:638:5) Nov 02 05:07:46 volumio-raspi3b volumio[7088]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 02 05:07:46 volumio-raspi3b volumio[7088]: errno: -101, Nov 02 05:07:46 volumio-raspi3b volumio[7088]: code: 'ENETUNREACH', Nov 02 05:07:46 volumio-raspi3b volumio[7088]: syscall: 'send', Nov 02 05:07:46 volumio-raspi3b volumio[7088]: address: '255.255.255.255', Nov 02 05:07:46 volumio-raspi3b volumio[7088]: port: 3483 Nov 02 05:07:46 volumio-raspi3b volumio[7088]: } Nov 02 05:07:46 volumio-raspi3b volumio[7088]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 05:07:46 volumio-raspi3b sudo[7257]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:46 volumio-raspi3b sudo[7257]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:46 volumio-raspi3b sudo[7257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 05:06 Nov 02 05:07:46 volumio-raspi3b sudo[7257]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:47 volumio-raspi3b sudo[7257]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:47 volumio-raspi3b volumio[7088]: sudo: unable to resolve host volumio-raspi3b: System error Nov 02 05:07:47 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:47] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 02 05:07:47 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:47] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 02 05:07:47 volumio-raspi3b sudo[7203]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:47 volumio-raspi3b systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:07:47 volumio-raspi3b sudo[7176]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:47 volumio-raspi3b systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 02 05:07:47 volumio-raspi3b systemd[1]: Started dynamicswap service. Nov 02 05:07:47 volumio-raspi3b mpd[7231]: Nov 02 05:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 05:07:47 volumio-raspi3b systemd[1]: dynamicswap.service: Succeeded. Nov 02 05:07:47 volumio-raspi3b systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 05:07:47 volumio-raspi3b systemd[1]: volumio.service: Scheduled restart job, restart counter is at 37. Nov 02 05:07:47 volumio-raspi3b systemd[1]: Stopped Volumio Backend Module. Nov 02 05:07:47 volumio-raspi3b systemd[1]: Started Volumio Backend Module. Nov 02 05:07:47 volumio-raspi3b systemd[1]: Started dynamicswap service. Nov 02 05:07:47 volumio-raspi3b systemd[1]: Started Music Player Daemon. Nov 02 05:07:47 volumio-raspi3b systemd[1]: dynamicswap.service: Succeeded. Nov 02 05:07:48 volumio-raspi3b wpa_supplicant[951]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="VinLen-889950" Nov 02 05:07:48 volumio-raspi3b wpa_supplicant[951]: wlan0: Trying to associate with SSID 'VinLen-889950' Nov 02 05:07:49 volumio-raspi3b volumio[7270]: info: ------------------------------------------- Nov 02 05:07:49 volumio-raspi3b volumio[7270]: info: ----- Volumio3 ---- Nov 02 05:07:49 volumio-raspi3b volumio[7270]: info: ------------------------------------------- Nov 02 05:07:49 volumio-raspi3b volumio[7270]: info: ----- System startup ---- Nov 02 05:07:49 volumio-raspi3b volumio[7270]: info: ------------------------------------------- Nov 02 05:07:49 volumio-raspi3b dhcpcd[963]: wlan0: carrier acquired Nov 02 05:07:49 volumio-raspi3b wpa_supplicant[951]: wlan0: Associated with 58:d9:d5:3a:e9:42 Nov 02 05:07:49 volumio-raspi3b wpa_supplicant[951]: wlan0: CTRL-EVENT-CONNECTED - Connection to 58:d9:d5:3a:e9:42 completed [id=0 id_str=] Nov 02 05:07:49 volumio-raspi3b wpa_supplicant[951]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 02 05:07:49 volumio-raspi3b wpa_supplicant[951]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Nov 02 05:07:49 volumio-raspi3b dhcpcd[963]: wlan0: IAID eb:f6:7f:2c Nov 02 05:07:49 volumio-raspi3b dhcpcd[963]: wlan0: carrier lost Nov 02 05:07:49 volumio-raspi3b dhcpcd[963]: wlan0: carrier acquired Nov 02 05:07:49 volumio-raspi3b dhcpcd[963]: wlan0: IAID eb:f6:7f:2c Nov 02 05:07:49 volumio-raspi3b dhcpcd[963]: wlan0: rebinding lease of 192.168.2.48 Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: MYVOLUMIO Environment detected Nov 02 05:07:50 volumio-raspi3b dhcpcd[963]: wlan0: soliciting an IPv6 router Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Plugin folders cleanup Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Scanning into folder /volumio/app/plugins/ Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Scanning category audio_interface Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Scanning category miscellanea Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Scanning category music_service Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Scanning category plugins.json Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Scanning category system_controller Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Scanning category user_interface Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Scanning into folder /data/plugins/ Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Scanning category audio_interface Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Scanning category music_service Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Scanning category system_controller Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Scanning category system_hardware Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Scanning category user_interface Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Plugin folders cleanup completed Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: ------------------------------------------- Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: ----- Core plugins startup ---- Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: ------------------------------------------- Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Loading plugins from folder /volumio/app/plugins/ Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Adding plugin upnp to MyMusic Plugins Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Loading plugins from folder /data/plugins/ Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Loading plugin "system"... Nov 02 05:07:50 volumio-raspi3b volumio[7270]: info: Loading plugin "appearance"... Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: Loading plugin "network"... Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: Refreshing Cached IP Addresses Nov 02 05:07:51 volumio-raspi3b sudo[7311]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:51 volumio-raspi3b sudo[7311]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:51 volumio-raspi3b sudo[7311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 05:07:51 volumio-raspi3b sudo[7311]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:51 volumio-raspi3b sudo[7313]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:51 volumio-raspi3b sudo[7313]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: Loading plugin "services"... Nov 02 05:07:51 volumio-raspi3b sudo[7313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 05:07:51 volumio-raspi3b sudo[7311]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:51 volumio-raspi3b sudo[7313]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: Loading plugin "alsa_controller"... Nov 02 05:07:51 volumio-raspi3b sudo[7313]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:51 volumio-raspi3b sudo[7316]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:51 volumio-raspi3b sudo[7316]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:51 volumio-raspi3b sudo[7316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 02 05:07:51 volumio-raspi3b sudo[7316]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: Loading plugin "wizard"... Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: Loading plugin "networkfs"... Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: Cannot mount NAS E-Home-Media-HighRes at system boot, trial number 1 ,retrying in 5 seconds Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: Starting Udev Watcher for removable devices Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: Ignoring mount for partition: boot Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: Ignoring mount for partition: volumio Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: Ignoring mount for partition: volumio_data Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: Loading plugin "volumio_command_line_client"... Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: Loading plugin "upnp"... Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: [1730520471765] Starting Upmpd Daemon Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: Loading plugin "my_music"... Nov 02 05:07:51 volumio-raspi3b volumio[7270]: info: Loading plugin "mpd"... Nov 02 05:07:52 volumio-raspi3b volumio[7270]: info: Loading plugin "upnp_browser"... Nov 02 05:07:52 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:52] [connect] Successful connection Nov 02 05:07:52 volumio-raspi3b volumio[7270]: info: Loading plugin "alarm-clock"... Nov 02 05:07:52 volumio-raspi3b sudo[7316]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:52 volumio-raspi3b volumio[7270]: info: Loading plugin "airplay_emulation"... Nov 02 05:07:52 volumio-raspi3b volumio[7270]: info: Starting Shairport Sync Nov 02 05:07:52 volumio-raspi3b volumio[7270]: info: Loading plugin "last_100"... Nov 02 05:07:52 volumio-raspi3b volumio[7270]: info: Loading plugin "webradio"... Nov 02 05:07:52 volumio-raspi3b volumio[7270]: info: Loading plugin "i2s_dacs"... Nov 02 05:07:52 volumio-raspi3b volumio[7270]: info: Loading plugin "volumiodiscovery"... Nov 02 05:07:52 volumio-raspi3b volumio[7270]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:52 volumio-raspi3b volumio[7270]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:52 volumio-raspi3b volumio[7270]: *** WARNING *** For more information see Nov 02 05:07:52 volumio-raspi3b node[7270]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:52 volumio-raspi3b volumio[7270]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:52 volumio-raspi3b volumio[7270]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:52 volumio-raspi3b volumio[7270]: *** WARNING *** For more information see Nov 02 05:07:52 volumio-raspi3b node[7270]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:52 volumio-raspi3b node[7270]: *** WARNING *** For more information see Nov 02 05:07:52 volumio-raspi3b node[7270]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 05:07:52 volumio-raspi3b node[7270]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:07:52 volumio-raspi3b node[7270]: *** WARNING *** For more information see Nov 02 05:07:52 volumio-raspi3b volumio[7270]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 05:07:52 volumio-raspi3b volumio[7270]: info: Discovery: Started advertising with name: Volumio-Raspi3b+ Nov 02 05:07:52 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 05:07:52 volumio-raspi3b volumio[7270]: info: Loading plugin "squeezelite_mc"... Nov 02 05:07:53 volumio-raspi3b volumio[7270]: info: Loading plugin "now_playing"... Nov 02 05:07:53 volumio-raspi3b dhcpcd[963]: wlan0: probing address 192.168.2.48/24 Nov 02 05:07:55 volumio-raspi3b volumio[7270]: info: Loading plugin "outputs"... Nov 02 05:07:55 volumio-raspi3b volumio[7270]: info: Loading plugin "albumart"... Nov 02 05:07:55 volumio-raspi3b volumio[7270]: info: Plugin example_plugin is not enabled Nov 02 05:07:55 volumio-raspi3b volumio[7270]: info: Loading plugin "inputs"... Nov 02 05:07:55 volumio-raspi3b volumio[7270]: info: Loading plugin "updater_comm"... Nov 02 05:07:55 volumio-raspi3b volumio[7270]: info: Plugin mpdemulation is not enabled Nov 02 05:07:55 volumio-raspi3b volumio[7270]: info: Loading plugin "rest_api"... Nov 02 05:07:55 volumio-raspi3b volumio[7270]: info: Loading plugin "websocket"... Nov 02 05:07:55 volumio-raspi3b volumio[7270]: info: Starting Socket.io Server version 2.3.0 Nov 02 05:07:55 volumio-raspi3b volumio[7270]: info: Plugin fusiondsp is not enabled Nov 02 05:07:55 volumio-raspi3b volumio[7270]: info: Loading plugin "RoonBridge"... Nov 02 05:07:55 volumio-raspi3b volumio[7270]: Forking 3 albumart workers Nov 02 05:07:55 volumio-raspi3b volumio[7270]: info: Applying required configuration parameters for plugin RoonBridge Nov 02 05:07:55 volumio-raspi3b volumio[7270]: info: Loading plugin "minidlna"... Nov 02 05:07:55 volumio-raspi3b volumio[7270]: info: Applying required configuration parameters for plugin minidlna Nov 02 05:07:55 volumio-raspi3b volumio[7270]: info: Loading plugin "backup_restore"... Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: Applying required configuration parameters for plugin backup_restore Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: Plugin mpd_oled is not enabled Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: Plugin Systeminfo is not enabled Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: Plugin lastfm is not enabled Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: Plugin touch_display is not enabled Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: Loading i18n strings for locale de Nov 02 05:07:56 volumio-raspi3b volumio[7270]: Updating browse sources language Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::initPlayerControls Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 05:07:56 volumio-raspi3b volumio[7270]: Express server listening on port 3000 Nov 02 05:07:56 volumio-raspi3b volumio[7270]: [Metrics] WebUI: 7s 680.42ms Nov 02 05:07:56 volumio-raspi3b volumio[7270]: Starting albumart workers Nov 02 05:07:56 volumio-raspi3b volumio[7270]: Starting albumart workers Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreStateMachine::resetVolumioState Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreStateMachine::getcurrentVolume Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: Cannot mount NAS E-Home-Media-HighRes at system boot, trial number 2 ,retrying in 5 seconds Nov 02 05:07:56 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1730520472 101 Nov 02 05:07:56 volumio-raspi3b volumio[7270]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreStateMachine::pushState Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:56 volumio-raspi3b volumio[7270]: Starting albumart workers Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreStateMachine::updateTrackBlock Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CorePlayQueue::getTrackBlock Nov 02 05:07:56 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: Reloading queue from file Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreStateMachine::setRepeat null single undefined Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreStateMachine::pushState Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreStateMachine::setRandom null Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreStateMachine::pushState Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: Setting Device type: Raspberry PI Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: Completed loading Core Plugins Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: Preparing to generate the ALSA configuration file Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreStateMachine::pushState Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: Reading ALSA contributions from plugins. Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: Asound.conf file unchanged, so no further update is needed Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: Output device has changed, restarting MPD Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: Output device has changed, restarting Shairport Sync Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:57 volumio-raspi3b sudo[7372]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:57 volumio-raspi3b sudo[7377]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:57 volumio-raspi3b sudo[7377]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:57 volumio-raspi3b sudo[7372]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:57 volumio-raspi3b sudo[7377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 05:07:57 volumio-raspi3b sudo[7372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 05:07:57 volumio-raspi3b sudo[7372]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:57 volumio-raspi3b sudo[7377]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:57 volumio-raspi3b sudo[7372]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:57 volumio-raspi3b systemd[1]: Stopping Music Player Daemon... Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 05:07:57 volumio-raspi3b systemd[1]: mpd.service: Succeeded. Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: ___________ START PLUGINS ___________ Nov 02 05:07:57 volumio-raspi3b systemd[1]: Stopped Music Player Daemon. Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: ControllerMpd::onStart: Initializing MPD Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: Creating MPD Configuration file Nov 02 05:07:57 volumio-raspi3b sudo[7397]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:57 volumio-raspi3b sudo[7397]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:57 volumio-raspi3b systemd[1]: Starting Music Player Daemon... Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 05:07:57 volumio-raspi3b sudo[7397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: [1730520477567] CoreMusicLibrary::Adding element Medienserver Nov 02 05:07:57 volumio-raspi3b sudo[7397]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:57 volumio-raspi3b sudo[7397]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:57 volumio-raspi3b sudo[7400]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:57 volumio-raspi3b sudo[7400]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:57 volumio-raspi3b sudo[7400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 05:07:57 volumio-raspi3b sudo[7400]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:57 volumio-raspi3b systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 02 05:07:57 volumio-raspi3b systemd[1]: mpd.service: Succeeded. Nov 02 05:07:57 volumio-raspi3b systemd[1]: Stopped Music Player Daemon. Nov 02 05:07:57 volumio-raspi3b systemd[1]: Starting Music Player Daemon... Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: [1730520477745] CoreMusicLibrary::Adding element Last_100 Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: [1730520477751] CoreMusicLibrary::Adding element Webradio Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: Initializing BBC Radios Nov 02 05:07:57 volumio-raspi3b sudo[7405]: root : unable to resolve host volumio-raspi3b Nov 02 05:07:57 volumio-raspi3b sudo[7405]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:57 volumio-raspi3b sudo[7405]: sudo: unable to resolve host volumio-raspi3b: System error Nov 02 05:07:57 volumio-raspi3b sudo[7405]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 05:07:57 volumio-raspi3b sudo[7405]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:57 volumio-raspi3b sudo[7405]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: [squeezelite_mc] Starting proxy server... Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: [now-playing] ConfigUpdater: config is up to date. Nov 02 05:07:57 volumio-raspi3b volumio[7270]: info: Loading i18n strings for locale de Nov 02 05:07:57 volumio-raspi3b sudo[7430]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:57 volumio-raspi3b sudo[7430]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:57 volumio-raspi3b sudo[7430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Nov 02 05:07:57 volumio-raspi3b sudo[7430]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:57 volumio-raspi3b wpa_supplicant[951]: wlan0: CTRL-EVENT-DISCONNECTED bssid=58:d9:d5:3a:e9:42 reason=0 locally_generated=1 Nov 02 05:07:57 volumio-raspi3b dhcpcd[963]: wlan0: carrier lost Nov 02 05:07:57 volumio-raspi3b wpa_supplicant[951]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Nov 02 05:07:57 volumio-raspi3b wpa_supplicant[951]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Nov 02 05:07:58 volumio-raspi3b sudo[7430]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:58 volumio-raspi3b volumio[7270]: info: Volumio Calling Home Nov 02 05:07:58 volumio-raspi3b wpa_supplicant[951]: wlan0: Trying to associate with SSID 'VinLen-889950' Nov 02 05:07:58 volumio-raspi3b volumio[7270]: info: [squeezelite_mc] Proxy server started on port 36241 Nov 02 05:07:58 volumio-raspi3b volumio[7270]: info: [now-playing] Fetching API key... Nov 02 05:07:58 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 05:07:58 volumio-raspi3b volumio[7270]: info: [now-playing] App is listening on port 4004. Nov 02 05:07:58 volumio-raspi3b volumio[7270]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Nov 02 05:07:58 volumio-raspi3b volumio[7270]: Unhandled rejection Error: No sockets available, cannot start. Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: error: [now-playing] Error fetching https://openweathermap.org: FetchError: request to https://openweathermap.org/ failed, reason: getaddrinfo EBUSY openweathermap.org Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at ClientRequest. (/data/plugins/user_interface/now_playing/node_modules/node-fetch/lib/index.js:1505:11) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at ClientRequest.emit (events.js:315:20) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at TLSSocket.emit (events.js:315:20) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at emitErrorNT (internal/streams/destroy.js:106:8) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: (node:7270) UnhandledPromiseRejectionWarning: FetchError: request to https://openweathermap.org/ failed, reason: getaddrinfo EBUSY openweathermap.org Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at ClientRequest. (/data/plugins/user_interface/now_playing/node_modules/node-fetch/lib/index.js:1505:11) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at ClientRequest.emit (events.js:315:20) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at TLSSocket.emit (events.js:315:20) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at emitErrorNT (internal/streams/destroy.js:106:8) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: (Use `node --trace-warnings ...` to show where the warning was created) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: (node:7270) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 4) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: (node:7270) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Nov 02 05:07:58 volumio-raspi3b volumio[7270]: info: CoreStateMachine::pushState Nov 02 05:07:58 volumio-raspi3b volumio[7270]: info: CorePlayQueue::getTrack 0 Nov 02 05:07:58 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::volumioPushState Nov 02 05:07:58 volumio-raspi3b volumio[7270]: info: [squeezelite_mc] Server discovery started Nov 02 05:07:58 volumio-raspi3b volumio[7270]: info: [squeezelite_mc] Player finder started Nov 02 05:07:58 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:58 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:58 volumio-raspi3b volumio[7270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:07:58 volumio-raspi3b volumio[7270]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 02 05:07:58 volumio-raspi3b volumio[7270]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 05:07:58 volumio-raspi3b volumio[7270]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at doSend (dgram.js:692:16) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at afterDns (dgram.js:638:5) Nov 02 05:07:58 volumio-raspi3b volumio[7270]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 02 05:07:58 volumio-raspi3b volumio[7270]: errno: -101, Nov 02 05:07:58 volumio-raspi3b volumio[7270]: code: 'ENETUNREACH', Nov 02 05:07:58 volumio-raspi3b volumio[7270]: syscall: 'send', Nov 02 05:07:58 volumio-raspi3b volumio[7270]: address: '255.255.255.255', Nov 02 05:07:58 volumio-raspi3b volumio[7270]: port: 3483 Nov 02 05:07:58 volumio-raspi3b volumio[7270]: } Nov 02 05:07:58 volumio-raspi3b volumio[7270]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 05:07:58 volumio-raspi3b wpa_supplicant[951]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Nov 02 05:07:59 volumio-raspi3b sudo[7474]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:07:59 volumio-raspi3b sudo[7474]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:07:59 volumio-raspi3b sudo[7474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 05:06 Nov 02 05:07:59 volumio-raspi3b sudo[7474]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:07:59 volumio-raspi3b wpa_supplicant[951]: wlan0: Trying to associate with SSID 'VinLen-889950' Nov 02 05:07:59 volumio-raspi3b sudo[7474]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:59 volumio-raspi3b mpd[7421]: Nov 02 05:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 05:07:59 volumio-raspi3b volumio[7270]: sudo: unable to resolve host volumio-raspi3b: System error Nov 02 05:07:59 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:59] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 02 05:07:59 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:07:59] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 02 05:07:59 volumio-raspi3b systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:07:59 volumio-raspi3b sudo[7400]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:59 volumio-raspi3b sudo[7377]: pam_unix(sudo:session): session closed for user root Nov 02 05:07:59 volumio-raspi3b systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 02 05:07:59 volumio-raspi3b systemd[1]: Started dynamicswap service. Nov 02 05:07:59 volumio-raspi3b systemd[1]: dynamicswap.service: Succeeded. Nov 02 05:07:59 volumio-raspi3b systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 05:07:59 volumio-raspi3b systemd[1]: volumio.service: Scheduled restart job, restart counter is at 38. Nov 02 05:07:59 volumio-raspi3b systemd[1]: Stopped Volumio Backend Module. Nov 02 05:07:59 volumio-raspi3b systemd[1]: Started Volumio Backend Module. Nov 02 05:07:59 volumio-raspi3b systemd[1]: Started dynamicswap service. Nov 02 05:07:59 volumio-raspi3b systemd[1]: Started Music Player Daemon. Nov 02 05:07:59 volumio-raspi3b systemd[1]: dynamicswap.service: Succeeded. Nov 02 05:08:00 volumio-raspi3b dhcpcd[963]: wlan0: carrier acquired Nov 02 05:08:00 volumio-raspi3b wpa_supplicant[951]: wlan0: Associated with 58:d9:d5:3a:e9:02 Nov 02 05:08:00 volumio-raspi3b wpa_supplicant[951]: wlan0: CTRL-EVENT-CONNECTED - Connection to 58:d9:d5:3a:e9:02 completed [id=0 id_str=] Nov 02 05:08:00 volumio-raspi3b wpa_supplicant[951]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 02 05:08:00 volumio-raspi3b wpa_supplicant[951]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Nov 02 05:08:00 volumio-raspi3b dhcpcd[963]: wlan0: IAID eb:f6:7f:2c Nov 02 05:08:00 volumio-raspi3b dhcpcd[963]: wlan0: carrier lost Nov 02 05:08:00 volumio-raspi3b dhcpcd[963]: wlan0: carrier acquired Nov 02 05:08:00 volumio-raspi3b dhcpcd[963]: wlan0: IAID eb:f6:7f:2c Nov 02 05:08:00 volumio-raspi3b dhcpcd[963]: wlan0: soliciting an IPv6 router Nov 02 05:08:01 volumio-raspi3b dhcpcd[963]: wlan0: rebinding lease of 192.168.2.48 Nov 02 05:08:01 volumio-raspi3b volumio[7487]: info: ------------------------------------------- Nov 02 05:08:01 volumio-raspi3b volumio[7487]: info: ----- Volumio3 ---- Nov 02 05:08:01 volumio-raspi3b volumio[7487]: info: ------------------------------------------- Nov 02 05:08:01 volumio-raspi3b volumio[7487]: info: ----- System startup ---- Nov 02 05:08:01 volumio-raspi3b volumio[7487]: info: ------------------------------------------- Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: MYVOLUMIO Environment detected Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Plugin folders cleanup Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Scanning into folder /volumio/app/plugins/ Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Scanning category audio_interface Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Scanning category miscellanea Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Scanning category music_service Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Scanning category plugins.json Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Scanning category system_controller Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Scanning category user_interface Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Scanning into folder /data/plugins/ Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Scanning category audio_interface Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Scanning category music_service Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Scanning category system_controller Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Scanning category system_hardware Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Scanning category user_interface Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Plugin folders cleanup completed Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: ------------------------------------------- Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: ----- Core plugins startup ---- Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: ------------------------------------------- Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Loading plugins from folder /volumio/app/plugins/ Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Adding plugin upnp to MyMusic Plugins Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Loading plugins from folder /data/plugins/ Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Loading plugin "system"... Nov 02 05:08:02 volumio-raspi3b volumio[7487]: info: Loading plugin "appearance"... Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: Loading plugin "network"... Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: Refreshing Cached IP Addresses Nov 02 05:08:03 volumio-raspi3b sudo[7528]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:08:03 volumio-raspi3b sudo[7528]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:08:03 volumio-raspi3b sudo[7528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 05:08:03 volumio-raspi3b sudo[7528]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:08:03 volumio-raspi3b sudo[7530]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:08:03 volumio-raspi3b sudo[7530]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: Loading plugin "services"... Nov 02 05:08:03 volumio-raspi3b sudo[7530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 05:08:03 volumio-raspi3b sudo[7528]: pam_unix(sudo:session): session closed for user root Nov 02 05:08:03 volumio-raspi3b sudo[7530]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: Loading plugin "alsa_controller"... Nov 02 05:08:03 volumio-raspi3b sudo[7530]: pam_unix(sudo:session): session closed for user root Nov 02 05:08:03 volumio-raspi3b sudo[7533]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:08:03 volumio-raspi3b sudo[7533]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:08:03 volumio-raspi3b sudo[7533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 02 05:08:03 volumio-raspi3b sudo[7533]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: Loading plugin "wizard"... Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: Loading plugin "networkfs"... Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: Cannot mount NAS E-Home-Media-HighRes at system boot, trial number 1 ,retrying in 5 seconds Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: Starting Udev Watcher for removable devices Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: Ignoring mount for partition: boot Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: Ignoring mount for partition: volumio Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: Ignoring mount for partition: volumio_data Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: Loading plugin "volumio_command_line_client"... Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: Loading plugin "upnp"... Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: [1730520483934] Starting Upmpd Daemon Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: Loading plugin "my_music"... Nov 02 05:08:03 volumio-raspi3b volumio[7487]: info: Loading plugin "mpd"... Nov 02 05:08:04 volumio-raspi3b volumio[7487]: info: Loading plugin "upnp_browser"... Nov 02 05:08:04 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:08:04] [connect] Successful connection Nov 02 05:08:04 volumio-raspi3b volumio[7487]: info: Loading plugin "alarm-clock"... Nov 02 05:08:04 volumio-raspi3b sudo[7533]: pam_unix(sudo:session): session closed for user root Nov 02 05:08:04 volumio-raspi3b volumio[7487]: info: Loading plugin "airplay_emulation"... Nov 02 05:08:04 volumio-raspi3b volumio[7487]: info: Starting Shairport Sync Nov 02 05:08:04 volumio-raspi3b volumio[7487]: info: Loading plugin "last_100"... Nov 02 05:08:04 volumio-raspi3b volumio[7487]: info: Loading plugin "webradio"... Nov 02 05:08:04 volumio-raspi3b dhcpcd[963]: wlan0: probing address 192.168.2.48/24 Nov 02 05:08:04 volumio-raspi3b volumio[7487]: info: Loading plugin "i2s_dacs"... Nov 02 05:08:04 volumio-raspi3b volumio[7487]: info: Loading plugin "volumiodiscovery"... Nov 02 05:08:04 volumio-raspi3b volumio[7487]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 05:08:04 volumio-raspi3b volumio[7487]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:08:04 volumio-raspi3b volumio[7487]: *** WARNING *** For more information see Nov 02 05:08:04 volumio-raspi3b node[7487]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 05:08:04 volumio-raspi3b volumio[7487]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 05:08:04 volumio-raspi3b volumio[7487]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:08:04 volumio-raspi3b volumio[7487]: *** WARNING *** For more information see Nov 02 05:08:04 volumio-raspi3b node[7487]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:08:04 volumio-raspi3b node[7487]: *** WARNING *** For more information see Nov 02 05:08:04 volumio-raspi3b node[7487]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 05:08:04 volumio-raspi3b node[7487]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 05:08:04 volumio-raspi3b node[7487]: *** WARNING *** For more information see Nov 02 05:08:04 volumio-raspi3b volumio[7487]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 05:08:04 volumio-raspi3b volumio[7487]: info: Discovery: Started advertising with name: Volumio-Raspi3b+ Nov 02 05:08:05 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 05:08:05 volumio-raspi3b volumio[7487]: info: Loading plugin "squeezelite_mc"... Nov 02 05:08:05 volumio-raspi3b volumio[7487]: info: Loading plugin "now_playing"... Nov 02 05:08:07 volumio-raspi3b volumio[7487]: info: Loading plugin "outputs"... Nov 02 05:08:07 volumio-raspi3b volumio[7487]: info: Loading plugin "albumart"... Nov 02 05:08:07 volumio-raspi3b volumio[7487]: info: Plugin example_plugin is not enabled Nov 02 05:08:07 volumio-raspi3b volumio[7487]: info: Loading plugin "inputs"... Nov 02 05:08:07 volumio-raspi3b volumio[7487]: info: Loading plugin "updater_comm"... Nov 02 05:08:07 volumio-raspi3b volumio[7487]: info: Plugin mpdemulation is not enabled Nov 02 05:08:07 volumio-raspi3b volumio[7487]: info: Loading plugin "rest_api"... Nov 02 05:08:07 volumio-raspi3b volumio[7487]: info: Loading plugin "websocket"... Nov 02 05:08:07 volumio-raspi3b volumio[7487]: info: Starting Socket.io Server version 2.3.0 Nov 02 05:08:07 volumio-raspi3b volumio[7487]: info: Plugin fusiondsp is not enabled Nov 02 05:08:07 volumio-raspi3b volumio[7487]: info: Loading plugin "RoonBridge"... Nov 02 05:08:07 volumio-raspi3b volumio[7487]: Forking 3 albumart workers Nov 02 05:08:07 volumio-raspi3b volumio[7487]: info: Applying required configuration parameters for plugin RoonBridge Nov 02 05:08:07 volumio-raspi3b volumio[7487]: info: Loading plugin "minidlna"... Nov 02 05:08:07 volumio-raspi3b volumio[7487]: info: Applying required configuration parameters for plugin minidlna Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: Loading plugin "backup_restore"... Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: Applying required configuration parameters for plugin backup_restore Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: Plugin mpd_oled is not enabled Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: Plugin Systeminfo is not enabled Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: Plugin lastfm is not enabled Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: Plugin touch_display is not enabled Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: Loading i18n strings for locale de Nov 02 05:08:08 volumio-raspi3b volumio[7487]: Updating browse sources language Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::initPlayerControls Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 05:08:08 volumio-raspi3b volumio[7487]: Express server listening on port 3000 Nov 02 05:08:08 volumio-raspi3b volumio[7487]: [Metrics] WebUI: 7s 495.31ms Nov 02 05:08:08 volumio-raspi3b volumio[7487]: Starting albumart workers Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: CoreStateMachine::resetVolumioState Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: CoreStateMachine::getcurrentVolume Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 05:08:08 volumio-raspi3b volumio[7487]: Starting albumart workers Nov 02 05:08:08 volumio-raspi3b volumio[7487]: Starting albumart workers Nov 02 05:08:08 volumio-raspi3b volumio[7487]: info: Cannot mount NAS E-Home-Media-HighRes at system boot, trial number 2 ,retrying in 5 seconds Nov 02 05:08:08 volumio-raspi3b volumio-remote-updater[662]: [2024-11-02 05:08:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1730520484 101 Nov 02 05:08:09 volumio-raspi3b volumio[7487]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreStateMachine::pushState Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CorePlayQueue::getTrack 0 Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::volumioPushState Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreStateMachine::updateTrackBlock Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CorePlayQueue::getTrackBlock Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: Reloading queue from file Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreStateMachine::setRepeat null single undefined Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreStateMachine::pushState Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CorePlayQueue::getTrack 0 Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::volumioPushState Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreStateMachine::setRandom null Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreStateMachine::pushState Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CorePlayQueue::getTrack 0 Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::volumioPushState Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: Setting Device type: Raspberry PI Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreStateMachine::pushState Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CorePlayQueue::getTrack 0 Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::volumioPushState Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: Completed loading Core Plugins Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: Preparing to generate the ALSA configuration file Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: Reading ALSA contributions from plugins. Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: Asound.conf file unchanged, so no further update is needed Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: Output device has changed, restarting MPD Nov 02 05:08:09 volumio-raspi3b sudo[7589]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:08:09 volumio-raspi3b sudo[7589]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: Output device has changed, restarting Shairport Sync Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:08:09 volumio-raspi3b sudo[7594]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:08:09 volumio-raspi3b sudo[7594]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:08:09 volumio-raspi3b sudo[7589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 05:08:09 volumio-raspi3b sudo[7589]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:08:09 volumio-raspi3b sudo[7594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 05:08:09 volumio-raspi3b sudo[7594]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:08:09 volumio-raspi3b sudo[7589]: pam_unix(sudo:session): session closed for user root Nov 02 05:08:09 volumio-raspi3b systemd[1]: Stopping Music Player Daemon... Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: ___________ START PLUGINS ___________ Nov 02 05:08:09 volumio-raspi3b systemd[1]: mpd.service: Succeeded. Nov 02 05:08:09 volumio-raspi3b systemd[1]: Stopped Music Player Daemon. Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: ControllerMpd::onStart: Initializing MPD Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: Creating MPD Configuration file Nov 02 05:08:09 volumio-raspi3b systemd[1]: Starting Music Player Daemon... Nov 02 05:08:09 volumio-raspi3b sudo[7606]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:08:09 volumio-raspi3b sudo[7606]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:08:09 volumio-raspi3b sudo[7606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 05:08:09 volumio-raspi3b sudo[7606]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:08:09 volumio-raspi3b sudo[7606]: pam_unix(sudo:session): session closed for user root Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: [1730520489619] CoreMusicLibrary::Adding element Medienserver Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:08:09 volumio-raspi3b sudo[7609]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:08:09 volumio-raspi3b sudo[7609]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:08:09 volumio-raspi3b sudo[7609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 05:08:09 volumio-raspi3b sudo[7609]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:08:09 volumio-raspi3b systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 02 05:08:09 volumio-raspi3b systemd[1]: mpd.service: Succeeded. Nov 02 05:08:09 volumio-raspi3b systemd[1]: Stopped Music Player Daemon. Nov 02 05:08:09 volumio-raspi3b systemd[1]: Starting Music Player Daemon... Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: [1730520489820] CoreMusicLibrary::Adding element Last_100 Nov 02 05:08:09 volumio-raspi3b sudo[7621]: root : unable to resolve host volumio-raspi3b Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:08:09 volumio-raspi3b sudo[7621]: sudo: unable to resolve host volumio-raspi3b: System error Nov 02 05:08:09 volumio-raspi3b sudo[7621]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: [1730520489824] CoreMusicLibrary::Adding element Webradio Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:08:09 volumio-raspi3b sudo[7621]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 05:08:09 volumio-raspi3b sudo[7621]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: Initializing BBC Radios Nov 02 05:08:09 volumio-raspi3b sudo[7621]: pam_unix(sudo:session): session closed for user root Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: [squeezelite_mc] Starting proxy server... Nov 02 05:08:09 volumio-raspi3b volumio[7487]: info: [now-playing] ConfigUpdater: config is up to date. Nov 02 05:08:10 volumio-raspi3b volumio[7487]: info: Loading i18n strings for locale de Nov 02 05:08:10 volumio-raspi3b sudo[7647]: volumio : unable to resolve host volumio-raspi3b Nov 02 05:08:10 volumio-raspi3b sudo[7647]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 02 05:08:10 volumio-raspi3b sudo[7647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Nov 02 05:08:10 volumio-raspi3b sudo[7647]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: info: Volumio Calling Home Nov 02 05:08:10 volumio-raspi3b sudo[7647]: pam_unix(sudo:session): session closed for user root Nov 02 05:08:10 volumio-raspi3b volumio[7487]: info: [squeezelite_mc] Proxy server started on port 38249 Nov 02 05:08:10 volumio-raspi3b volumio[7487]: info: [now-playing] Fetching API key... Nov 02 05:08:10 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 05:08:10 volumio-raspi3b volumio[7487]: info: [now-playing] App is listening on port 4004. Nov 02 05:08:10 volumio-raspi3b volumio[7487]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Nov 02 05:08:10 volumio-raspi3b volumio[7487]: Unhandled rejection Error: No sockets available, cannot start. Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: error: [now-playing] Error fetching https://openweathermap.org: FetchError: request to https://openweathermap.org/ failed, reason: getaddrinfo EBUSY openweathermap.org Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at ClientRequest. (/data/plugins/user_interface/now_playing/node_modules/node-fetch/lib/index.js:1505:11) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at ClientRequest.emit (events.js:315:20) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at TLSSocket.emit (events.js:315:20) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at emitErrorNT (internal/streams/destroy.js:106:8) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: (node:7487) UnhandledPromiseRejectionWarning: FetchError: request to https://openweathermap.org/ failed, reason: getaddrinfo EBUSY openweathermap.org Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at ClientRequest. (/data/plugins/user_interface/now_playing/node_modules/node-fetch/lib/index.js:1505:11) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at ClientRequest.emit (events.js:315:20) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at TLSSocket.emit (events.js:315:20) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at emitErrorNT (internal/streams/destroy.js:106:8) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: (Use `node --trace-warnings ...` to show where the warning was created) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: (node:7487) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 4) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: (node:7487) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Nov 02 05:08:10 volumio-raspi3b volumio[7487]: info: CoreStateMachine::pushState Nov 02 05:08:10 volumio-raspi3b volumio[7487]: info: CorePlayQueue::getTrack 0 Nov 02 05:08:10 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::volumioPushState Nov 02 05:08:10 volumio-raspi3b volumio[7487]: info: [squeezelite_mc] Server discovery started Nov 02 05:08:10 volumio-raspi3b volumio[7487]: info: [squeezelite_mc] Player finder started Nov 02 05:08:10 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:08:10 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:08:10 volumio-raspi3b volumio[7487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:08:10 volumio-raspi3b volumio[7487]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 02 05:08:10 volumio-raspi3b volumio[7487]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 05:08:10 volumio-raspi3b volumio[7487]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at doSend (dgram.js:692:16) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at afterDns (dgram.js:638:5) Nov 02 05:08:10 volumio-raspi3b volumio[7487]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 02 05:08:10 volumio-raspi3b volumio[7487]: errno: -101, Nov 02 05:08:10 volumio-raspi3b volumio[7487]: code: 'ENETUNREACH', Nov 02 05:08:10 volumio-raspi3b volumio[7487]: syscall: 'send', Nov 02 05:08:10 volumio-raspi3b volumio[7487]: address: '255.255.255.255', Nov 02 05:08:10 volumio-raspi3b volumio[7487]: port: 3483 Nov 02 05:08:10 volumio-raspi3b volumio[7487]: } Nov 02 05:08:10 volumio-raspi3b volumio[7487]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 05:08:10 volumio-raspi3b dhcpcd[963]: wlan0: leased 192.168.2.48 for 3600 seconds Nov 02 05:08:10 volumio-raspi3b avahi-daemon[672]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.48. Nov 02 05:08:10 volumio-raspi3b dhcpcd[963]: wlan0: adding route to 192.168.2.0/24 Nov 02 05:08:10 volumio-raspi3b dhcpcd[963]: wlan0: adding default route via 192.168.2.20 Nov 02 05:08:10 volumio-raspi3b avahi-daemon[672]: New relevant interface wlan0.IPv4 for mDNS. Nov 02 05:08:10 volumio-raspi3b avahi-daemon[672]: Registering new address record for 192.168.2.48 on wlan0.IPv4. Nov 02 05:08:11 volumio-raspi3b sudo[7693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 05:07 Nov 02 05:08:11 volumio-raspi3b sudo[7693]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"