-- Logs begin at Thu 2019-02-14 18:11:59 AWST, end at Fri 2024-08-23 20:11:03 AWST. -- Aug 23 20:10:00 volumio volumio[6758]: info: ------------------------------------------- Aug 23 20:10:00 volumio volumio[6758]: info: ----- Volumio3 ---- Aug 23 20:10:00 volumio volumio[6758]: info: ------------------------------------------- Aug 23 20:10:00 volumio volumio[6758]: info: ----- System startup ---- Aug 23 20:10:00 volumio volumio[6758]: info: ------------------------------------------- Aug 23 20:10:00 volumio wpa_supplicant[1085]: wlan0: Trying to associate with SSID 'botbot' Aug 23 20:10:00 volumio volumio[6758]: info: MYVOLUMIO Environment detected Aug 23 20:10:00 volumio volumio[6758]: info: Plugin folders cleanup Aug 23 20:10:00 volumio volumio[6758]: info: Scanning into folder /volumio/app/plugins/ Aug 23 20:10:00 volumio volumio[6758]: info: Scanning category audio_interface Aug 23 20:10:00 volumio volumio[6758]: info: Scanning category miscellanea Aug 23 20:10:00 volumio volumio[6758]: info: Scanning category music_service Aug 23 20:10:00 volumio volumio[6758]: info: Scanning category plugins.json Aug 23 20:10:00 volumio volumio[6758]: info: Scanning category system_controller Aug 23 20:10:00 volumio volumio[6758]: info: Scanning category user_interface Aug 23 20:10:00 volumio volumio[6758]: info: Scanning into folder /data/plugins/ Aug 23 20:10:00 volumio volumio[6758]: info: Scanning category music_service Aug 23 20:10:00 volumio volumio[6758]: info: Scanning category user_interface Aug 23 20:10:00 volumio volumio[6758]: info: Plugin folders cleanup completed Aug 23 20:10:00 volumio volumio[6758]: info: ------------------------------------------- Aug 23 20:10:00 volumio volumio[6758]: info: ----- Core plugins startup ---- Aug 23 20:10:00 volumio volumio[6758]: info: ------------------------------------------- Aug 23 20:10:00 volumio volumio[6758]: info: Loading plugins from folder /volumio/app/plugins/ Aug 23 20:10:00 volumio volumio[6758]: info: Adding plugin upnp to MyMusic Plugins Aug 23 20:10:00 volumio volumio[6758]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 23 20:10:00 volumio volumio[6758]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 23 20:10:00 volumio volumio[6758]: info: Loading plugins from folder /data/plugins/ Aug 23 20:10:00 volumio volumio[6758]: info: Loading plugin "system"... Aug 23 20:10:00 volumio volumio[6758]: info: Loading plugin "appearance"... Aug 23 20:10:01 volumio volumio[6758]: info: Loading plugin "network"... Aug 23 20:10:01 volumio volumio[6758]: info: Refreshing Cached IP Addresses Aug 23 20:10:01 volumio sudo[6785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 20:10:01 volumio sudo[6785]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:01 volumio sudo[6785]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:01 volumio volumio[6758]: info: Loading plugin "services"... Aug 23 20:10:01 volumio sudo[6787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 20:10:01 volumio sudo[6787]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:01 volumio volumio[6758]: info: Loading plugin "alsa_controller"... Aug 23 20:10:01 volumio sudo[6787]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:01 volumio sudo[6790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 23 20:10:01 volumio sudo[6790]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:01 volumio sudo[6790]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:01 volumio kernel: ieee80211 phy0: brcmf_cfg80211_scan: Connecting: status (3) Aug 23 20:10:01 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:01 volumio volumio[6758]: info: Loading plugin "wizard"... Aug 23 20:10:01 volumio volumio[6758]: info: Loading plugin "networkfs"... Aug 23 20:10:01 volumio volumio[6758]: info: Starting Udev Watcher for removable devices Aug 23 20:10:01 volumio volumio[6758]: info: Ignoring mount for partition: boot Aug 23 20:10:01 volumio volumio[6758]: info: Ignoring mount for partition: volumio Aug 23 20:10:01 volumio volumio[6758]: info: Ignoring mount for partition: volumio_data Aug 23 20:10:01 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:10:01 volumio volumio[6758]: info: Loading plugin "volumio_command_line_client"... Aug 23 20:10:01 volumio volumio[6758]: info: Plugin upnp is not enabled Aug 23 20:10:01 volumio volumio[6758]: info: Loading plugin "my_music"... Aug 23 20:10:01 volumio volumio[6758]: info: Loading plugin "mpd"... Aug 23 20:10:01 volumio volumio[6758]: info: Plugin upnp_browser is not enabled Aug 23 20:10:01 volumio volumio[6758]: info: Loading plugin "alarm-clock"... Aug 23 20:10:01 volumio volumio[6758]: info: Loading plugin "airplay_emulation"... Aug 23 20:10:01 volumio volumio[6758]: info: Starting Shairport Sync Aug 23 20:10:01 volumio volumio[6758]: info: Loading plugin "last_100"... Aug 23 20:10:01 volumio volumio[6758]: info: Loading plugin "webradio"... Aug 23 20:10:01 volumio volumio[6758]: info: Loading plugin "i2s_dacs"... Aug 23 20:10:01 volumio volumio[6758]: info: Loading plugin "volumiodiscovery"... Aug 23 20:10:01 volumio volumio[6758]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:01 volumio volumio[6758]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:01 volumio volumio[6758]: *** WARNING *** For more information see Aug 23 20:10:01 volumio node[6758]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:01 volumio volumio[6758]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:01 volumio volumio[6758]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:01 volumio volumio[6758]: *** WARNING *** For more information see Aug 23 20:10:01 volumio node[6758]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:01 volumio node[6758]: *** WARNING *** For more information see Aug 23 20:10:01 volumio node[6758]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:01 volumio node[6758]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:01 volumio node[6758]: *** WARNING *** For more information see Aug 23 20:10:01 volumio volumio[6758]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 23 20:10:01 volumio volumio[6758]: info: Discovery: Started advertising with name: VOLUMIO Aug 23 20:10:01 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:10:01 volumio volumio[6758]: info: Loading plugin "squeezelite_mc"... Aug 23 20:10:02 volumio volumio[6758]: info: Loading plugin "outputs"... Aug 23 20:10:02 volumio volumio[6758]: info: Loading plugin "albumart"... Aug 23 20:10:02 volumio volumio[6758]: info: Plugin example_plugin is not enabled Aug 23 20:10:02 volumio volumio[6758]: info: Loading plugin "inputs"... Aug 23 20:10:02 volumio volumio[6758]: info: Loading plugin "updater_comm"... Aug 23 20:10:02 volumio volumio[6758]: info: Plugin mpdemulation is not enabled Aug 23 20:10:02 volumio volumio[6758]: info: Loading plugin "rest_api"... Aug 23 20:10:02 volumio volumio[6758]: info: Loading plugin "websocket"... Aug 23 20:10:02 volumio volumio[6758]: info: Starting Socket.io Server version 2.3.0 Aug 23 20:10:02 volumio volumio[6758]: info: Loading plugin "Systeminfo"... Aug 23 20:10:02 volumio volumio[6758]: Forking 3 albumart workers Aug 23 20:10:02 volumio volumio[6758]: info: Loading i18n strings for locale en Aug 23 20:10:02 volumio volumio[6758]: Updating browse sources language Aug 23 20:10:02 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:02 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:02 volumio volumio[6758]: info: CoreCommandRouter::initPlayerControls Aug 23 20:10:02 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:02 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:02 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:02 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:02 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:02 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:02 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:02 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:02 volumio wpa_supplicant[1085]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Aug 23 20:10:02 volumio wpa_supplicant[1085]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="botbot" auth_failures=1 duration=10 reason=CONN_FAILED Aug 23 20:10:02 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:02 volumio volumio[6758]: Express server listening on port 3000 Aug 23 20:10:02 volumio volumio[6758]: [Metrics] WebUI: 2s 989.19ms Aug 23 20:10:02 volumio volumio[6758]: info: CoreStateMachine::resetVolumioState Aug 23 20:10:02 volumio volumio[6758]: info: CoreStateMachine::getcurrentVolume Aug 23 20:10:02 volumio volumio[6758]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:10:02 volumio volumio[6758]: info: CoreStateMachine::pushState Aug 23 20:10:02 volumio volumio[6758]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:02 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:10:02 volumio volumio[6758]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:02 volumio volumio[6758]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Aug 23 20:10:02 volumio volumio[6758]: wlan0 Interface doesn't support scanning : Resource temporarily unavailable Aug 23 20:10:02 volumio volumio[6758]: info: Cannot use regular scanning, forcing with ap-force Aug 23 20:10:02 volumio sudo[6829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Aug 23 20:10:02 volumio sudo[6829]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:03 volumio volumio[6758]: Starting albumart workers Aug 23 20:10:03 volumio volumio[6758]: Starting albumart workers Aug 23 20:10:03 volumio volumio[6758]: Starting albumart workers Aug 23 20:10:03 volumio volumio-remote-updater[598]: [2024-08-23 20:10:03] [connect] Successful connection Aug 23 20:10:05 volumio sudo[6829]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:05 volumio volumio-remote-updater[598]: [2024-08-23 20:10:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724415003 101 Aug 23 20:10:05 volumio volumio[6758]: 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 Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:05 volumio volumio[6758]: info: Reloading queue from file Aug 23 20:10:05 volumio volumio[6758]: info: CoreStateMachine::setRepeat null single undefined Aug 23 20:10:05 volumio volumio[6758]: info: CoreStateMachine::pushState Aug 23 20:10:05 volumio volumio[6758]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:05 volumio volumio[6758]: info: CoreStateMachine::setRandom null Aug 23 20:10:05 volumio volumio[6758]: info: CoreStateMachine::pushState Aug 23 20:10:05 volumio volumio[6758]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:05 volumio volumio[6758]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 23 20:10:05 volumio volumio[6758]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Aug 23 20:10:05 volumio volumio[6758]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 23 20:10:05 volumio volumio[6758]: info: Setting Device type: Raspberry PI Aug 23 20:10:05 volumio volumio[6758]: info: Completed loading Core Plugins Aug 23 20:10:05 volumio volumio[6758]: info: Preparing to generate the ALSA configuration file Aug 23 20:10:05 volumio volumio[6758]: info: Discovery: adding 92d514c2-05e0-4590-90ec-0ae0399380af Aug 23 20:10:05 volumio volumio[6758]: info: Discovery: Found device VOLUMIO Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::volumioGetState Aug 23 20:10:05 volumio volumio[6758]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:05 volumio volumio[6758]: info: Asound.conf file unchanged, so no further update is needed Aug 23 20:10:05 volumio volumio[6758]: info: Output device has changed, restarting MPD Aug 23 20:10:05 volumio sudo[6862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:10:05 volumio volumio[6758]: info: Output device has changed, restarting Shairport Sync Aug 23 20:10:05 volumio sudo[6862]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:05 volumio sudo[6862]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:05 volumio sudo[6864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:10:05 volumio sudo[6864]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:05 volumio systemd[1]: Stopping Music Player Daemon... Aug 23 20:10:05 volumio volumio[6758]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:05 volumio volumio[6758]: info: ___________ START PLUGINS ___________ Aug 23 20:10:05 volumio volumio[6758]: info: ControllerMpd::onStart: Initializing MPD Aug 23 20:10:05 volumio volumio[6758]: info: Creating MPD Configuration file Aug 23 20:10:05 volumio sudo[6871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:10:05 volumio sudo[6871]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:05 volumio sudo[6871]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:05 volumio sudo[6874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:10:05 volumio sudo[6874]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:05 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:10:05 volumio volumio[6758]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:05 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:05 volumio volumio[6758]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:10:05 volumio volumio[6758]: info: [1724415005643] CoreMusicLibrary::Adding element Last_100 Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:10:05 volumio volumio[6758]: info: [1724415005646] CoreMusicLibrary::Adding element Webradio Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:05 volumio volumio[6758]: info: Initializing BBC Radios Aug 23 20:10:05 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:05 volumio volumio[6758]: info: [squeezelite_mc] Starting proxy server... Aug 23 20:10:05 volumio volumio[6758]: info: Volumio Calling Home Aug 23 20:10:05 volumio sudo[6879]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 23 20:10:05 volumio sudo[6879]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:05 volumio sudo[6879]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:05 volumio volumio[6758]: info: [squeezelite_mc] Proxy server started on port 33537 Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:10:05 volumio volumio[6758]: info: CoreStateMachine::pushState Aug 23 20:10:05 volumio volumio[6758]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:05 volumio volumio[6758]: info: [squeezelite_mc] Server discovery started Aug 23 20:10:05 volumio volumio[6758]: info: [squeezelite_mc] Player finder started Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:05 volumio volumio[6758]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:05 volumio volumio[6758]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Aug 23 20:10:05 volumio volumio[6758]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:10:05 volumio volumio[6758]: Error: send ENETUNREACH 255.255.255.255:3483 Aug 23 20:10:05 volumio volumio[6758]: at doSend (dgram.js:692:16) Aug 23 20:10:05 volumio volumio[6758]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Aug 23 20:10:05 volumio volumio[6758]: at afterDns (dgram.js:638:5) Aug 23 20:10:05 volumio volumio[6758]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 23 20:10:05 volumio volumio[6758]: errno: -101, Aug 23 20:10:05 volumio volumio[6758]: code: 'ENETUNREACH', Aug 23 20:10:05 volumio volumio[6758]: syscall: 'send', Aug 23 20:10:05 volumio volumio[6758]: address: '255.255.255.255', Aug 23 20:10:05 volumio volumio[6758]: port: 3483 Aug 23 20:10:05 volumio volumio[6758]: } Aug 23 20:10:05 volumio volumio[6758]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:10:06 volumio sudo[6909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 20:09 Aug 23 20:10:06 volumio sudo[6909]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:06 volumio sudo[6909]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:06 volumio volumio-remote-updater[598]: [2024-08-23 20:10:06] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 23 20:10:06 volumio volumio-remote-updater[598]: [2024-08-23 20:10:06] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 23 20:10:06 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 23 20:10:06 volumio sudo[6864]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:06 volumio sudo[6874]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:06 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 23 20:10:06 volumio systemd[1]: Started dynamicswap service. Aug 23 20:10:06 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 23 20:10:06 volumio mpd[6896]: Aug 23 20:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 23 20:10:06 volumio systemd[1]: Started Music Player Daemon. Aug 23 20:10:06 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 23 20:10:06 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Aug 23 20:10:06 volumio systemd[1]: Stopped Volumio Backend Module. Aug 23 20:10:06 volumio systemd[1]: Started Volumio Backend Module. Aug 23 20:10:06 volumio systemd[1]: Started dynamicswap service. Aug 23 20:10:06 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 23 20:10:07 volumio volumio[6927]: info: ------------------------------------------- Aug 23 20:10:07 volumio volumio[6927]: info: ----- Volumio3 ---- Aug 23 20:10:07 volumio volumio[6927]: info: ------------------------------------------- Aug 23 20:10:07 volumio volumio[6927]: info: ----- System startup ---- Aug 23 20:10:07 volumio volumio[6927]: info: ------------------------------------------- Aug 23 20:10:07 volumio volumio[6927]: info: MYVOLUMIO Environment detected Aug 23 20:10:08 volumio volumio[6927]: info: Plugin folders cleanup Aug 23 20:10:08 volumio volumio[6927]: info: Scanning into folder /volumio/app/plugins/ Aug 23 20:10:08 volumio volumio[6927]: info: Scanning category audio_interface Aug 23 20:10:08 volumio volumio[6927]: info: Scanning category miscellanea Aug 23 20:10:08 volumio volumio[6927]: info: Scanning category music_service Aug 23 20:10:08 volumio volumio[6927]: info: Scanning category plugins.json Aug 23 20:10:08 volumio volumio[6927]: info: Scanning category system_controller Aug 23 20:10:08 volumio volumio[6927]: info: Scanning category user_interface Aug 23 20:10:08 volumio volumio[6927]: info: Scanning into folder /data/plugins/ Aug 23 20:10:08 volumio volumio[6927]: info: Scanning category music_service Aug 23 20:10:08 volumio volumio[6927]: info: Scanning category user_interface Aug 23 20:10:08 volumio volumio[6927]: info: Plugin folders cleanup completed Aug 23 20:10:08 volumio volumio[6927]: info: ------------------------------------------- Aug 23 20:10:08 volumio volumio[6927]: info: ----- Core plugins startup ---- Aug 23 20:10:08 volumio volumio[6927]: info: ------------------------------------------- Aug 23 20:10:08 volumio volumio[6927]: info: Loading plugins from folder /volumio/app/plugins/ Aug 23 20:10:08 volumio volumio[6927]: info: Adding plugin upnp to MyMusic Plugins Aug 23 20:10:08 volumio volumio[6927]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 23 20:10:08 volumio volumio[6927]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 23 20:10:08 volumio volumio[6927]: info: Loading plugins from folder /data/plugins/ Aug 23 20:10:08 volumio volumio[6927]: info: Loading plugin "system"... Aug 23 20:10:08 volumio volumio[6927]: info: Loading plugin "appearance"... Aug 23 20:10:08 volumio volumio[6927]: info: Loading plugin "network"... Aug 23 20:10:08 volumio volumio[6927]: info: Refreshing Cached IP Addresses Aug 23 20:10:08 volumio sudo[6954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 20:10:08 volumio sudo[6954]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:08 volumio sudo[6954]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:08 volumio sudo[6956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 20:10:08 volumio volumio[6927]: info: Loading plugin "services"... Aug 23 20:10:08 volumio sudo[6956]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:08 volumio volumio[6927]: info: Loading plugin "alsa_controller"... Aug 23 20:10:08 volumio sudo[6956]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:08 volumio sudo[6959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 23 20:10:08 volumio sudo[6959]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:08 volumio sudo[6959]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:08 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:08 volumio volumio[6927]: info: Loading plugin "wizard"... Aug 23 20:10:08 volumio volumio[6927]: info: Loading plugin "networkfs"... Aug 23 20:10:08 volumio volumio[6927]: info: Starting Udev Watcher for removable devices Aug 23 20:10:08 volumio volumio[6927]: info: Ignoring mount for partition: boot Aug 23 20:10:08 volumio volumio[6927]: info: Ignoring mount for partition: volumio Aug 23 20:10:08 volumio volumio[6927]: info: Ignoring mount for partition: volumio_data Aug 23 20:10:08 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:10:08 volumio volumio[6927]: info: Loading plugin "volumio_command_line_client"... Aug 23 20:10:08 volumio volumio[6927]: info: Plugin upnp is not enabled Aug 23 20:10:08 volumio volumio[6927]: info: Loading plugin "my_music"... Aug 23 20:10:08 volumio volumio[6927]: info: Loading plugin "mpd"... Aug 23 20:10:08 volumio volumio[6927]: info: Plugin upnp_browser is not enabled Aug 23 20:10:08 volumio volumio[6927]: info: Loading plugin "alarm-clock"... Aug 23 20:10:09 volumio volumio[6927]: info: Loading plugin "airplay_emulation"... Aug 23 20:10:09 volumio volumio[6927]: info: Starting Shairport Sync Aug 23 20:10:09 volumio volumio[6927]: info: Loading plugin "last_100"... Aug 23 20:10:09 volumio volumio[6927]: info: Loading plugin "webradio"... Aug 23 20:10:09 volumio volumio[6927]: info: Loading plugin "i2s_dacs"... Aug 23 20:10:09 volumio volumio[6927]: info: Loading plugin "volumiodiscovery"... Aug 23 20:10:09 volumio volumio[6927]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:09 volumio volumio[6927]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:09 volumio volumio[6927]: *** WARNING *** For more information see Aug 23 20:10:09 volumio node[6927]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:09 volumio volumio[6927]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:09 volumio volumio[6927]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:09 volumio volumio[6927]: *** WARNING *** For more information see Aug 23 20:10:09 volumio node[6927]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:09 volumio node[6927]: *** WARNING *** For more information see Aug 23 20:10:09 volumio node[6927]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:09 volumio node[6927]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:09 volumio node[6927]: *** WARNING *** For more information see Aug 23 20:10:09 volumio volumio[6927]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 23 20:10:09 volumio volumio[6927]: info: Discovery: Started advertising with name: VOLUMIO Aug 23 20:10:09 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:10:09 volumio volumio[6927]: info: Loading plugin "squeezelite_mc"... Aug 23 20:10:09 volumio volumio[6927]: info: Loading plugin "outputs"... Aug 23 20:10:09 volumio volumio[6927]: info: Loading plugin "albumart"... Aug 23 20:10:09 volumio volumio[6927]: info: Plugin example_plugin is not enabled Aug 23 20:10:09 volumio volumio[6927]: info: Loading plugin "inputs"... Aug 23 20:10:09 volumio volumio[6927]: info: Loading plugin "updater_comm"... Aug 23 20:10:09 volumio volumio[6927]: info: Plugin mpdemulation is not enabled Aug 23 20:10:09 volumio volumio[6927]: info: Loading plugin "rest_api"... Aug 23 20:10:09 volumio volumio[6927]: info: Loading plugin "websocket"... Aug 23 20:10:09 volumio volumio[6927]: info: Starting Socket.io Server version 2.3.0 Aug 23 20:10:09 volumio volumio[6927]: info: Loading plugin "Systeminfo"... Aug 23 20:10:09 volumio volumio[6927]: Forking 3 albumart workers Aug 23 20:10:10 volumio volumio[6927]: info: Loading i18n strings for locale en Aug 23 20:10:10 volumio volumio[6927]: Updating browse sources language Aug 23 20:10:10 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:10 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:10 volumio volumio[6927]: info: CoreCommandRouter::initPlayerControls Aug 23 20:10:10 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:10 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:10 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:10 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:10 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:10 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:10 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:10 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:10 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:10 volumio volumio[6927]: Express server listening on port 3000 Aug 23 20:10:10 volumio volumio[6927]: [Metrics] WebUI: 2s 911.33ms Aug 23 20:10:10 volumio volumio[6927]: info: CoreStateMachine::resetVolumioState Aug 23 20:10:10 volumio volumio[6927]: info: CoreStateMachine::getcurrentVolume Aug 23 20:10:10 volumio volumio[6927]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:10:10 volumio volumio[6927]: info: CoreStateMachine::pushState Aug 23 20:10:10 volumio volumio[6927]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:10 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:10:10 volumio volumio[6927]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:10 volumio volumio[6927]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Aug 23 20:10:10 volumio volumio[6927]: wlan0 Interface doesn't support scanning : Device or resource busy Aug 23 20:10:10 volumio volumio[6927]: info: Cannot use regular scanning, forcing with ap-force Aug 23 20:10:10 volumio sudo[6998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Aug 23 20:10:10 volumio sudo[6998]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:10 volumio volumio[6927]: Starting albumart workers Aug 23 20:10:10 volumio volumio[6927]: Starting albumart workers Aug 23 20:10:10 volumio volumio[6927]: Starting albumart workers Aug 23 20:10:11 volumio volumio-remote-updater[598]: [2024-08-23 20:10:11] [connect] Successful connection Aug 23 20:10:12 volumio sudo[6998]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:12 volumio volumio-remote-updater[598]: [2024-08-23 20:10:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724415011 101 Aug 23 20:10:12 volumio volumio[6927]: 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 Aug 23 20:10:12 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:12 volumio volumio[6927]: info: Reloading queue from file Aug 23 20:10:12 volumio volumio[6927]: info: CoreStateMachine::setRepeat null single undefined Aug 23 20:10:12 volumio volumio[6927]: info: CoreStateMachine::pushState Aug 23 20:10:12 volumio volumio[6927]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:12 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:10:12 volumio volumio[6927]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:12 volumio volumio[6927]: info: CoreStateMachine::setRandom null Aug 23 20:10:12 volumio volumio[6927]: info: CoreStateMachine::pushState Aug 23 20:10:12 volumio volumio[6927]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:12 volumio volumio[6927]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:12 volumio volumio[6927]: info: Setting Device type: Raspberry PI Aug 23 20:10:12 volumio volumio[6927]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 23 20:10:12 volumio volumio[6927]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Aug 23 20:10:12 volumio volumio[6927]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 23 20:10:12 volumio volumio[6927]: info: Completed loading Core Plugins Aug 23 20:10:12 volumio volumio[6927]: info: Preparing to generate the ALSA configuration file Aug 23 20:10:12 volumio volumio[6927]: info: Discovery: adding 92d514c2-05e0-4590-90ec-0ae0399380af Aug 23 20:10:12 volumio volumio[6927]: info: Discovery: Found device VOLUMIO Aug 23 20:10:12 volumio volumio[6927]: info: CoreCommandRouter::volumioGetState Aug 23 20:10:12 volumio volumio[6927]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:12 volumio volumio[6927]: info: Asound.conf file unchanged, so no further update is needed Aug 23 20:10:12 volumio volumio[6927]: info: Output device has changed, restarting MPD Aug 23 20:10:12 volumio sudo[7068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:10:12 volumio sudo[7068]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:12 volumio volumio[6927]: info: Output device has changed, restarting Shairport Sync Aug 23 20:10:12 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:12 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:12 volumio sudo[7068]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:12 volumio sudo[7070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:10:12 volumio sudo[7070]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:12 volumio systemd[1]: Stopping Music Player Daemon... Aug 23 20:10:12 volumio volumio[6927]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:12 volumio volumio[6927]: info: ___________ START PLUGINS ___________ Aug 23 20:10:12 volumio volumio[6927]: info: ControllerMpd::onStart: Initializing MPD Aug 23 20:10:12 volumio volumio[6927]: info: Creating MPD Configuration file Aug 23 20:10:12 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:10:12 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:10:12 volumio sudo[7078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:10:12 volumio sudo[7078]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:12 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:12 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:12 volumio sudo[7078]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:12 volumio sudo[7080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:10:12 volumio sudo[7080]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:12 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:10:12 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 23 20:10:12 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:10:12 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:10:12 volumio volumio[6927]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:12 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:12 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:10:12 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:12 volumio volumio[6927]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:13 volumio volumio[6927]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:10:13 volumio volumio[6927]: info: [1724415013001] CoreMusicLibrary::Adding element Last_100 Aug 23 20:10:13 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:13 volumio volumio[6927]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:10:13 volumio volumio[6927]: info: [1724415013004] CoreMusicLibrary::Adding element Webradio Aug 23 20:10:13 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:13 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:13 volumio volumio[6927]: info: Initializing BBC Radios Aug 23 20:10:13 volumio sudo[7087]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 23 20:10:13 volumio sudo[7087]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:13 volumio sudo[7087]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:13 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:13 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:13 volumio volumio[6927]: info: [squeezelite_mc] Starting proxy server... Aug 23 20:10:13 volumio volumio[6927]: info: Volumio Calling Home Aug 23 20:10:13 volumio volumio[6927]: info: [squeezelite_mc] Proxy server started on port 33639 Aug 23 20:10:13 volumio volumio[6927]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:10:13 volumio volumio[6927]: info: CoreStateMachine::pushState Aug 23 20:10:13 volumio volumio[6927]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:13 volumio volumio[6927]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:13 volumio wpa_supplicant[1085]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="botbot" Aug 23 20:10:13 volumio wpa_supplicant[1085]: wlan0: Trying to associate with SSID 'botbot' Aug 23 20:10:13 volumio volumio[6927]: info: [squeezelite_mc] Server discovery started Aug 23 20:10:13 volumio volumio[6927]: info: [squeezelite_mc] Player finder started Aug 23 20:10:13 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:13 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:13 volumio volumio[6927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:13 volumio volumio[6927]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Aug 23 20:10:13 volumio volumio[6927]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:10:13 volumio volumio[6927]: Error: send ENETUNREACH 255.255.255.255:3483 Aug 23 20:10:13 volumio volumio[6927]: at doSend (dgram.js:692:16) Aug 23 20:10:13 volumio volumio[6927]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Aug 23 20:10:13 volumio volumio[6927]: at afterDns (dgram.js:638:5) Aug 23 20:10:13 volumio volumio[6927]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 23 20:10:13 volumio volumio[6927]: errno: -101, Aug 23 20:10:13 volumio volumio[6927]: code: 'ENETUNREACH', Aug 23 20:10:13 volumio volumio[6927]: syscall: 'send', Aug 23 20:10:13 volumio volumio[6927]: address: '255.255.255.255', Aug 23 20:10:13 volumio volumio[6927]: port: 3483 Aug 23 20:10:13 volumio volumio[6927]: } Aug 23 20:10:13 volumio volumio[6927]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:10:13 volumio sudo[7118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 20:09 Aug 23 20:10:13 volumio sudo[7118]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:13 volumio sudo[7118]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:13 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 23 20:10:13 volumio volumio-remote-updater[598]: [2024-08-23 20:10:13] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 23 20:10:13 volumio volumio-remote-updater[598]: [2024-08-23 20:10:13] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 23 20:10:13 volumio sudo[7070]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:13 volumio sudo[7080]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:13 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 23 20:10:13 volumio systemd[1]: Started dynamicswap service. Aug 23 20:10:13 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 23 20:10:13 volumio mpd[7098]: Aug 23 20:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 23 20:10:13 volumio systemd[1]: Started Music Player Daemon. Aug 23 20:10:14 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 23 20:10:14 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Aug 23 20:10:14 volumio systemd[1]: Started dynamicswap service. Aug 23 20:10:14 volumio systemd[1]: Stopped Volumio Backend Module. Aug 23 20:10:14 volumio systemd[1]: Started Volumio Backend Module. Aug 23 20:10:14 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 23 20:10:15 volumio volumio[7136]: info: ------------------------------------------- Aug 23 20:10:15 volumio volumio[7136]: info: ----- Volumio3 ---- Aug 23 20:10:15 volumio volumio[7136]: info: ------------------------------------------- Aug 23 20:10:15 volumio volumio[7136]: info: ----- System startup ---- Aug 23 20:10:15 volumio volumio[7136]: info: ------------------------------------------- Aug 23 20:10:15 volumio volumio[7136]: info: MYVOLUMIO Environment detected Aug 23 20:10:15 volumio volumio[7136]: info: Plugin folders cleanup Aug 23 20:10:15 volumio volumio[7136]: info: Scanning into folder /volumio/app/plugins/ Aug 23 20:10:15 volumio volumio[7136]: info: Scanning category audio_interface Aug 23 20:10:15 volumio volumio[7136]: info: Scanning category miscellanea Aug 23 20:10:15 volumio volumio[7136]: info: Scanning category music_service Aug 23 20:10:15 volumio volumio[7136]: info: Scanning category plugins.json Aug 23 20:10:15 volumio volumio[7136]: info: Scanning category system_controller Aug 23 20:10:15 volumio volumio[7136]: info: Scanning category user_interface Aug 23 20:10:15 volumio volumio[7136]: info: Scanning into folder /data/plugins/ Aug 23 20:10:15 volumio volumio[7136]: info: Scanning category music_service Aug 23 20:10:15 volumio volumio[7136]: info: Scanning category user_interface Aug 23 20:10:15 volumio volumio[7136]: info: Plugin folders cleanup completed Aug 23 20:10:15 volumio volumio[7136]: info: ------------------------------------------- Aug 23 20:10:15 volumio volumio[7136]: info: ----- Core plugins startup ---- Aug 23 20:10:15 volumio volumio[7136]: info: ------------------------------------------- Aug 23 20:10:15 volumio volumio[7136]: info: Loading plugins from folder /volumio/app/plugins/ Aug 23 20:10:15 volumio volumio[7136]: info: Adding plugin upnp to MyMusic Plugins Aug 23 20:10:15 volumio volumio[7136]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 23 20:10:15 volumio volumio[7136]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 23 20:10:15 volumio volumio[7136]: info: Loading plugins from folder /data/plugins/ Aug 23 20:10:15 volumio volumio[7136]: info: Loading plugin "system"... Aug 23 20:10:15 volumio wpa_supplicant[1085]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Aug 23 20:10:15 volumio wpa_supplicant[1085]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="botbot" auth_failures=2 duration=23 reason=CONN_FAILED Aug 23 20:10:15 volumio volumio[7136]: info: Loading plugin "appearance"... Aug 23 20:10:16 volumio volumio[7136]: info: Loading plugin "network"... Aug 23 20:10:16 volumio volumio[7136]: info: Refreshing Cached IP Addresses Aug 23 20:10:16 volumio sudo[7162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 20:10:16 volumio sudo[7162]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:16 volumio sudo[7162]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:16 volumio sudo[7164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 20:10:16 volumio volumio[7136]: info: Loading plugin "services"... Aug 23 20:10:16 volumio sudo[7164]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:16 volumio volumio[7136]: info: Loading plugin "alsa_controller"... Aug 23 20:10:16 volumio sudo[7164]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:16 volumio sudo[7167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 23 20:10:16 volumio sudo[7167]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:16 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:16 volumio volumio[7136]: info: Loading plugin "wizard"... Aug 23 20:10:16 volumio volumio[7136]: info: Loading plugin "networkfs"... Aug 23 20:10:16 volumio volumio[7136]: info: Starting Udev Watcher for removable devices Aug 23 20:10:16 volumio volumio[7136]: info: Ignoring mount for partition: boot Aug 23 20:10:16 volumio volumio[7136]: info: Ignoring mount for partition: volumio Aug 23 20:10:16 volumio volumio[7136]: info: Ignoring mount for partition: volumio_data Aug 23 20:10:16 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:10:16 volumio volumio[7136]: info: Loading plugin "volumio_command_line_client"... Aug 23 20:10:16 volumio volumio[7136]: info: Plugin upnp is not enabled Aug 23 20:10:16 volumio volumio[7136]: info: Loading plugin "my_music"... Aug 23 20:10:16 volumio volumio[7136]: info: Loading plugin "mpd"... Aug 23 20:10:16 volumio volumio[7136]: info: Plugin upnp_browser is not enabled Aug 23 20:10:16 volumio volumio[7136]: info: Loading plugin "alarm-clock"... Aug 23 20:10:16 volumio volumio[7136]: info: Loading plugin "airplay_emulation"... Aug 23 20:10:16 volumio volumio[7136]: info: Starting Shairport Sync Aug 23 20:10:16 volumio volumio[7136]: info: Loading plugin "last_100"... Aug 23 20:10:16 volumio volumio[7136]: info: Loading plugin "webradio"... Aug 23 20:10:16 volumio volumio[7136]: info: Loading plugin "i2s_dacs"... Aug 23 20:10:16 volumio volumio[7136]: info: Loading plugin "volumiodiscovery"... Aug 23 20:10:16 volumio volumio[7136]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:16 volumio volumio[7136]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:16 volumio volumio[7136]: *** WARNING *** For more information see Aug 23 20:10:16 volumio node[7136]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:16 volumio volumio[7136]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:16 volumio volumio[7136]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:16 volumio volumio[7136]: *** WARNING *** For more information see Aug 23 20:10:16 volumio node[7136]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:16 volumio node[7136]: *** WARNING *** For more information see Aug 23 20:10:16 volumio node[7136]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:16 volumio node[7136]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:16 volumio node[7136]: *** WARNING *** For more information see Aug 23 20:10:16 volumio volumio[7136]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 23 20:10:16 volumio volumio[7136]: info: Discovery: Started advertising with name: VOLUMIO Aug 23 20:10:16 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:10:16 volumio volumio[7136]: info: Loading plugin "squeezelite_mc"... Aug 23 20:10:17 volumio volumio[7136]: info: Loading plugin "outputs"... Aug 23 20:10:17 volumio volumio[7136]: info: Loading plugin "albumart"... Aug 23 20:10:17 volumio volumio[7136]: info: Plugin example_plugin is not enabled Aug 23 20:10:17 volumio volumio[7136]: info: Loading plugin "inputs"... Aug 23 20:10:17 volumio volumio[7136]: info: Loading plugin "updater_comm"... Aug 23 20:10:17 volumio volumio[7136]: info: Plugin mpdemulation is not enabled Aug 23 20:10:17 volumio volumio[7136]: info: Loading plugin "rest_api"... Aug 23 20:10:17 volumio volumio[7136]: info: Loading plugin "websocket"... Aug 23 20:10:17 volumio volumio[7136]: info: Starting Socket.io Server version 2.3.0 Aug 23 20:10:17 volumio volumio[7136]: info: Loading plugin "Systeminfo"... Aug 23 20:10:17 volumio volumio[7136]: Forking 3 albumart workers Aug 23 20:10:17 volumio volumio[7136]: info: Loading i18n strings for locale en Aug 23 20:10:17 volumio volumio[7136]: Updating browse sources language Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::initPlayerControls Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:17 volumio volumio[7136]: Express server listening on port 3000 Aug 23 20:10:17 volumio volumio[7136]: [Metrics] WebUI: 2s 980.89ms Aug 23 20:10:17 volumio volumio[7136]: info: CoreStateMachine::resetVolumioState Aug 23 20:10:17 volumio volumio[7136]: info: CoreStateMachine::getcurrentVolume Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:10:17 volumio volumio[7136]: info: CoreStateMachine::pushState Aug 23 20:10:17 volumio volumio[7136]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:17 volumio volumio[7136]: info: Reloading queue from file Aug 23 20:10:17 volumio volumio[7136]: info: CoreStateMachine::setRepeat null single undefined Aug 23 20:10:17 volumio volumio[7136]: info: CoreStateMachine::pushState Aug 23 20:10:17 volumio volumio[7136]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:17 volumio volumio[7136]: info: CoreStateMachine::setRandom null Aug 23 20:10:17 volumio volumio[7136]: info: CoreStateMachine::pushState Aug 23 20:10:17 volumio volumio[7136]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:17 volumio volumio[7136]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 23 20:10:17 volumio volumio[7136]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Aug 23 20:10:17 volumio volumio[7136]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 23 20:10:17 volumio volumio[7136]: info: Setting Device type: Raspberry PI Aug 23 20:10:17 volumio volumio[7136]: info: Completed loading Core Plugins Aug 23 20:10:17 volumio volumio[7136]: info: Preparing to generate the ALSA configuration file Aug 23 20:10:17 volumio volumio[7136]: info: Discovery: adding 92d514c2-05e0-4590-90ec-0ae0399380af Aug 23 20:10:17 volumio volumio[7136]: info: Discovery: Found device VOLUMIO Aug 23 20:10:17 volumio volumio[7136]: info: CoreCommandRouter::volumioGetState Aug 23 20:10:17 volumio volumio[7136]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:17 volumio volumio[7136]: info: Asound.conf file unchanged, so no further update is needed Aug 23 20:10:17 volumio volumio[7136]: info: Output device has changed, restarting MPD Aug 23 20:10:18 volumio volumio[7136]: info: Output device has changed, restarting Shairport Sync Aug 23 20:10:18 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:18 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:18 volumio sudo[7212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:10:18 volumio sudo[7212]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:18 volumio sudo[7213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:10:18 volumio sudo[7213]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:18 volumio sudo[7212]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:18 volumio systemd[1]: Stopping Music Player Daemon... Aug 23 20:10:18 volumio volumio[7136]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:18 volumio volumio[7136]: info: ___________ START PLUGINS ___________ Aug 23 20:10:18 volumio volumio[7136]: info: ControllerMpd::onStart: Initializing MPD Aug 23 20:10:18 volumio volumio[7136]: info: Creating MPD Configuration file Aug 23 20:10:18 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:10:18 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:10:18 volumio volumio[7136]: Starting albumart workers Aug 23 20:10:18 volumio volumio[7136]: Starting albumart workers Aug 23 20:10:18 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:18 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:18 volumio volumio[7136]: Starting albumart workers Aug 23 20:10:18 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:10:18 volumio sudo[7224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:10:18 volumio sudo[7224]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:18 volumio sudo[7222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:10:18 volumio sudo[7222]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:18 volumio sudo[7222]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:18 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 23 20:10:18 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:10:18 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:10:18 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:10:18 volumio volumio[7136]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:18 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:18 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:18 volumio volumio[7136]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:18 volumio volumio[7136]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:10:18 volumio volumio[7136]: info: [1724415018301] CoreMusicLibrary::Adding element Last_100 Aug 23 20:10:18 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:18 volumio sudo[7229]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 23 20:10:18 volumio volumio[7136]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:10:18 volumio sudo[7229]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:18 volumio volumio[7136]: info: [1724415018304] CoreMusicLibrary::Adding element Webradio Aug 23 20:10:18 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:18 volumio sudo[7229]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:18 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:18 volumio volumio[7136]: info: Initializing BBC Radios Aug 23 20:10:18 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:18 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:18 volumio volumio[7136]: info: [squeezelite_mc] Starting proxy server... Aug 23 20:10:18 volumio volumio[7136]: info: Volumio Calling Home Aug 23 20:10:18 volumio volumio[7136]: info: [squeezelite_mc] Proxy server started on port 45071 Aug 23 20:10:18 volumio volumio[7136]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:10:18 volumio volumio[7136]: info: CoreStateMachine::pushState Aug 23 20:10:18 volumio volumio[7136]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:18 volumio volumio[7136]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:18 volumio volumio[7136]: info: [squeezelite_mc] Server discovery started Aug 23 20:10:18 volumio volumio[7136]: info: [squeezelite_mc] Player finder started Aug 23 20:10:18 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:18 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:18 volumio volumio[7136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:18 volumio volumio[7136]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Aug 23 20:10:18 volumio volumio[7136]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:10:18 volumio sudo[7167]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:18 volumio volumio[7136]: Error: send ENETUNREACH 255.255.255.255:3483 Aug 23 20:10:18 volumio volumio[7136]: at doSend (dgram.js:692:16) Aug 23 20:10:18 volumio volumio[7136]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Aug 23 20:10:18 volumio volumio[7136]: at afterDns (dgram.js:638:5) Aug 23 20:10:18 volumio volumio[7136]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 23 20:10:18 volumio volumio[7136]: errno: -101, Aug 23 20:10:18 volumio volumio[7136]: code: 'ENETUNREACH', Aug 23 20:10:18 volumio volumio[7136]: syscall: 'send', Aug 23 20:10:18 volumio volumio[7136]: address: '255.255.255.255', Aug 23 20:10:18 volumio volumio[7136]: port: 3483 Aug 23 20:10:18 volumio volumio[7136]: } Aug 23 20:10:18 volumio volumio[7136]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:10:18 volumio volumio-remote-updater[598]: [2024-08-23 20:10:18] [connect] Successful connection Aug 23 20:10:19 volumio sudo[7283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 20:09 Aug 23 20:10:19 volumio sudo[7283]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:19 volumio sudo[7283]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:19 volumio mpd[7234]: Aug 23 20:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 23 20:10:19 volumio systemd[1]: Started Music Player Daemon. Aug 23 20:10:19 volumio sudo[7213]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:19 volumio sudo[7224]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:19 volumio volumio-remote-updater[598]: [2024-08-23 20:10:19] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Aug 23 20:10:19 volumio volumio-remote-updater[598]: [2024-08-23 20:10:19] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Aug 23 20:10:19 volumio volumio-remote-updater[598]: [2024-08-23 20:10:19] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 23 20:10:19 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 23 20:10:19 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 23 20:10:19 volumio systemd[1]: Started dynamicswap service. Aug 23 20:10:19 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 23 20:10:19 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 23 20:10:19 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Aug 23 20:10:19 volumio systemd[1]: Started dynamicswap service. Aug 23 20:10:19 volumio systemd[1]: Stopped Volumio Backend Module. Aug 23 20:10:19 volumio systemd[1]: Started Volumio Backend Module. Aug 23 20:10:19 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 23 20:10:20 volumio volumio[7300]: info: ------------------------------------------- Aug 23 20:10:20 volumio volumio[7300]: info: ----- Volumio3 ---- Aug 23 20:10:20 volumio volumio[7300]: info: ------------------------------------------- Aug 23 20:10:20 volumio volumio[7300]: info: ----- System startup ---- Aug 23 20:10:20 volumio volumio[7300]: info: ------------------------------------------- Aug 23 20:10:21 volumio volumio[7300]: info: MYVOLUMIO Environment detected Aug 23 20:10:21 volumio volumio[7300]: info: Plugin folders cleanup Aug 23 20:10:21 volumio volumio[7300]: info: Scanning into folder /volumio/app/plugins/ Aug 23 20:10:21 volumio volumio[7300]: info: Scanning category audio_interface Aug 23 20:10:21 volumio volumio[7300]: info: Scanning category miscellanea Aug 23 20:10:21 volumio volumio[7300]: info: Scanning category music_service Aug 23 20:10:21 volumio volumio[7300]: info: Scanning category plugins.json Aug 23 20:10:21 volumio volumio[7300]: info: Scanning category system_controller Aug 23 20:10:21 volumio volumio[7300]: info: Scanning category user_interface Aug 23 20:10:21 volumio volumio[7300]: info: Scanning into folder /data/plugins/ Aug 23 20:10:21 volumio volumio[7300]: info: Scanning category music_service Aug 23 20:10:21 volumio volumio[7300]: info: Scanning category user_interface Aug 23 20:10:21 volumio volumio[7300]: info: Plugin folders cleanup completed Aug 23 20:10:21 volumio volumio[7300]: info: ------------------------------------------- Aug 23 20:10:21 volumio volumio[7300]: info: ----- Core plugins startup ---- Aug 23 20:10:21 volumio volumio[7300]: info: ------------------------------------------- Aug 23 20:10:21 volumio volumio[7300]: info: Loading plugins from folder /volumio/app/plugins/ Aug 23 20:10:21 volumio volumio[7300]: info: Adding plugin upnp to MyMusic Plugins Aug 23 20:10:21 volumio volumio[7300]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 23 20:10:21 volumio volumio[7300]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 23 20:10:21 volumio volumio[7300]: info: Loading plugins from folder /data/plugins/ Aug 23 20:10:21 volumio volumio[7300]: info: Loading plugin "system"... Aug 23 20:10:21 volumio volumio[7300]: info: Loading plugin "appearance"... Aug 23 20:10:21 volumio volumio[7300]: info: Loading plugin "network"... Aug 23 20:10:21 volumio volumio[7300]: info: Refreshing Cached IP Addresses Aug 23 20:10:21 volumio sudo[7326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 20:10:21 volumio sudo[7326]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:21 volumio sudo[7326]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:21 volumio sudo[7328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 20:10:21 volumio sudo[7328]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:21 volumio volumio[7300]: info: Loading plugin "services"... Aug 23 20:10:21 volumio volumio[7300]: info: Loading plugin "alsa_controller"... Aug 23 20:10:21 volumio sudo[7328]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:21 volumio sudo[7331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 23 20:10:21 volumio sudo[7331]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:21 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:21 volumio volumio[7300]: info: Loading plugin "wizard"... Aug 23 20:10:21 volumio volumio[7300]: info: Loading plugin "networkfs"... Aug 23 20:10:21 volumio volumio[7300]: info: Starting Udev Watcher for removable devices Aug 23 20:10:21 volumio volumio[7300]: info: Ignoring mount for partition: boot Aug 23 20:10:21 volumio volumio[7300]: info: Ignoring mount for partition: volumio Aug 23 20:10:21 volumio volumio[7300]: info: Ignoring mount for partition: volumio_data Aug 23 20:10:21 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:10:21 volumio volumio[7300]: info: Loading plugin "volumio_command_line_client"... Aug 23 20:10:21 volumio volumio[7300]: info: Plugin upnp is not enabled Aug 23 20:10:21 volumio volumio[7300]: info: Loading plugin "my_music"... Aug 23 20:10:21 volumio volumio[7300]: info: Loading plugin "mpd"... Aug 23 20:10:22 volumio volumio[7300]: info: Plugin upnp_browser is not enabled Aug 23 20:10:22 volumio volumio[7300]: info: Loading plugin "alarm-clock"... Aug 23 20:10:22 volumio volumio[7300]: info: Loading plugin "airplay_emulation"... Aug 23 20:10:22 volumio volumio[7300]: info: Starting Shairport Sync Aug 23 20:10:22 volumio volumio[7300]: info: Loading plugin "last_100"... Aug 23 20:10:22 volumio volumio[7300]: info: Loading plugin "webradio"... Aug 23 20:10:22 volumio volumio[7300]: info: Loading plugin "i2s_dacs"... Aug 23 20:10:22 volumio volumio[7300]: info: Loading plugin "volumiodiscovery"... Aug 23 20:10:22 volumio volumio[7300]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:22 volumio volumio[7300]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:22 volumio volumio[7300]: *** WARNING *** For more information see Aug 23 20:10:22 volumio node[7300]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:22 volumio volumio[7300]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:22 volumio volumio[7300]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:22 volumio volumio[7300]: *** WARNING *** For more information see Aug 23 20:10:22 volumio node[7300]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:22 volumio node[7300]: *** WARNING *** For more information see Aug 23 20:10:22 volumio node[7300]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:22 volumio node[7300]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:22 volumio node[7300]: *** WARNING *** For more information see Aug 23 20:10:22 volumio volumio[7300]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 23 20:10:22 volumio volumio[7300]: info: Discovery: Started advertising with name: VOLUMIO Aug 23 20:10:22 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:10:22 volumio volumio[7300]: info: Loading plugin "squeezelite_mc"... Aug 23 20:10:22 volumio volumio[7300]: info: Loading plugin "outputs"... Aug 23 20:10:22 volumio volumio[7300]: info: Loading plugin "albumart"... Aug 23 20:10:22 volumio volumio[7300]: info: Plugin example_plugin is not enabled Aug 23 20:10:22 volumio volumio[7300]: info: Loading plugin "inputs"... Aug 23 20:10:22 volumio volumio[7300]: info: Loading plugin "updater_comm"... Aug 23 20:10:22 volumio volumio[7300]: info: Plugin mpdemulation is not enabled Aug 23 20:10:22 volumio volumio[7300]: info: Loading plugin "rest_api"... Aug 23 20:10:22 volumio volumio[7300]: info: Loading plugin "websocket"... Aug 23 20:10:22 volumio volumio[7300]: info: Starting Socket.io Server version 2.3.0 Aug 23 20:10:22 volumio volumio[7300]: info: Loading plugin "Systeminfo"... Aug 23 20:10:22 volumio volumio[7300]: Forking 3 albumart workers Aug 23 20:10:23 volumio volumio[7300]: info: Loading i18n strings for locale en Aug 23 20:10:23 volumio volumio[7300]: Updating browse sources language Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::initPlayerControls Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:23 volumio volumio[7300]: Express server listening on port 3000 Aug 23 20:10:23 volumio volumio[7300]: [Metrics] WebUI: 2s 946.83ms Aug 23 20:10:23 volumio volumio[7300]: info: CoreStateMachine::resetVolumioState Aug 23 20:10:23 volumio volumio[7300]: info: CoreStateMachine::getcurrentVolume Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:10:23 volumio volumio[7300]: info: CoreStateMachine::pushState Aug 23 20:10:23 volumio volumio[7300]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:23 volumio volumio[7300]: info: Reloading queue from file Aug 23 20:10:23 volumio volumio[7300]: info: CoreStateMachine::setRepeat null single undefined Aug 23 20:10:23 volumio volumio[7300]: info: CoreStateMachine::pushState Aug 23 20:10:23 volumio volumio[7300]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:23 volumio volumio[7300]: info: CoreStateMachine::setRandom null Aug 23 20:10:23 volumio volumio[7300]: info: CoreStateMachine::pushState Aug 23 20:10:23 volumio volumio[7300]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:23 volumio volumio[7300]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 23 20:10:23 volumio volumio[7300]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Aug 23 20:10:23 volumio volumio[7300]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 23 20:10:23 volumio volumio[7300]: info: Setting Device type: Raspberry PI Aug 23 20:10:23 volumio volumio[7300]: info: Completed loading Core Plugins Aug 23 20:10:23 volumio volumio[7300]: info: Preparing to generate the ALSA configuration file Aug 23 20:10:23 volumio volumio[7300]: info: Asound.conf file unchanged, so no further update is needed Aug 23 20:10:23 volumio volumio[7300]: info: Output device has changed, restarting MPD Aug 23 20:10:23 volumio sudo[7377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:10:23 volumio sudo[7377]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:23 volumio volumio[7300]: info: Output device has changed, restarting Shairport Sync Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:23 volumio sudo[7377]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:23 volumio sudo[7379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:10:23 volumio sudo[7379]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:23 volumio volumio[7300]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:23 volumio volumio[7300]: info: ___________ START PLUGINS ___________ Aug 23 20:10:23 volumio volumio[7300]: info: ControllerMpd::onStart: Initializing MPD Aug 23 20:10:23 volumio volumio[7300]: info: Creating MPD Configuration file Aug 23 20:10:23 volumio volumio[7300]: Starting albumart workers Aug 23 20:10:23 volumio systemd[1]: Stopping Music Player Daemon... Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:23 volumio volumio[7300]: Starting albumart workers Aug 23 20:10:23 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:10:23 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:10:23 volumio sudo[7386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:10:23 volumio sudo[7386]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:23 volumio volumio[7300]: Starting albumart workers Aug 23 20:10:23 volumio sudo[7386]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:23 volumio sudo[7387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:10:23 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:10:23 volumio sudo[7387]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:23 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 23 20:10:23 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:10:23 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:10:23 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:10:23 volumio volumio[7300]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:23 volumio sudo[7394]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 23 20:10:23 volumio sudo[7394]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:23 volumio sudo[7394]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:23 volumio volumio[7300]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:10:23 volumio volumio[7300]: info: [1724415023881] CoreMusicLibrary::Adding element Last_100 Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:10:23 volumio volumio[7300]: info: [1724415023885] CoreMusicLibrary::Adding element Webradio Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:23 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:23 volumio volumio[7300]: info: Initializing BBC Radios Aug 23 20:10:24 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:24 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:24 volumio volumio[7300]: info: [squeezelite_mc] Starting proxy server... Aug 23 20:10:24 volumio volumio[7300]: info: Volumio Calling Home Aug 23 20:10:24 volumio sudo[7331]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:24 volumio volumio[7300]: info: [squeezelite_mc] Proxy server started on port 42375 Aug 23 20:10:24 volumio volumio[7300]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:10:24 volumio volumio[7300]: info: CoreStateMachine::pushState Aug 23 20:10:24 volumio volumio[7300]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:24 volumio volumio[7300]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:24 volumio volumio[7300]: info: [squeezelite_mc] Server discovery started Aug 23 20:10:24 volumio volumio[7300]: info: [squeezelite_mc] Player finder started Aug 23 20:10:24 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:24 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:24 volumio volumio[7300]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:24 volumio volumio[7300]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Aug 23 20:10:24 volumio volumio[7300]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:10:24 volumio volumio[7300]: Error: send ENETUNREACH 255.255.255.255:3483 Aug 23 20:10:24 volumio volumio[7300]: at doSend (dgram.js:692:16) Aug 23 20:10:24 volumio volumio[7300]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Aug 23 20:10:24 volumio volumio[7300]: at afterDns (dgram.js:638:5) Aug 23 20:10:24 volumio volumio[7300]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 23 20:10:24 volumio volumio[7300]: errno: -101, Aug 23 20:10:24 volumio volumio[7300]: code: 'ENETUNREACH', Aug 23 20:10:24 volumio volumio[7300]: syscall: 'send', Aug 23 20:10:24 volumio volumio[7300]: address: '255.255.255.255', Aug 23 20:10:24 volumio volumio[7300]: port: 3483 Aug 23 20:10:24 volumio volumio[7300]: } Aug 23 20:10:24 volumio volumio[7300]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:10:24 volumio sudo[7449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 20:09 Aug 23 20:10:24 volumio sudo[7449]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:24 volumio mpd[7399]: Aug 23 20:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 23 20:10:24 volumio systemd[1]: Started Music Player Daemon. Aug 23 20:10:24 volumio sudo[7379]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:24 volumio sudo[7387]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:24 volumio sudo[7449]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:24 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 23 20:10:24 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 23 20:10:24 volumio systemd[1]: Started dynamicswap service. Aug 23 20:10:24 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 23 20:10:25 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 23 20:10:25 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Aug 23 20:10:25 volumio systemd[1]: Started dynamicswap service. Aug 23 20:10:25 volumio systemd[1]: Stopped Volumio Backend Module. Aug 23 20:10:25 volumio systemd[1]: Started Volumio Backend Module. Aug 23 20:10:25 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 23 20:10:26 volumio volumio[7466]: info: ------------------------------------------- Aug 23 20:10:26 volumio volumio[7466]: info: ----- Volumio3 ---- Aug 23 20:10:26 volumio volumio[7466]: info: ------------------------------------------- Aug 23 20:10:26 volumio volumio[7466]: info: ----- System startup ---- Aug 23 20:10:26 volumio volumio[7466]: info: ------------------------------------------- Aug 23 20:10:26 volumio volumio[7466]: info: MYVOLUMIO Environment detected Aug 23 20:10:26 volumio volumio[7466]: info: Plugin folders cleanup Aug 23 20:10:26 volumio volumio[7466]: info: Scanning into folder /volumio/app/plugins/ Aug 23 20:10:26 volumio volumio[7466]: info: Scanning category audio_interface Aug 23 20:10:26 volumio volumio[7466]: info: Scanning category miscellanea Aug 23 20:10:26 volumio volumio[7466]: info: Scanning category music_service Aug 23 20:10:26 volumio volumio[7466]: info: Scanning category plugins.json Aug 23 20:10:26 volumio volumio[7466]: info: Scanning category system_controller Aug 23 20:10:26 volumio volumio[7466]: info: Scanning category user_interface Aug 23 20:10:26 volumio volumio[7466]: info: Scanning into folder /data/plugins/ Aug 23 20:10:26 volumio volumio[7466]: info: Scanning category music_service Aug 23 20:10:26 volumio volumio[7466]: info: Scanning category user_interface Aug 23 20:10:26 volumio volumio[7466]: info: Plugin folders cleanup completed Aug 23 20:10:26 volumio volumio[7466]: info: ------------------------------------------- Aug 23 20:10:26 volumio volumio[7466]: info: ----- Core plugins startup ---- Aug 23 20:10:26 volumio volumio[7466]: info: ------------------------------------------- Aug 23 20:10:26 volumio volumio[7466]: info: Loading plugins from folder /volumio/app/plugins/ Aug 23 20:10:26 volumio volumio[7466]: info: Adding plugin upnp to MyMusic Plugins Aug 23 20:10:26 volumio volumio[7466]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 23 20:10:26 volumio volumio[7466]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 23 20:10:26 volumio volumio[7466]: info: Loading plugins from folder /data/plugins/ Aug 23 20:10:26 volumio volumio[7466]: info: Loading plugin "system"... Aug 23 20:10:26 volumio volumio[7466]: info: Loading plugin "appearance"... Aug 23 20:10:26 volumio volumio-remote-updater[598]: [2024-08-23 20:10:26] [connect] Successful connection Aug 23 20:10:27 volumio volumio[7466]: info: Loading plugin "network"... Aug 23 20:10:27 volumio volumio[7466]: info: Refreshing Cached IP Addresses Aug 23 20:10:27 volumio sudo[7492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 20:10:27 volumio sudo[7492]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:27 volumio sudo[7492]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:27 volumio sudo[7494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 20:10:27 volumio volumio[7466]: info: Loading plugin "services"... Aug 23 20:10:27 volumio sudo[7494]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:27 volumio volumio[7466]: info: Loading plugin "alsa_controller"... Aug 23 20:10:27 volumio sudo[7494]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:27 volumio sudo[7497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 23 20:10:27 volumio sudo[7497]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:27 volumio sudo[7497]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:27 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:27 volumio volumio[7466]: info: Loading plugin "wizard"... Aug 23 20:10:27 volumio volumio[7466]: info: Loading plugin "networkfs"... Aug 23 20:10:27 volumio volumio[7466]: info: Starting Udev Watcher for removable devices Aug 23 20:10:27 volumio volumio[7466]: info: Ignoring mount for partition: boot Aug 23 20:10:27 volumio volumio[7466]: info: Ignoring mount for partition: volumio Aug 23 20:10:27 volumio volumio[7466]: info: Ignoring mount for partition: volumio_data Aug 23 20:10:27 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:10:27 volumio volumio[7466]: info: Loading plugin "volumio_command_line_client"... Aug 23 20:10:27 volumio volumio[7466]: info: Plugin upnp is not enabled Aug 23 20:10:27 volumio volumio[7466]: info: Loading plugin "my_music"... Aug 23 20:10:27 volumio volumio[7466]: info: Loading plugin "mpd"... Aug 23 20:10:27 volumio volumio[7466]: info: Plugin upnp_browser is not enabled Aug 23 20:10:27 volumio volumio[7466]: info: Loading plugin "alarm-clock"... Aug 23 20:10:27 volumio volumio[7466]: info: Loading plugin "airplay_emulation"... Aug 23 20:10:27 volumio volumio[7466]: info: Starting Shairport Sync Aug 23 20:10:27 volumio volumio[7466]: info: Loading plugin "last_100"... Aug 23 20:10:27 volumio volumio[7466]: info: Loading plugin "webradio"... Aug 23 20:10:27 volumio volumio[7466]: info: Loading plugin "i2s_dacs"... Aug 23 20:10:27 volumio volumio[7466]: info: Loading plugin "volumiodiscovery"... Aug 23 20:10:27 volumio volumio[7466]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:27 volumio volumio[7466]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:27 volumio volumio[7466]: *** WARNING *** For more information see Aug 23 20:10:27 volumio node[7466]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:27 volumio volumio[7466]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:27 volumio volumio[7466]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:27 volumio volumio[7466]: *** WARNING *** For more information see Aug 23 20:10:27 volumio node[7466]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:27 volumio node[7466]: *** WARNING *** For more information see Aug 23 20:10:27 volumio node[7466]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:27 volumio node[7466]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:27 volumio node[7466]: *** WARNING *** For more information see Aug 23 20:10:27 volumio volumio[7466]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 23 20:10:27 volumio volumio[7466]: info: Discovery: Started advertising with name: VOLUMIO Aug 23 20:10:27 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:10:27 volumio volumio[7466]: info: Loading plugin "squeezelite_mc"... Aug 23 20:10:28 volumio volumio[7466]: info: Loading plugin "outputs"... Aug 23 20:10:28 volumio volumio[7466]: info: Loading plugin "albumart"... Aug 23 20:10:28 volumio volumio[7466]: info: Plugin example_plugin is not enabled Aug 23 20:10:28 volumio volumio[7466]: info: Loading plugin "inputs"... Aug 23 20:10:28 volumio volumio[7466]: info: Loading plugin "updater_comm"... Aug 23 20:10:28 volumio volumio[7466]: info: Plugin mpdemulation is not enabled Aug 23 20:10:28 volumio volumio[7466]: info: Loading plugin "rest_api"... Aug 23 20:10:28 volumio volumio[7466]: info: Loading plugin "websocket"... Aug 23 20:10:28 volumio volumio[7466]: info: Starting Socket.io Server version 2.3.0 Aug 23 20:10:28 volumio volumio[7466]: info: Loading plugin "Systeminfo"... Aug 23 20:10:28 volumio volumio[7466]: Forking 3 albumart workers Aug 23 20:10:28 volumio volumio[7466]: info: Loading i18n strings for locale en Aug 23 20:10:28 volumio volumio[7466]: Updating browse sources language Aug 23 20:10:28 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:28 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:28 volumio volumio[7466]: info: CoreCommandRouter::initPlayerControls Aug 23 20:10:28 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:28 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:28 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:28 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:28 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:28 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:28 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:28 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:28 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:28 volumio volumio[7466]: Express server listening on port 3000 Aug 23 20:10:28 volumio volumio[7466]: [Metrics] WebUI: 2s 933.32ms Aug 23 20:10:28 volumio volumio[7466]: info: CoreStateMachine::resetVolumioState Aug 23 20:10:28 volumio volumio[7466]: info: CoreStateMachine::getcurrentVolume Aug 23 20:10:28 volumio volumio[7466]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:10:28 volumio volumio[7466]: info: CoreStateMachine::pushState Aug 23 20:10:28 volumio volumio[7466]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:28 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:10:28 volumio volumio[7466]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:28 volumio volumio[7466]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Aug 23 20:10:28 volumio volumio[7466]: wlan0 Interface doesn't support scanning : Device or resource busy Aug 23 20:10:28 volumio volumio[7466]: info: Cannot use regular scanning, forcing with ap-force Aug 23 20:10:28 volumio sudo[7536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Aug 23 20:10:28 volumio sudo[7536]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:28 volumio volumio[7466]: Starting albumart workers Aug 23 20:10:29 volumio volumio[7466]: Starting albumart workers Aug 23 20:10:29 volumio volumio[7466]: Starting albumart workers Aug 23 20:10:31 volumio sudo[7536]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:31 volumio volumio-remote-updater[598]: [2024-08-23 20:10:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724415026 101 Aug 23 20:10:31 volumio volumio[7466]: 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 Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:31 volumio volumio[7466]: info: Reloading queue from file Aug 23 20:10:31 volumio volumio[7466]: info: CoreStateMachine::setRepeat null single undefined Aug 23 20:10:31 volumio volumio[7466]: info: CoreStateMachine::pushState Aug 23 20:10:31 volumio volumio[7466]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:31 volumio volumio[7466]: info: CoreStateMachine::setRandom null Aug 23 20:10:31 volumio volumio[7466]: info: CoreStateMachine::pushState Aug 23 20:10:31 volumio volumio[7466]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:31 volumio volumio[7466]: info: Setting Device type: Raspberry PI Aug 23 20:10:31 volumio volumio[7466]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 23 20:10:31 volumio volumio[7466]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Aug 23 20:10:31 volumio volumio[7466]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 23 20:10:31 volumio volumio[7466]: info: Completed loading Core Plugins Aug 23 20:10:31 volumio volumio[7466]: info: Preparing to generate the ALSA configuration file Aug 23 20:10:31 volumio volumio[7466]: info: Discovery: adding 92d514c2-05e0-4590-90ec-0ae0399380af Aug 23 20:10:31 volumio volumio[7466]: info: Discovery: Found device VOLUMIO Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::volumioGetState Aug 23 20:10:31 volumio volumio[7466]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:31 volumio volumio[7466]: info: Asound.conf file unchanged, so no further update is needed Aug 23 20:10:31 volumio volumio[7466]: info: Output device has changed, restarting MPD Aug 23 20:10:31 volumio sudo[7568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:10:31 volumio sudo[7568]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:31 volumio volumio[7466]: info: Output device has changed, restarting Shairport Sync Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:31 volumio sudo[7568]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:31 volumio sudo[7570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:10:31 volumio sudo[7570]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:31 volumio systemd[1]: Stopping Music Player Daemon... Aug 23 20:10:31 volumio volumio[7466]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:31 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:10:31 volumio volumio[7466]: info: ___________ START PLUGINS ___________ Aug 23 20:10:31 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:10:31 volumio volumio[7466]: info: ControllerMpd::onStart: Initializing MPD Aug 23 20:10:31 volumio volumio[7466]: info: Creating MPD Configuration file Aug 23 20:10:31 volumio sudo[7578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:10:31 volumio sudo[7578]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:31 volumio sudo[7578]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:31 volumio sudo[7580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:10:31 volumio sudo[7580]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:31 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:10:31 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 23 20:10:31 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:10:31 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:10:31 volumio volumio[7466]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:31 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:10:31 volumio volumio[7466]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:10:31 volumio volumio[7466]: info: [1724415031567] CoreMusicLibrary::Adding element Last_100 Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:10:31 volumio volumio[7466]: info: [1724415031570] CoreMusicLibrary::Adding element Webradio Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:31 volumio volumio[7466]: info: Initializing BBC Radios Aug 23 20:10:31 volumio sudo[7586]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 23 20:10:31 volumio sudo[7586]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:31 volumio sudo[7586]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:31 volumio volumio[7466]: info: [squeezelite_mc] Starting proxy server... Aug 23 20:10:31 volumio volumio[7466]: info: Volumio Calling Home Aug 23 20:10:31 volumio volumio[7466]: info: [squeezelite_mc] Proxy server started on port 42759 Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:10:31 volumio volumio[7466]: info: CoreStateMachine::pushState Aug 23 20:10:31 volumio volumio[7466]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:31 volumio volumio[7466]: info: [squeezelite_mc] Server discovery started Aug 23 20:10:31 volumio volumio[7466]: info: [squeezelite_mc] Player finder started Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:31 volumio volumio[7466]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:31 volumio volumio[7466]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Aug 23 20:10:31 volumio volumio[7466]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:10:31 volumio volumio[7466]: Error: send ENETUNREACH 255.255.255.255:3483 Aug 23 20:10:31 volumio volumio[7466]: at doSend (dgram.js:692:16) Aug 23 20:10:31 volumio volumio[7466]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Aug 23 20:10:31 volumio volumio[7466]: at afterDns (dgram.js:638:5) Aug 23 20:10:31 volumio volumio[7466]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 23 20:10:31 volumio volumio[7466]: errno: -101, Aug 23 20:10:31 volumio volumio[7466]: code: 'ENETUNREACH', Aug 23 20:10:31 volumio volumio[7466]: syscall: 'send', Aug 23 20:10:31 volumio volumio[7466]: address: '255.255.255.255', Aug 23 20:10:31 volumio volumio[7466]: port: 3483 Aug 23 20:10:31 volumio volumio[7466]: } Aug 23 20:10:31 volumio volumio[7466]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:10:32 volumio sudo[7616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 20:09 Aug 23 20:10:32 volumio sudo[7616]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:32 volumio sudo[7616]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:32 volumio volumio-remote-updater[598]: [2024-08-23 20:10:32] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 23 20:10:32 volumio volumio-remote-updater[598]: [2024-08-23 20:10:32] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 23 20:10:32 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 23 20:10:32 volumio sudo[7580]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:32 volumio sudo[7570]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:32 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 23 20:10:32 volumio systemd[1]: Started dynamicswap service. Aug 23 20:10:32 volumio mpd[7597]: Aug 23 20:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 23 20:10:32 volumio systemd[1]: Started Music Player Daemon. Aug 23 20:10:32 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 23 20:10:32 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 23 20:10:32 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Aug 23 20:10:32 volumio systemd[1]: Started dynamicswap service. Aug 23 20:10:32 volumio systemd[1]: Stopped Volumio Backend Module. Aug 23 20:10:32 volumio systemd[1]: Started Volumio Backend Module. Aug 23 20:10:32 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 23 20:10:33 volumio volumio[7635]: info: ------------------------------------------- Aug 23 20:10:33 volumio volumio[7635]: info: ----- Volumio3 ---- Aug 23 20:10:33 volumio volumio[7635]: info: ------------------------------------------- Aug 23 20:10:33 volumio volumio[7635]: info: ----- System startup ---- Aug 23 20:10:33 volumio volumio[7635]: info: ------------------------------------------- Aug 23 20:10:34 volumio volumio[7635]: info: MYVOLUMIO Environment detected Aug 23 20:10:34 volumio volumio[7635]: info: Plugin folders cleanup Aug 23 20:10:34 volumio volumio[7635]: info: Scanning into folder /volumio/app/plugins/ Aug 23 20:10:34 volumio volumio[7635]: info: Scanning category audio_interface Aug 23 20:10:34 volumio volumio[7635]: info: Scanning category miscellanea Aug 23 20:10:34 volumio volumio[7635]: info: Scanning category music_service Aug 23 20:10:34 volumio volumio[7635]: info: Scanning category plugins.json Aug 23 20:10:34 volumio volumio[7635]: info: Scanning category system_controller Aug 23 20:10:34 volumio volumio[7635]: info: Scanning category user_interface Aug 23 20:10:34 volumio volumio[7635]: info: Scanning into folder /data/plugins/ Aug 23 20:10:34 volumio volumio[7635]: info: Scanning category music_service Aug 23 20:10:34 volumio volumio[7635]: info: Scanning category user_interface Aug 23 20:10:34 volumio volumio[7635]: info: Plugin folders cleanup completed Aug 23 20:10:34 volumio volumio[7635]: info: ------------------------------------------- Aug 23 20:10:34 volumio volumio[7635]: info: ----- Core plugins startup ---- Aug 23 20:10:34 volumio volumio[7635]: info: ------------------------------------------- Aug 23 20:10:34 volumio volumio[7635]: info: Loading plugins from folder /volumio/app/plugins/ Aug 23 20:10:34 volumio volumio[7635]: info: Adding plugin upnp to MyMusic Plugins Aug 23 20:10:34 volumio volumio[7635]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 23 20:10:34 volumio volumio[7635]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 23 20:10:34 volumio volumio[7635]: info: Loading plugins from folder /data/plugins/ Aug 23 20:10:34 volumio volumio[7635]: info: Loading plugin "system"... Aug 23 20:10:34 volumio volumio[7635]: info: Loading plugin "appearance"... Aug 23 20:10:34 volumio volumio[7635]: info: Loading plugin "network"... Aug 23 20:10:34 volumio volumio[7635]: info: Refreshing Cached IP Addresses Aug 23 20:10:34 volumio sudo[7661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 20:10:34 volumio sudo[7661]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:34 volumio sudo[7661]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:34 volumio sudo[7663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 20:10:34 volumio volumio[7635]: info: Loading plugin "services"... Aug 23 20:10:34 volumio sudo[7663]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:34 volumio volumio[7635]: info: Loading plugin "alsa_controller"... Aug 23 20:10:34 volumio sudo[7663]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:34 volumio sudo[7666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 23 20:10:34 volumio sudo[7666]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:34 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:34 volumio volumio[7635]: info: Loading plugin "wizard"... Aug 23 20:10:34 volumio volumio[7635]: info: Loading plugin "networkfs"... Aug 23 20:10:34 volumio volumio[7635]: info: Starting Udev Watcher for removable devices Aug 23 20:10:34 volumio volumio[7635]: info: Ignoring mount for partition: boot Aug 23 20:10:34 volumio volumio[7635]: info: Ignoring mount for partition: volumio Aug 23 20:10:34 volumio volumio[7635]: info: Ignoring mount for partition: volumio_data Aug 23 20:10:34 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:10:34 volumio volumio[7635]: info: Loading plugin "volumio_command_line_client"... Aug 23 20:10:34 volumio volumio[7635]: info: Plugin upnp is not enabled Aug 23 20:10:34 volumio volumio[7635]: info: Loading plugin "my_music"... Aug 23 20:10:34 volumio volumio[7635]: info: Loading plugin "mpd"... Aug 23 20:10:34 volumio volumio[7635]: info: Plugin upnp_browser is not enabled Aug 23 20:10:34 volumio volumio[7635]: info: Loading plugin "alarm-clock"... Aug 23 20:10:35 volumio volumio[7635]: info: Loading plugin "airplay_emulation"... Aug 23 20:10:35 volumio volumio[7635]: info: Starting Shairport Sync Aug 23 20:10:35 volumio volumio[7635]: info: Loading plugin "last_100"... Aug 23 20:10:35 volumio volumio[7635]: info: Loading plugin "webradio"... Aug 23 20:10:35 volumio volumio[7635]: info: Loading plugin "i2s_dacs"... Aug 23 20:10:35 volumio volumio[7635]: info: Loading plugin "volumiodiscovery"... Aug 23 20:10:35 volumio volumio[7635]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:35 volumio volumio[7635]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:35 volumio volumio[7635]: *** WARNING *** For more information see Aug 23 20:10:35 volumio node[7635]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:35 volumio volumio[7635]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:35 volumio volumio[7635]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:35 volumio volumio[7635]: *** WARNING *** For more information see Aug 23 20:10:35 volumio node[7635]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:35 volumio node[7635]: *** WARNING *** For more information see Aug 23 20:10:35 volumio node[7635]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:35 volumio node[7635]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:35 volumio node[7635]: *** WARNING *** For more information see Aug 23 20:10:35 volumio volumio[7635]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 23 20:10:35 volumio volumio[7635]: info: Discovery: Started advertising with name: VOLUMIO Aug 23 20:10:35 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:10:35 volumio volumio[7635]: info: Loading plugin "squeezelite_mc"... Aug 23 20:10:35 volumio volumio[7635]: info: Loading plugin "outputs"... Aug 23 20:10:35 volumio volumio[7635]: info: Loading plugin "albumart"... Aug 23 20:10:35 volumio volumio[7635]: info: Plugin example_plugin is not enabled Aug 23 20:10:35 volumio volumio[7635]: info: Loading plugin "inputs"... Aug 23 20:10:35 volumio volumio[7635]: info: Loading plugin "updater_comm"... Aug 23 20:10:35 volumio volumio[7635]: info: Plugin mpdemulation is not enabled Aug 23 20:10:35 volumio volumio[7635]: info: Loading plugin "rest_api"... Aug 23 20:10:35 volumio volumio[7635]: info: Loading plugin "websocket"... Aug 23 20:10:35 volumio volumio[7635]: info: Starting Socket.io Server version 2.3.0 Aug 23 20:10:35 volumio volumio[7635]: info: Loading plugin "Systeminfo"... Aug 23 20:10:35 volumio volumio[7635]: Forking 3 albumart workers Aug 23 20:10:36 volumio volumio[7635]: info: Loading i18n strings for locale en Aug 23 20:10:36 volumio volumio[7635]: Updating browse sources language Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::initPlayerControls Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:36 volumio volumio[7635]: Express server listening on port 3000 Aug 23 20:10:36 volumio volumio[7635]: [Metrics] WebUI: 2s 946.38ms Aug 23 20:10:36 volumio volumio[7635]: info: CoreStateMachine::resetVolumioState Aug 23 20:10:36 volumio volumio[7635]: info: CoreStateMachine::getcurrentVolume Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:10:36 volumio volumio[7635]: info: CoreStateMachine::pushState Aug 23 20:10:36 volumio volumio[7635]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:36 volumio volumio[7635]: info: Reloading queue from file Aug 23 20:10:36 volumio volumio[7635]: info: CoreStateMachine::setRepeat null single undefined Aug 23 20:10:36 volumio volumio[7635]: info: CoreStateMachine::pushState Aug 23 20:10:36 volumio volumio[7635]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:36 volumio volumio[7635]: info: CoreStateMachine::setRandom null Aug 23 20:10:36 volumio volumio[7635]: info: CoreStateMachine::pushState Aug 23 20:10:36 volumio volumio[7635]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:36 volumio volumio[7635]: info: Setting Device type: Raspberry PI Aug 23 20:10:36 volumio volumio[7635]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 23 20:10:36 volumio volumio[7635]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Aug 23 20:10:36 volumio volumio[7635]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 23 20:10:36 volumio volumio[7635]: info: Completed loading Core Plugins Aug 23 20:10:36 volumio volumio[7635]: info: Preparing to generate the ALSA configuration file Aug 23 20:10:36 volumio volumio[7635]: info: Asound.conf file unchanged, so no further update is needed Aug 23 20:10:36 volumio volumio[7635]: info: Output device has changed, restarting MPD Aug 23 20:10:36 volumio volumio[7635]: info: Output device has changed, restarting Shairport Sync Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:36 volumio sudo[7711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:10:36 volumio sudo[7711]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:36 volumio sudo[7711]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:36 volumio sudo[7712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:10:36 volumio sudo[7712]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:36 volumio volumio[7635]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:36 volumio volumio[7635]: info: ___________ START PLUGINS ___________ Aug 23 20:10:36 volumio systemd[1]: Stopping Music Player Daemon... Aug 23 20:10:36 volumio volumio[7635]: info: ControllerMpd::onStart: Initializing MPD Aug 23 20:10:36 volumio volumio[7635]: info: Creating MPD Configuration file Aug 23 20:10:36 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:10:36 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:10:36 volumio sudo[7721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:10:36 volumio sudo[7721]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:36 volumio sudo[7721]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:36 volumio volumio[7635]: Starting albumart workers Aug 23 20:10:36 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:10:36 volumio sudo[7722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:10:36 volumio sudo[7722]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:36 volumio volumio[7635]: Starting albumart workers Aug 23 20:10:36 volumio volumio[7635]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:36 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 23 20:10:36 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:10:36 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:10:36 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:10:36 volumio volumio[7635]: Starting albumart workers Aug 23 20:10:36 volumio volumio[7635]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:10:36 volumio volumio[7635]: info: [1724415036734] CoreMusicLibrary::Adding element Last_100 Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:10:36 volumio volumio[7635]: info: [1724415036738] CoreMusicLibrary::Adding element Webradio Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:36 volumio volumio[7635]: info: Initializing BBC Radios Aug 23 20:10:36 volumio sudo[7729]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 23 20:10:36 volumio sudo[7729]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:36 volumio sudo[7729]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:36 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:36 volumio volumio[7635]: info: [squeezelite_mc] Starting proxy server... Aug 23 20:10:36 volumio volumio[7635]: info: Volumio Calling Home Aug 23 20:10:36 volumio volumio[7635]: info: [squeezelite_mc] Proxy server started on port 43589 Aug 23 20:10:37 volumio volumio[7635]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:10:37 volumio volumio[7635]: info: CoreStateMachine::pushState Aug 23 20:10:37 volumio volumio[7635]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:37 volumio volumio[7635]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:37 volumio volumio[7635]: info: [squeezelite_mc] Server discovery started Aug 23 20:10:37 volumio volumio[7635]: info: [squeezelite_mc] Player finder started Aug 23 20:10:37 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:37 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:37 volumio volumio[7635]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:37 volumio volumio[7635]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Aug 23 20:10:37 volumio sudo[7666]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:37 volumio volumio[7635]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:10:37 volumio volumio[7635]: Error: send ENETUNREACH 255.255.255.255:3483 Aug 23 20:10:37 volumio volumio[7635]: at doSend (dgram.js:692:16) Aug 23 20:10:37 volumio volumio[7635]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Aug 23 20:10:37 volumio volumio[7635]: at afterDns (dgram.js:638:5) Aug 23 20:10:37 volumio volumio[7635]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 23 20:10:37 volumio volumio[7635]: errno: -101, Aug 23 20:10:37 volumio volumio[7635]: code: 'ENETUNREACH', Aug 23 20:10:37 volumio volumio[7635]: syscall: 'send', Aug 23 20:10:37 volumio volumio[7635]: address: '255.255.255.255', Aug 23 20:10:37 volumio volumio[7635]: port: 3483 Aug 23 20:10:37 volumio volumio[7635]: } Aug 23 20:10:37 volumio volumio[7635]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:10:37 volumio volumio-remote-updater[598]: [2024-08-23 20:10:37] [connect] Successful connection Aug 23 20:10:37 volumio sudo[7782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 20:09 Aug 23 20:10:37 volumio sudo[7782]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:37 volumio sudo[7782]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:37 volumio mpd[7739]: Aug 23 20:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 23 20:10:37 volumio systemd[1]: Started Music Player Daemon. Aug 23 20:10:37 volumio sudo[7722]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:37 volumio sudo[7712]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:37 volumio volumio-remote-updater[598]: [2024-08-23 20:10:37] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Aug 23 20:10:37 volumio volumio-remote-updater[598]: [2024-08-23 20:10:37] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Aug 23 20:10:37 volumio volumio-remote-updater[598]: [2024-08-23 20:10:37] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 23 20:10:37 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 23 20:10:37 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 23 20:10:37 volumio systemd[1]: Started dynamicswap service. Aug 23 20:10:37 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 23 20:10:38 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 23 20:10:38 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Aug 23 20:10:38 volumio systemd[1]: Started dynamicswap service. Aug 23 20:10:38 volumio systemd[1]: Stopped Volumio Backend Module. Aug 23 20:10:38 volumio systemd[1]: Started Volumio Backend Module. Aug 23 20:10:38 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 23 20:10:39 volumio wpa_supplicant[1085]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="botbot" Aug 23 20:10:39 volumio wpa_supplicant[1085]: wlan0: Failed to initiate sched scan Aug 23 20:10:39 volumio volumio[7799]: info: ------------------------------------------- Aug 23 20:10:39 volumio volumio[7799]: info: ----- Volumio3 ---- Aug 23 20:10:39 volumio volumio[7799]: info: ------------------------------------------- Aug 23 20:10:39 volumio volumio[7799]: info: ----- System startup ---- Aug 23 20:10:39 volumio volumio[7799]: info: ------------------------------------------- Aug 23 20:10:39 volumio volumio[7799]: info: MYVOLUMIO Environment detected Aug 23 20:10:39 volumio volumio[7799]: info: Plugin folders cleanup Aug 23 20:10:39 volumio volumio[7799]: info: Scanning into folder /volumio/app/plugins/ Aug 23 20:10:39 volumio volumio[7799]: info: Scanning category audio_interface Aug 23 20:10:39 volumio volumio[7799]: info: Scanning category miscellanea Aug 23 20:10:39 volumio volumio[7799]: info: Scanning category music_service Aug 23 20:10:39 volumio volumio[7799]: info: Scanning category plugins.json Aug 23 20:10:39 volumio volumio[7799]: info: Scanning category system_controller Aug 23 20:10:39 volumio volumio[7799]: info: Scanning category user_interface Aug 23 20:10:39 volumio volumio[7799]: info: Scanning into folder /data/plugins/ Aug 23 20:10:39 volumio volumio[7799]: info: Scanning category music_service Aug 23 20:10:39 volumio volumio[7799]: info: Scanning category user_interface Aug 23 20:10:39 volumio volumio[7799]: info: Plugin folders cleanup completed Aug 23 20:10:39 volumio volumio[7799]: info: ------------------------------------------- Aug 23 20:10:39 volumio volumio[7799]: info: ----- Core plugins startup ---- Aug 23 20:10:39 volumio volumio[7799]: info: ------------------------------------------- Aug 23 20:10:39 volumio volumio[7799]: info: Loading plugins from folder /volumio/app/plugins/ Aug 23 20:10:39 volumio volumio[7799]: info: Adding plugin upnp to MyMusic Plugins Aug 23 20:10:39 volumio volumio[7799]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 23 20:10:39 volumio volumio[7799]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 23 20:10:39 volumio volumio[7799]: info: Loading plugins from folder /data/plugins/ Aug 23 20:10:39 volumio volumio[7799]: info: Loading plugin "system"... Aug 23 20:10:39 volumio volumio[7799]: info: Loading plugin "appearance"... Aug 23 20:10:40 volumio volumio[7799]: info: Loading plugin "network"... Aug 23 20:10:40 volumio volumio[7799]: info: Refreshing Cached IP Addresses Aug 23 20:10:40 volumio sudo[7825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 20:10:40 volumio sudo[7825]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:40 volumio sudo[7825]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:40 volumio sudo[7827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 20:10:40 volumio volumio[7799]: info: Loading plugin "services"... Aug 23 20:10:40 volumio sudo[7827]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:40 volumio volumio[7799]: info: Loading plugin "alsa_controller"... Aug 23 20:10:40 volumio sudo[7827]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:40 volumio sudo[7830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 23 20:10:40 volumio sudo[7830]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:40 volumio sudo[7830]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:40 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:40 volumio volumio[7799]: info: Loading plugin "wizard"... Aug 23 20:10:40 volumio volumio[7799]: info: Loading plugin "networkfs"... Aug 23 20:10:40 volumio volumio[7799]: info: Starting Udev Watcher for removable devices Aug 23 20:10:40 volumio volumio[7799]: info: Ignoring mount for partition: boot Aug 23 20:10:40 volumio volumio[7799]: info: Ignoring mount for partition: volumio Aug 23 20:10:40 volumio volumio[7799]: info: Ignoring mount for partition: volumio_data Aug 23 20:10:40 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:10:40 volumio volumio[7799]: info: Loading plugin "volumio_command_line_client"... Aug 23 20:10:40 volumio volumio[7799]: info: Plugin upnp is not enabled Aug 23 20:10:40 volumio volumio[7799]: info: Loading plugin "my_music"... Aug 23 20:10:40 volumio volumio[7799]: info: Loading plugin "mpd"... Aug 23 20:10:40 volumio volumio[7799]: info: Plugin upnp_browser is not enabled Aug 23 20:10:40 volumio volumio[7799]: info: Loading plugin "alarm-clock"... Aug 23 20:10:40 volumio volumio[7799]: info: Loading plugin "airplay_emulation"... Aug 23 20:10:40 volumio volumio[7799]: info: Starting Shairport Sync Aug 23 20:10:40 volumio volumio[7799]: info: Loading plugin "last_100"... Aug 23 20:10:40 volumio volumio[7799]: info: Loading plugin "webradio"... Aug 23 20:10:40 volumio volumio[7799]: info: Loading plugin "i2s_dacs"... Aug 23 20:10:40 volumio volumio[7799]: info: Loading plugin "volumiodiscovery"... Aug 23 20:10:40 volumio volumio[7799]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:40 volumio volumio[7799]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:40 volumio node[7799]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:40 volumio volumio[7799]: *** WARNING *** For more information see Aug 23 20:10:40 volumio volumio[7799]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:40 volumio volumio[7799]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:40 volumio volumio[7799]: *** WARNING *** For more information see Aug 23 20:10:40 volumio node[7799]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:40 volumio node[7799]: *** WARNING *** For more information see Aug 23 20:10:40 volumio node[7799]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:40 volumio node[7799]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:40 volumio node[7799]: *** WARNING *** For more information see Aug 23 20:10:40 volumio volumio[7799]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 23 20:10:40 volumio volumio[7799]: info: Discovery: Started advertising with name: VOLUMIO Aug 23 20:10:40 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:10:40 volumio volumio[7799]: info: Loading plugin "squeezelite_mc"... Aug 23 20:10:41 volumio volumio[7799]: info: Loading plugin "outputs"... Aug 23 20:10:41 volumio volumio[7799]: info: Loading plugin "albumart"... Aug 23 20:10:41 volumio volumio[7799]: info: Plugin example_plugin is not enabled Aug 23 20:10:41 volumio volumio[7799]: info: Loading plugin "inputs"... Aug 23 20:10:41 volumio volumio[7799]: info: Loading plugin "updater_comm"... Aug 23 20:10:41 volumio volumio[7799]: info: Plugin mpdemulation is not enabled Aug 23 20:10:41 volumio volumio[7799]: info: Loading plugin "rest_api"... Aug 23 20:10:41 volumio volumio[7799]: info: Loading plugin "websocket"... Aug 23 20:10:41 volumio volumio[7799]: info: Starting Socket.io Server version 2.3.0 Aug 23 20:10:41 volumio volumio[7799]: info: Loading plugin "Systeminfo"... Aug 23 20:10:41 volumio volumio[7799]: Forking 3 albumart workers Aug 23 20:10:41 volumio wpa_supplicant[1085]: wlan0: Failed to initiate sched scan Aug 23 20:10:41 volumio volumio[7799]: info: Loading i18n strings for locale en Aug 23 20:10:41 volumio volumio[7799]: Updating browse sources language Aug 23 20:10:41 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:41 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:41 volumio volumio[7799]: info: CoreCommandRouter::initPlayerControls Aug 23 20:10:41 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:41 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:41 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:41 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:41 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:41 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:41 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:41 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:41 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:41 volumio volumio[7799]: Express server listening on port 3000 Aug 23 20:10:41 volumio volumio[7799]: [Metrics] WebUI: 2s 913.63ms Aug 23 20:10:41 volumio volumio[7799]: info: CoreStateMachine::resetVolumioState Aug 23 20:10:41 volumio volumio[7799]: info: CoreStateMachine::getcurrentVolume Aug 23 20:10:41 volumio volumio[7799]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:10:41 volumio volumio[7799]: info: CoreStateMachine::pushState Aug 23 20:10:41 volumio volumio[7799]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:41 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:10:41 volumio volumio[7799]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:41 volumio volumio[7799]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Aug 23 20:10:41 volumio volumio[7799]: wlan0 Interface doesn't support scanning : Device or resource busy Aug 23 20:10:41 volumio volumio[7799]: info: Cannot use regular scanning, forcing with ap-force Aug 23 20:10:41 volumio sudo[7869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Aug 23 20:10:41 volumio sudo[7869]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:41 volumio volumio[7799]: Starting albumart workers Aug 23 20:10:42 volumio volumio[7799]: Starting albumart workers Aug 23 20:10:42 volumio volumio[7799]: Starting albumart workers Aug 23 20:10:44 volumio sudo[7869]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:44 volumio volumio[7799]: info: Reloading queue from file Aug 23 20:10:44 volumio volumio[7799]: info: CoreStateMachine::setRepeat null single undefined Aug 23 20:10:44 volumio volumio[7799]: info: CoreStateMachine::pushState Aug 23 20:10:44 volumio volumio[7799]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:44 volumio volumio[7799]: info: CoreStateMachine::setRandom null Aug 23 20:10:44 volumio volumio[7799]: info: CoreStateMachine::pushState Aug 23 20:10:44 volumio volumio[7799]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:44 volumio volumio[7799]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 23 20:10:44 volumio volumio[7799]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Aug 23 20:10:44 volumio volumio[7799]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 23 20:10:44 volumio volumio[7799]: info: Setting Device type: Raspberry PI Aug 23 20:10:44 volumio volumio[7799]: info: Completed loading Core Plugins Aug 23 20:10:44 volumio volumio[7799]: info: Preparing to generate the ALSA configuration file Aug 23 20:10:44 volumio volumio[7799]: info: Discovery: adding 92d514c2-05e0-4590-90ec-0ae0399380af Aug 23 20:10:44 volumio volumio[7799]: info: Discovery: Found device VOLUMIO Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::volumioGetState Aug 23 20:10:44 volumio volumio[7799]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:44 volumio volumio[7799]: info: Asound.conf file unchanged, so no further update is needed Aug 23 20:10:44 volumio volumio[7799]: info: Output device has changed, restarting MPD Aug 23 20:10:44 volumio sudo[7901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:10:44 volumio sudo[7901]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:44 volumio volumio[7799]: info: Output device has changed, restarting Shairport Sync Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:44 volumio sudo[7901]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:44 volumio sudo[7903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:10:44 volumio sudo[7903]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:44 volumio systemd[1]: Stopping Music Player Daemon... Aug 23 20:10:44 volumio volumio[7799]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:44 volumio volumio[7799]: info: ___________ START PLUGINS ___________ Aug 23 20:10:44 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:10:44 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:10:44 volumio volumio[7799]: info: ControllerMpd::onStart: Initializing MPD Aug 23 20:10:44 volumio volumio[7799]: info: Creating MPD Configuration file Aug 23 20:10:44 volumio sudo[7911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:10:44 volumio sudo[7911]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:44 volumio volumio[7799]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:44 volumio sudo[7911]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:44 volumio sudo[7913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:10:44 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:10:44 volumio sudo[7913]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:44 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 23 20:10:44 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:10:44 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:10:44 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:10:44 volumio volumio[7799]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:10:44 volumio volumio[7799]: info: [1724415044517] CoreMusicLibrary::Adding element Last_100 Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:10:44 volumio volumio[7799]: info: [1724415044519] CoreMusicLibrary::Adding element Webradio Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:44 volumio volumio[7799]: info: Initializing BBC Radios Aug 23 20:10:44 volumio sudo[7924]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 23 20:10:44 volumio sudo[7924]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:44 volumio sudo[7924]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:44 volumio volumio[7799]: info: [squeezelite_mc] Starting proxy server... Aug 23 20:10:44 volumio volumio[7799]: info: Volumio Calling Home Aug 23 20:10:44 volumio volumio[7799]: info: [squeezelite_mc] Proxy server started on port 38591 Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:10:44 volumio volumio[7799]: info: CoreStateMachine::pushState Aug 23 20:10:44 volumio volumio[7799]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:44 volumio volumio[7799]: info: [squeezelite_mc] Server discovery started Aug 23 20:10:44 volumio volumio[7799]: info: [squeezelite_mc] Player finder started Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:44 volumio volumio[7799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:44 volumio volumio[7799]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Aug 23 20:10:44 volumio volumio[7799]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:10:44 volumio volumio[7799]: Error: send ENETUNREACH 255.255.255.255:3483 Aug 23 20:10:44 volumio volumio[7799]: at doSend (dgram.js:692:16) Aug 23 20:10:44 volumio volumio[7799]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Aug 23 20:10:44 volumio volumio[7799]: at afterDns (dgram.js:638:5) Aug 23 20:10:44 volumio volumio[7799]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 23 20:10:44 volumio volumio[7799]: errno: -101, Aug 23 20:10:44 volumio volumio[7799]: code: 'ENETUNREACH', Aug 23 20:10:44 volumio volumio[7799]: syscall: 'send', Aug 23 20:10:44 volumio volumio[7799]: address: '255.255.255.255', Aug 23 20:10:44 volumio volumio[7799]: port: 3483 Aug 23 20:10:44 volumio volumio[7799]: } Aug 23 20:10:44 volumio volumio[7799]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:10:45 volumio sudo[7952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 20:09 Aug 23 20:10:45 volumio sudo[7952]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:45 volumio sudo[7952]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:45 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 23 20:10:45 volumio volumio-remote-updater[598]: [2024-08-23 20:10:45] [info] asio async_connect error: system:111 (Connection refused) Aug 23 20:10:45 volumio volumio-remote-updater[598]: [2024-08-23 20:10:45] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 23 20:10:45 volumio volumio-remote-updater[598]: [2024-08-23 20:10:45] [error] handle_connect error: Underlying Transport Error Aug 23 20:10:45 volumio sudo[7913]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:45 volumio sudo[7903]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:45 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 23 20:10:45 volumio systemd[1]: Started dynamicswap service. Aug 23 20:10:45 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 23 20:10:45 volumio mpd[7937]: Aug 23 20:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 23 20:10:45 volumio systemd[1]: Started Music Player Daemon. Aug 23 20:10:45 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 23 20:10:45 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Aug 23 20:10:45 volumio systemd[1]: Stopped Volumio Backend Module. Aug 23 20:10:45 volumio systemd[1]: Started Volumio Backend Module. Aug 23 20:10:45 volumio systemd[1]: Started dynamicswap service. Aug 23 20:10:45 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 23 20:10:46 volumio volumio[7968]: info: ------------------------------------------- Aug 23 20:10:46 volumio volumio[7968]: info: ----- Volumio3 ---- Aug 23 20:10:46 volumio volumio[7968]: info: ------------------------------------------- Aug 23 20:10:46 volumio volumio[7968]: info: ----- System startup ---- Aug 23 20:10:46 volumio volumio[7968]: info: ------------------------------------------- Aug 23 20:10:47 volumio volumio[7968]: info: MYVOLUMIO Environment detected Aug 23 20:10:47 volumio volumio[7968]: info: Plugin folders cleanup Aug 23 20:10:47 volumio volumio[7968]: info: Scanning into folder /volumio/app/plugins/ Aug 23 20:10:47 volumio volumio[7968]: info: Scanning category audio_interface Aug 23 20:10:47 volumio volumio[7968]: info: Scanning category miscellanea Aug 23 20:10:47 volumio volumio[7968]: info: Scanning category music_service Aug 23 20:10:47 volumio volumio[7968]: info: Scanning category plugins.json Aug 23 20:10:47 volumio volumio[7968]: info: Scanning category system_controller Aug 23 20:10:47 volumio volumio[7968]: info: Scanning category user_interface Aug 23 20:10:47 volumio volumio[7968]: info: Scanning into folder /data/plugins/ Aug 23 20:10:47 volumio volumio[7968]: info: Scanning category music_service Aug 23 20:10:47 volumio volumio[7968]: info: Scanning category user_interface Aug 23 20:10:47 volumio volumio[7968]: info: Plugin folders cleanup completed Aug 23 20:10:47 volumio volumio[7968]: info: ------------------------------------------- Aug 23 20:10:47 volumio volumio[7968]: info: ----- Core plugins startup ---- Aug 23 20:10:47 volumio volumio[7968]: info: ------------------------------------------- Aug 23 20:10:47 volumio volumio[7968]: info: Loading plugins from folder /volumio/app/plugins/ Aug 23 20:10:47 volumio volumio[7968]: info: Adding plugin upnp to MyMusic Plugins Aug 23 20:10:47 volumio volumio[7968]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 23 20:10:47 volumio volumio[7968]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 23 20:10:47 volumio volumio[7968]: info: Loading plugins from folder /data/plugins/ Aug 23 20:10:47 volumio volumio[7968]: info: Loading plugin "system"... Aug 23 20:10:47 volumio volumio[7968]: info: Loading plugin "appearance"... Aug 23 20:10:47 volumio volumio[7968]: info: Loading plugin "network"... Aug 23 20:10:47 volumio volumio[7968]: info: Refreshing Cached IP Addresses Aug 23 20:10:47 volumio sudo[7995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 20:10:47 volumio sudo[7995]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:47 volumio sudo[7995]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:47 volumio sudo[7997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 20:10:47 volumio sudo[7997]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:47 volumio volumio[7968]: info: Loading plugin "services"... Aug 23 20:10:47 volumio volumio[7968]: info: Loading plugin "alsa_controller"... Aug 23 20:10:47 volumio sudo[7997]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:47 volumio sudo[8000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 23 20:10:47 volumio sudo[8000]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:47 volumio sudo[8000]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:47 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:47 volumio volumio[7968]: info: Loading plugin "wizard"... Aug 23 20:10:47 volumio volumio[7968]: info: Loading plugin "networkfs"... Aug 23 20:10:47 volumio volumio[7968]: info: Starting Udev Watcher for removable devices Aug 23 20:10:47 volumio volumio[7968]: info: Ignoring mount for partition: boot Aug 23 20:10:47 volumio volumio[7968]: info: Ignoring mount for partition: volumio Aug 23 20:10:47 volumio volumio[7968]: info: Ignoring mount for partition: volumio_data Aug 23 20:10:47 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:10:47 volumio volumio[7968]: info: Loading plugin "volumio_command_line_client"... Aug 23 20:10:47 volumio volumio[7968]: info: Plugin upnp is not enabled Aug 23 20:10:47 volumio volumio[7968]: info: Loading plugin "my_music"... Aug 23 20:10:47 volumio volumio[7968]: info: Loading plugin "mpd"... Aug 23 20:10:48 volumio volumio[7968]: info: Plugin upnp_browser is not enabled Aug 23 20:10:48 volumio volumio[7968]: info: Loading plugin "alarm-clock"... Aug 23 20:10:48 volumio volumio[7968]: info: Loading plugin "airplay_emulation"... Aug 23 20:10:48 volumio volumio[7968]: info: Starting Shairport Sync Aug 23 20:10:48 volumio volumio[7968]: info: Loading plugin "last_100"... Aug 23 20:10:48 volumio volumio[7968]: info: Loading plugin "webradio"... Aug 23 20:10:48 volumio volumio[7968]: info: Loading plugin "i2s_dacs"... Aug 23 20:10:48 volumio volumio[7968]: info: Loading plugin "volumiodiscovery"... Aug 23 20:10:48 volumio volumio[7968]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:48 volumio volumio[7968]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:48 volumio volumio[7968]: *** WARNING *** For more information see Aug 23 20:10:48 volumio node[7968]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:48 volumio volumio[7968]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:48 volumio volumio[7968]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:48 volumio volumio[7968]: *** WARNING *** For more information see Aug 23 20:10:48 volumio node[7968]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:48 volumio node[7968]: *** WARNING *** For more information see Aug 23 20:10:48 volumio node[7968]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:48 volumio node[7968]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:48 volumio node[7968]: *** WARNING *** For more information see Aug 23 20:10:48 volumio volumio[7968]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 23 20:10:48 volumio volumio[7968]: info: Discovery: Started advertising with name: VOLUMIO Aug 23 20:10:48 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:10:48 volumio volumio[7968]: info: Loading plugin "squeezelite_mc"... Aug 23 20:10:48 volumio volumio[7968]: info: Loading plugin "outputs"... Aug 23 20:10:48 volumio volumio[7968]: info: Loading plugin "albumart"... Aug 23 20:10:48 volumio volumio[7968]: info: Plugin example_plugin is not enabled Aug 23 20:10:48 volumio volumio[7968]: info: Loading plugin "inputs"... Aug 23 20:10:48 volumio volumio[7968]: info: Loading plugin "updater_comm"... Aug 23 20:10:48 volumio volumio[7968]: info: Plugin mpdemulation is not enabled Aug 23 20:10:48 volumio volumio[7968]: info: Loading plugin "rest_api"... Aug 23 20:10:48 volumio volumio[7968]: info: Loading plugin "websocket"... Aug 23 20:10:48 volumio volumio[7968]: info: Starting Socket.io Server version 2.3.0 Aug 23 20:10:48 volumio volumio[7968]: info: Loading plugin "Systeminfo"... Aug 23 20:10:48 volumio wpa_supplicant[1085]: wlan0: Failed to initiate sched scan Aug 23 20:10:49 volumio volumio[7968]: Forking 3 albumart workers Aug 23 20:10:49 volumio volumio[7968]: info: Loading i18n strings for locale en Aug 23 20:10:49 volumio volumio[7968]: Updating browse sources language Aug 23 20:10:49 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:49 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:49 volumio volumio[7968]: info: CoreCommandRouter::initPlayerControls Aug 23 20:10:49 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:49 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:49 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:49 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:49 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:49 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:49 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:49 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:49 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:49 volumio volumio[7968]: Express server listening on port 3000 Aug 23 20:10:49 volumio volumio[7968]: [Metrics] WebUI: 2s 941.79ms Aug 23 20:10:49 volumio volumio[7968]: info: CoreStateMachine::resetVolumioState Aug 23 20:10:49 volumio volumio[7968]: info: CoreStateMachine::getcurrentVolume Aug 23 20:10:49 volumio volumio[7968]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:10:49 volumio volumio[7968]: info: CoreStateMachine::pushState Aug 23 20:10:49 volumio volumio[7968]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:49 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:10:49 volumio volumio[7968]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:49 volumio volumio[7968]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Aug 23 20:10:49 volumio volumio[7968]: wlan0 Interface doesn't support scanning : Device or resource busy Aug 23 20:10:49 volumio volumio[7968]: info: Cannot use regular scanning, forcing with ap-force Aug 23 20:10:49 volumio sudo[8039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Aug 23 20:10:49 volumio sudo[8039]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:49 volumio volumio[7968]: Starting albumart workers Aug 23 20:10:49 volumio volumio[7968]: Starting albumart workers Aug 23 20:10:49 volumio volumio[7968]: Starting albumart workers Aug 23 20:10:51 volumio sudo[8039]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:51 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:51 volumio volumio[7968]: info: Reloading queue from file Aug 23 20:10:51 volumio volumio[7968]: info: CoreStateMachine::setRepeat null single undefined Aug 23 20:10:51 volumio volumio[7968]: info: CoreStateMachine::pushState Aug 23 20:10:51 volumio volumio[7968]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:51 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:10:51 volumio volumio[7968]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:51 volumio volumio[7968]: info: CoreStateMachine::setRandom null Aug 23 20:10:51 volumio volumio[7968]: info: CoreStateMachine::pushState Aug 23 20:10:51 volumio volumio[7968]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:51 volumio volumio[7968]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:51 volumio volumio[7968]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 23 20:10:51 volumio volumio[7968]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Aug 23 20:10:51 volumio volumio[7968]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 23 20:10:51 volumio volumio[7968]: info: Setting Device type: Raspberry PI Aug 23 20:10:51 volumio volumio[7968]: info: Completed loading Core Plugins Aug 23 20:10:51 volumio volumio[7968]: info: Preparing to generate the ALSA configuration file Aug 23 20:10:51 volumio volumio[7968]: info: Discovery: adding 92d514c2-05e0-4590-90ec-0ae0399380af Aug 23 20:10:51 volumio volumio[7968]: info: Discovery: Found device VOLUMIO Aug 23 20:10:51 volumio volumio[7968]: info: CoreCommandRouter::volumioGetState Aug 23 20:10:51 volumio volumio[7968]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:51 volumio volumio[7968]: info: Asound.conf file unchanged, so no further update is needed Aug 23 20:10:51 volumio volumio[7968]: info: Output device has changed, restarting MPD Aug 23 20:10:51 volumio sudo[8071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:10:51 volumio sudo[8071]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:51 volumio volumio[7968]: info: Output device has changed, restarting Shairport Sync Aug 23 20:10:51 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:51 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:51 volumio sudo[8071]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:51 volumio sudo[8074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:10:51 volumio sudo[8074]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:51 volumio systemd[1]: Stopping Music Player Daemon... Aug 23 20:10:51 volumio volumio[7968]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:51 volumio volumio[7968]: info: ___________ START PLUGINS ___________ Aug 23 20:10:51 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:10:51 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:10:51 volumio volumio[7968]: info: ControllerMpd::onStart: Initializing MPD Aug 23 20:10:51 volumio volumio[7968]: info: Creating MPD Configuration file Aug 23 20:10:51 volumio sudo[8081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:10:51 volumio sudo[8081]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:52 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:52 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:52 volumio volumio[7968]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:52 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:52 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:52 volumio volumio[7968]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:52 volumio volumio[7968]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:10:52 volumio volumio[7968]: info: [1724415052055] CoreMusicLibrary::Adding element Last_100 Aug 23 20:10:52 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:52 volumio volumio[7968]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:10:52 volumio volumio[7968]: info: [1724415052057] CoreMusicLibrary::Adding element Webradio Aug 23 20:10:52 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:52 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:52 volumio volumio[7968]: info: Initializing BBC Radios Aug 23 20:10:51 volumio sudo[8081]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:52 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:10:51 volumio sudo[8083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:10:51 volumio sudo[8083]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:52 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 23 20:10:52 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:10:52 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:10:52 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:10:52 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:52 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:52 volumio volumio[7968]: info: [squeezelite_mc] Starting proxy server... Aug 23 20:10:52 volumio volumio[7968]: info: Volumio Calling Home Aug 23 20:10:52 volumio sudo[8095]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 23 20:10:52 volumio sudo[8095]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:52 volumio sudo[8095]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:52 volumio volumio[7968]: info: [squeezelite_mc] Proxy server started on port 39629 Aug 23 20:10:52 volumio volumio[7968]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:10:52 volumio volumio[7968]: info: CoreStateMachine::pushState Aug 23 20:10:52 volumio volumio[7968]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:52 volumio volumio[7968]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:52 volumio volumio[7968]: info: [squeezelite_mc] Server discovery started Aug 23 20:10:52 volumio volumio[7968]: info: [squeezelite_mc] Player finder started Aug 23 20:10:52 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:52 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:52 volumio volumio[7968]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:52 volumio volumio[7968]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Aug 23 20:10:52 volumio volumio[7968]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:10:52 volumio volumio[7968]: Error: send ENETUNREACH 255.255.255.255:3483 Aug 23 20:10:52 volumio volumio[7968]: at doSend (dgram.js:692:16) Aug 23 20:10:52 volumio volumio[7968]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Aug 23 20:10:52 volumio volumio[7968]: at afterDns (dgram.js:638:5) Aug 23 20:10:52 volumio volumio[7968]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 23 20:10:52 volumio volumio[7968]: errno: -101, Aug 23 20:10:52 volumio volumio[7968]: code: 'ENETUNREACH', Aug 23 20:10:52 volumio volumio[7968]: syscall: 'send', Aug 23 20:10:52 volumio volumio[7968]: address: '255.255.255.255', Aug 23 20:10:52 volumio volumio[7968]: port: 3483 Aug 23 20:10:52 volumio volumio[7968]: } Aug 23 20:10:52 volumio volumio[7968]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:10:52 volumio sudo[8119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 20:09 Aug 23 20:10:52 volumio sudo[8119]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:52 volumio sudo[8119]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:52 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 23 20:10:52 volumio sudo[8074]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:52 volumio sudo[8083]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:52 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 23 20:10:52 volumio mpd[8106]: Aug 23 20:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 23 20:10:52 volumio systemd[1]: Started dynamicswap service. Aug 23 20:10:52 volumio systemd[1]: Started Music Player Daemon. Aug 23 20:10:52 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 23 20:10:53 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 23 20:10:53 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9. Aug 23 20:10:53 volumio systemd[1]: Started dynamicswap service. Aug 23 20:10:53 volumio systemd[1]: Stopped Volumio Backend Module. Aug 23 20:10:53 volumio systemd[1]: Started Volumio Backend Module. Aug 23 20:10:53 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 23 20:10:54 volumio volumio[8137]: info: ------------------------------------------- Aug 23 20:10:54 volumio volumio[8137]: info: ----- Volumio3 ---- Aug 23 20:10:54 volumio volumio[8137]: info: ------------------------------------------- Aug 23 20:10:54 volumio volumio[8137]: info: ----- System startup ---- Aug 23 20:10:54 volumio volumio[8137]: info: ------------------------------------------- Aug 23 20:10:54 volumio volumio[8137]: info: MYVOLUMIO Environment detected Aug 23 20:10:54 volumio volumio[8137]: info: Plugin folders cleanup Aug 23 20:10:54 volumio volumio[8137]: info: Scanning into folder /volumio/app/plugins/ Aug 23 20:10:54 volumio volumio[8137]: info: Scanning category audio_interface Aug 23 20:10:54 volumio volumio[8137]: info: Scanning category miscellanea Aug 23 20:10:54 volumio volumio[8137]: info: Scanning category music_service Aug 23 20:10:54 volumio volumio[8137]: info: Scanning category plugins.json Aug 23 20:10:54 volumio volumio[8137]: info: Scanning category system_controller Aug 23 20:10:54 volumio volumio[8137]: info: Scanning category user_interface Aug 23 20:10:54 volumio volumio[8137]: info: Scanning into folder /data/plugins/ Aug 23 20:10:54 volumio volumio[8137]: info: Scanning category music_service Aug 23 20:10:54 volumio volumio[8137]: info: Scanning category user_interface Aug 23 20:10:54 volumio volumio[8137]: info: Plugin folders cleanup completed Aug 23 20:10:54 volumio volumio[8137]: info: ------------------------------------------- Aug 23 20:10:54 volumio volumio[8137]: info: ----- Core plugins startup ---- Aug 23 20:10:54 volumio volumio[8137]: info: ------------------------------------------- Aug 23 20:10:54 volumio volumio[8137]: info: Loading plugins from folder /volumio/app/plugins/ Aug 23 20:10:54 volumio volumio[8137]: info: Adding plugin upnp to MyMusic Plugins Aug 23 20:10:54 volumio volumio[8137]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 23 20:10:54 volumio volumio[8137]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 23 20:10:54 volumio volumio[8137]: info: Loading plugins from folder /data/plugins/ Aug 23 20:10:54 volumio volumio[8137]: info: Loading plugin "system"... Aug 23 20:10:54 volumio volumio[8137]: info: Loading plugin "appearance"... Aug 23 20:10:55 volumio volumio[8137]: info: Loading plugin "network"... Aug 23 20:10:55 volumio volumio[8137]: info: Refreshing Cached IP Addresses Aug 23 20:10:55 volumio sudo[8163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 20:10:55 volumio sudo[8163]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:55 volumio sudo[8163]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:55 volumio sudo[8165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 20:10:55 volumio volumio[8137]: info: Loading plugin "services"... Aug 23 20:10:55 volumio sudo[8165]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:55 volumio volumio[8137]: info: Loading plugin "alsa_controller"... Aug 23 20:10:55 volumio sudo[8165]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:55 volumio sudo[8168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 23 20:10:55 volumio sudo[8168]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:55 volumio sudo[8168]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:55 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:55 volumio volumio[8137]: info: Loading plugin "wizard"... Aug 23 20:10:55 volumio volumio[8137]: info: Loading plugin "networkfs"... Aug 23 20:10:55 volumio volumio[8137]: info: Starting Udev Watcher for removable devices Aug 23 20:10:55 volumio volumio[8137]: info: Ignoring mount for partition: boot Aug 23 20:10:55 volumio volumio[8137]: info: Ignoring mount for partition: volumio Aug 23 20:10:55 volumio volumio[8137]: info: Ignoring mount for partition: volumio_data Aug 23 20:10:55 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:10:55 volumio volumio[8137]: info: Loading plugin "volumio_command_line_client"... Aug 23 20:10:55 volumio volumio[8137]: info: Plugin upnp is not enabled Aug 23 20:10:55 volumio volumio[8137]: info: Loading plugin "my_music"... Aug 23 20:10:55 volumio volumio[8137]: info: Loading plugin "mpd"... Aug 23 20:10:55 volumio volumio-remote-updater[598]: [2024-08-23 20:10:55] [connect] Successful connection Aug 23 20:10:55 volumio volumio[8137]: info: Plugin upnp_browser is not enabled Aug 23 20:10:55 volumio volumio[8137]: info: Loading plugin "alarm-clock"... Aug 23 20:10:55 volumio volumio[8137]: info: Loading plugin "airplay_emulation"... Aug 23 20:10:55 volumio volumio[8137]: info: Starting Shairport Sync Aug 23 20:10:55 volumio volumio[8137]: info: Loading plugin "last_100"... Aug 23 20:10:55 volumio volumio[8137]: info: Loading plugin "webradio"... Aug 23 20:10:55 volumio volumio[8137]: info: Loading plugin "i2s_dacs"... Aug 23 20:10:55 volumio volumio[8137]: info: Loading plugin "volumiodiscovery"... Aug 23 20:10:55 volumio volumio[8137]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:55 volumio volumio[8137]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:55 volumio volumio[8137]: *** WARNING *** For more information see Aug 23 20:10:55 volumio volumio[8137]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:55 volumio node[8137]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:55 volumio volumio[8137]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:55 volumio volumio[8137]: *** WARNING *** For more information see Aug 23 20:10:55 volumio node[8137]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:55 volumio node[8137]: *** WARNING *** For more information see Aug 23 20:10:55 volumio node[8137]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:10:55 volumio node[8137]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:10:55 volumio node[8137]: *** WARNING *** For more information see Aug 23 20:10:55 volumio volumio[8137]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 23 20:10:55 volumio volumio[8137]: info: Discovery: Started advertising with name: VOLUMIO Aug 23 20:10:55 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:10:55 volumio volumio[8137]: info: Loading plugin "squeezelite_mc"... Aug 23 20:10:56 volumio volumio[8137]: info: Loading plugin "outputs"... Aug 23 20:10:56 volumio volumio[8137]: info: Loading plugin "albumart"... Aug 23 20:10:56 volumio volumio[8137]: info: Plugin example_plugin is not enabled Aug 23 20:10:56 volumio volumio[8137]: info: Loading plugin "inputs"... Aug 23 20:10:56 volumio volumio[8137]: info: Loading plugin "updater_comm"... Aug 23 20:10:56 volumio volumio[8137]: info: Plugin mpdemulation is not enabled Aug 23 20:10:56 volumio volumio[8137]: info: Loading plugin "rest_api"... Aug 23 20:10:56 volumio volumio[8137]: info: Loading plugin "websocket"... Aug 23 20:10:56 volumio volumio[8137]: info: Starting Socket.io Server version 2.3.0 Aug 23 20:10:56 volumio volumio[8137]: info: Loading plugin "Systeminfo"... Aug 23 20:10:56 volumio wpa_supplicant[1085]: wlan0: Trying to associate with SSID 'botbot' Aug 23 20:10:56 volumio volumio[8137]: Forking 3 albumart workers Aug 23 20:10:56 volumio volumio[8137]: info: Loading i18n strings for locale en Aug 23 20:10:56 volumio volumio[8137]: Updating browse sources language Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::initPlayerControls Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:56 volumio volumio[8137]: Express server listening on port 3000 Aug 23 20:10:56 volumio volumio[8137]: [Metrics] WebUI: 2s 934.19ms Aug 23 20:10:56 volumio volumio[8137]: info: CoreStateMachine::resetVolumioState Aug 23 20:10:56 volumio volumio[8137]: info: CoreStateMachine::getcurrentVolume Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:10:56 volumio volumio[8137]: info: CoreStateMachine::pushState Aug 23 20:10:56 volumio volumio[8137]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:56 volumio volumio[8137]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Aug 23 20:10:56 volumio volumio[8137]: wlan0 Interface doesn't support scanning : Device or resource busy Aug 23 20:10:56 volumio volumio[8137]: info: Cannot use regular scanning, forcing with ap-force Aug 23 20:10:56 volumio sudo[8207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Aug 23 20:10:56 volumio sudo[8207]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:56 volumio sudo[8207]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:56 volumio kernel: ieee80211 phy0: brcmf_cfg80211_scan: Connecting: status (3) Aug 23 20:10:56 volumio volumio[8137]: command failed: Resource temporarily unavailable (-11) Aug 23 20:10:56 volumio volumio[8137]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Aug 23 20:10:56 volumio volumio[8137]: command failed: Resource temporarily unavailable (-11) Aug 23 20:10:56 volumio volumio-remote-updater[598]: [2024-08-23 20:10:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724415055 101 Aug 23 20:10:56 volumio volumio[8137]: 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 Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:56 volumio volumio[8137]: info: Reloading queue from file Aug 23 20:10:56 volumio volumio[8137]: info: CoreStateMachine::setRepeat null single undefined Aug 23 20:10:56 volumio volumio[8137]: info: CoreStateMachine::pushState Aug 23 20:10:56 volumio volumio[8137]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:56 volumio volumio[8137]: info: CoreStateMachine::setRandom null Aug 23 20:10:56 volumio volumio[8137]: info: CoreStateMachine::pushState Aug 23 20:10:56 volumio volumio[8137]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:56 volumio volumio[8137]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 23 20:10:56 volumio volumio[8137]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Aug 23 20:10:56 volumio volumio[8137]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 23 20:10:56 volumio volumio[8137]: info: Setting Device type: Raspberry PI Aug 23 20:10:56 volumio volumio[8137]: info: Discovery: adding 92d514c2-05e0-4590-90ec-0ae0399380af Aug 23 20:10:56 volumio volumio[8137]: info: Discovery: Found device VOLUMIO Aug 23 20:10:56 volumio volumio[8137]: info: CoreCommandRouter::volumioGetState Aug 23 20:10:56 volumio volumio[8137]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:56 volumio volumio[8137]: info: Completed loading Core Plugins Aug 23 20:10:56 volumio volumio[8137]: info: Preparing to generate the ALSA configuration file Aug 23 20:10:56 volumio volumio[8137]: info: Asound.conf file unchanged, so no further update is needed Aug 23 20:10:56 volumio volumio[8137]: info: Output device has changed, restarting MPD Aug 23 20:10:57 volumio volumio[8137]: info: Output device has changed, restarting Shairport Sync Aug 23 20:10:57 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:57 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:57 volumio sudo[8216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:10:57 volumio sudo[8216]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:57 volumio volumio[8137]: Starting albumart workers Aug 23 20:10:57 volumio sudo[8216]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:57 volumio sudo[8217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:10:57 volumio sudo[8217]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:57 volumio volumio[8137]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:57 volumio volumio[8137]: info: ___________ START PLUGINS ___________ Aug 23 20:10:57 volumio volumio[8137]: Starting albumart workers Aug 23 20:10:57 volumio systemd[1]: Stopping Music Player Daemon... Aug 23 20:10:57 volumio volumio[8137]: info: ControllerMpd::onStart: Initializing MPD Aug 23 20:10:57 volumio volumio[8137]: info: Creating MPD Configuration file Aug 23 20:10:57 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:10:57 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:10:57 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:57 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:57 volumio sudo[8225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:10:57 volumio volumio[8137]: Starting albumart workers Aug 23 20:10:57 volumio sudo[8225]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:57 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:10:57 volumio sudo[8228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:10:57 volumio sudo[8228]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:57 volumio sudo[8225]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:57 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 23 20:10:57 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:10:57 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:10:57 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:10:57 volumio volumio[8137]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:57 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:57 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:57 volumio volumio[8137]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:10:57 volumio volumio[8137]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:10:57 volumio sudo[8233]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 23 20:10:57 volumio volumio[8137]: info: [1724415057340] CoreMusicLibrary::Adding element Last_100 Aug 23 20:10:57 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:57 volumio sudo[8233]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:57 volumio volumio[8137]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:10:57 volumio volumio[8137]: info: [1724415057345] CoreMusicLibrary::Adding element Webradio Aug 23 20:10:57 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:10:57 volumio sudo[8233]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:57 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:57 volumio volumio[8137]: info: Initializing BBC Radios Aug 23 20:10:57 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:10:57 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:10:57 volumio volumio[8137]: info: [squeezelite_mc] Starting proxy server... Aug 23 20:10:57 volumio volumio[8137]: info: Volumio Calling Home Aug 23 20:10:57 volumio volumio[8137]: info: [squeezelite_mc] Proxy server started on port 37631 Aug 23 20:10:57 volumio volumio[8137]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:10:57 volumio volumio[8137]: info: CoreStateMachine::pushState Aug 23 20:10:57 volumio volumio[8137]: info: CorePlayQueue::getTrack 0 Aug 23 20:10:57 volumio volumio[8137]: info: CoreCommandRouter::volumioPushState Aug 23 20:10:57 volumio volumio[8137]: info: [squeezelite_mc] Server discovery started Aug 23 20:10:57 volumio volumio[8137]: info: [squeezelite_mc] Player finder started Aug 23 20:10:57 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:57 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:10:57 volumio volumio[8137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:10:57 volumio volumio[8137]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Aug 23 20:10:57 volumio volumio[8137]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:10:57 volumio volumio[8137]: Error: send ENETUNREACH 255.255.255.255:3483 Aug 23 20:10:57 volumio volumio[8137]: at doSend (dgram.js:692:16) Aug 23 20:10:57 volumio volumio[8137]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Aug 23 20:10:57 volumio volumio[8137]: at afterDns (dgram.js:638:5) Aug 23 20:10:57 volumio volumio[8137]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 23 20:10:57 volumio volumio[8137]: errno: -101, Aug 23 20:10:57 volumio volumio[8137]: code: 'ENETUNREACH', Aug 23 20:10:57 volumio volumio[8137]: syscall: 'send', Aug 23 20:10:57 volumio volumio[8137]: address: '255.255.255.255', Aug 23 20:10:57 volumio volumio[8137]: port: 3483 Aug 23 20:10:57 volumio volumio[8137]: } Aug 23 20:10:57 volumio volumio[8137]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:10:58 volumio sudo[8287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 20:09 Aug 23 20:10:58 volumio sudo[8287]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:10:58 volumio mpd[8238]: Aug 23 20:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 23 20:10:58 volumio systemd[1]: Started Music Player Daemon. Aug 23 20:10:58 volumio sudo[8217]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:58 volumio sudo[8228]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:58 volumio sudo[8287]: pam_unix(sudo:session): session closed for user root Aug 23 20:10:58 volumio volumio-remote-updater[598]: [2024-08-23 20:10:58] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 23 20:10:58 volumio volumio-remote-updater[598]: [2024-08-23 20:10:58] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 23 20:10:58 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 23 20:10:58 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 23 20:10:58 volumio systemd[1]: Started dynamicswap service. Aug 23 20:10:58 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 23 20:10:58 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 23 20:10:58 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 10. Aug 23 20:10:58 volumio systemd[1]: Stopped Volumio Backend Module. Aug 23 20:10:58 volumio systemd[1]: Started Volumio Backend Module. Aug 23 20:10:58 volumio systemd[1]: Started dynamicswap service. Aug 23 20:10:58 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 23 20:10:58 volumio dhcpcd[1118]: wlan0: carrier acquired Aug 23 20:10:59 volumio wpa_supplicant[1085]: wlan0: Associated with 36:86:ab:ad:23:df Aug 23 20:10:59 volumio wpa_supplicant[1085]: wlan0: CTRL-EVENT-CONNECTED - Connection to 36:86:ab:ad:23:df completed [id=0 id_str=] Aug 23 20:10:59 volumio wpa_supplicant[1085]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 23 20:10:59 volumio wpa_supplicant[1085]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=AU Aug 23 20:10:59 volumio dhcpcd[1118]: wlan0: IAID 32:52:ab:68 Aug 23 20:10:59 volumio dhcpcd[1118]: wlan0: carrier lost Aug 23 20:10:59 volumio dhcpcd[1118]: wlan0: carrier acquired Aug 23 20:10:59 volumio dhcpcd[1118]: wlan0: IAID 32:52:ab:68 Aug 23 20:10:59 volumio dhcpcd[1118]: wlan0: soliciting an IPv6 router Aug 23 20:10:59 volumio dhcpcd[1118]: wlan0: rebinding lease of 192.168.0.248 Aug 23 20:10:59 volumio dhcpcd[1118]: wlan0: probing address 192.168.0.248/24 Aug 23 20:10:59 volumio volumio[8303]: info: ------------------------------------------- Aug 23 20:10:59 volumio volumio[8303]: info: ----- Volumio3 ---- Aug 23 20:10:59 volumio volumio[8303]: info: ------------------------------------------- Aug 23 20:10:59 volumio volumio[8303]: info: ----- System startup ---- Aug 23 20:10:59 volumio volumio[8303]: info: ------------------------------------------- Aug 23 20:11:00 volumio volumio[8303]: info: MYVOLUMIO Environment detected Aug 23 20:11:00 volumio volumio[8303]: info: Plugin folders cleanup Aug 23 20:11:00 volumio volumio[8303]: info: Scanning into folder /volumio/app/plugins/ Aug 23 20:11:00 volumio volumio[8303]: info: Scanning category audio_interface Aug 23 20:11:00 volumio volumio[8303]: info: Scanning category miscellanea Aug 23 20:11:00 volumio volumio[8303]: info: Scanning category music_service Aug 23 20:11:00 volumio volumio[8303]: info: Scanning category plugins.json Aug 23 20:11:00 volumio volumio[8303]: info: Scanning category system_controller Aug 23 20:11:00 volumio volumio[8303]: info: Scanning category user_interface Aug 23 20:11:00 volumio volumio[8303]: info: Scanning into folder /data/plugins/ Aug 23 20:11:00 volumio volumio[8303]: info: Scanning category music_service Aug 23 20:11:00 volumio volumio[8303]: info: Scanning category user_interface Aug 23 20:11:00 volumio volumio[8303]: info: Plugin folders cleanup completed Aug 23 20:11:00 volumio volumio[8303]: info: ------------------------------------------- Aug 23 20:11:00 volumio volumio[8303]: info: ----- Core plugins startup ---- Aug 23 20:11:00 volumio volumio[8303]: info: ------------------------------------------- Aug 23 20:11:00 volumio volumio[8303]: info: Loading plugins from folder /volumio/app/plugins/ Aug 23 20:11:00 volumio volumio[8303]: info: Adding plugin upnp to MyMusic Plugins Aug 23 20:11:00 volumio volumio[8303]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 23 20:11:00 volumio volumio[8303]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 23 20:11:00 volumio volumio[8303]: info: Loading plugins from folder /data/plugins/ Aug 23 20:11:00 volumio volumio[8303]: info: Loading plugin "system"... Aug 23 20:11:00 volumio volumio[8303]: info: Loading plugin "appearance"... Aug 23 20:11:00 volumio volumio[8303]: info: Loading plugin "network"... Aug 23 20:11:00 volumio volumio[8303]: info: Refreshing Cached IP Addresses Aug 23 20:11:00 volumio sudo[8342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 20:11:00 volumio sudo[8342]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:11:00 volumio sudo[8342]: pam_unix(sudo:session): session closed for user root Aug 23 20:11:00 volumio sudo[8344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 20:11:00 volumio sudo[8344]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:11:00 volumio volumio[8303]: info: Loading plugin "services"... Aug 23 20:11:00 volumio volumio[8303]: info: Loading plugin "alsa_controller"... Aug 23 20:11:00 volumio sudo[8344]: pam_unix(sudo:session): session closed for user root Aug 23 20:11:00 volumio sudo[8347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 23 20:11:00 volumio sudo[8347]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:11:00 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:11:00 volumio volumio[8303]: info: Loading plugin "wizard"... Aug 23 20:11:00 volumio volumio[8303]: info: Loading plugin "networkfs"... Aug 23 20:11:00 volumio volumio[8303]: info: Starting Udev Watcher for removable devices Aug 23 20:11:00 volumio volumio[8303]: info: Ignoring mount for partition: boot Aug 23 20:11:00 volumio volumio[8303]: info: Ignoring mount for partition: volumio Aug 23 20:11:00 volumio volumio[8303]: info: Ignoring mount for partition: volumio_data Aug 23 20:11:00 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:11:00 volumio volumio[8303]: info: Loading plugin "volumio_command_line_client"... Aug 23 20:11:00 volumio volumio[8303]: info: Plugin upnp is not enabled Aug 23 20:11:00 volumio volumio[8303]: info: Loading plugin "my_music"... Aug 23 20:11:00 volumio volumio[8303]: info: Loading plugin "mpd"... Aug 23 20:11:00 volumio volumio[8303]: info: Plugin upnp_browser is not enabled Aug 23 20:11:00 volumio volumio[8303]: info: Loading plugin "alarm-clock"... Aug 23 20:11:01 volumio volumio[8303]: info: Loading plugin "airplay_emulation"... Aug 23 20:11:01 volumio volumio[8303]: info: Starting Shairport Sync Aug 23 20:11:01 volumio volumio[8303]: info: Loading plugin "last_100"... Aug 23 20:11:01 volumio volumio[8303]: info: Loading plugin "webradio"... Aug 23 20:11:01 volumio volumio[8303]: info: Loading plugin "i2s_dacs"... Aug 23 20:11:01 volumio volumio[8303]: info: Loading plugin "volumiodiscovery"... Aug 23 20:11:01 volumio volumio[8303]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:11:01 volumio volumio[8303]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:11:01 volumio volumio[8303]: *** WARNING *** For more information see Aug 23 20:11:01 volumio node[8303]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 20:11:01 volumio volumio[8303]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:11:01 volumio volumio[8303]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:11:01 volumio volumio[8303]: *** WARNING *** For more information see Aug 23 20:11:01 volumio node[8303]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:11:01 volumio node[8303]: *** WARNING *** For more information see Aug 23 20:11:01 volumio node[8303]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 20:11:01 volumio node[8303]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 20:11:01 volumio node[8303]: *** WARNING *** For more information see Aug 23 20:11:01 volumio volumio[8303]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 23 20:11:01 volumio volumio[8303]: info: Discovery: Started advertising with name: VOLUMIO Aug 23 20:11:01 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 20:11:01 volumio volumio[8303]: info: Loading plugin "squeezelite_mc"... Aug 23 20:11:01 volumio volumio[8303]: info: Loading plugin "outputs"... Aug 23 20:11:01 volumio volumio[8303]: info: Loading plugin "albumart"... Aug 23 20:11:01 volumio volumio[8303]: info: Plugin example_plugin is not enabled Aug 23 20:11:01 volumio volumio[8303]: info: Loading plugin "inputs"... Aug 23 20:11:01 volumio volumio[8303]: info: Loading plugin "updater_comm"... Aug 23 20:11:01 volumio volumio[8303]: info: Plugin mpdemulation is not enabled Aug 23 20:11:01 volumio volumio[8303]: info: Loading plugin "rest_api"... Aug 23 20:11:01 volumio volumio[8303]: info: Loading plugin "websocket"... Aug 23 20:11:01 volumio volumio[8303]: info: Starting Socket.io Server version 2.3.0 Aug 23 20:11:01 volumio volumio[8303]: info: Loading plugin "Systeminfo"... Aug 23 20:11:01 volumio volumio[8303]: Forking 3 albumart workers Aug 23 20:11:02 volumio volumio[8303]: info: Loading i18n strings for locale en Aug 23 20:11:02 volumio volumio[8303]: Updating browse sources language Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::initPlayerControls Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:11:02 volumio volumio[8303]: Express server listening on port 3000 Aug 23 20:11:02 volumio volumio[8303]: [Metrics] WebUI: 2s 918.67ms Aug 23 20:11:02 volumio volumio[8303]: info: CoreStateMachine::resetVolumioState Aug 23 20:11:02 volumio volumio[8303]: info: CoreStateMachine::getcurrentVolume Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:11:02 volumio volumio[8303]: info: CoreStateMachine::pushState Aug 23 20:11:02 volumio volumio[8303]: info: CorePlayQueue::getTrack 0 Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::volumioPushState Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:11:02 volumio volumio[8303]: info: Reloading queue from file Aug 23 20:11:02 volumio volumio[8303]: info: CoreStateMachine::setRepeat null single undefined Aug 23 20:11:02 volumio volumio[8303]: info: CoreStateMachine::pushState Aug 23 20:11:02 volumio volumio[8303]: info: CorePlayQueue::getTrack 0 Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::volumioPushState Aug 23 20:11:02 volumio volumio[8303]: info: CoreStateMachine::setRandom null Aug 23 20:11:02 volumio volumio[8303]: info: CoreStateMachine::pushState Aug 23 20:11:02 volumio volumio[8303]: info: CorePlayQueue::getTrack 0 Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::volumioPushState Aug 23 20:11:02 volumio volumio[8303]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 23 20:11:02 volumio volumio[8303]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Aug 23 20:11:02 volumio volumio[8303]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 23 20:11:02 volumio volumio[8303]: info: Setting Device type: Raspberry PI Aug 23 20:11:02 volumio volumio[8303]: info: Completed loading Core Plugins Aug 23 20:11:02 volumio volumio[8303]: info: Preparing to generate the ALSA configuration file Aug 23 20:11:02 volumio volumio[8303]: info: Asound.conf file unchanged, so no further update is needed Aug 23 20:11:02 volumio volumio[8303]: info: Output device has changed, restarting MPD Aug 23 20:11:02 volumio sudo[8392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:11:02 volumio volumio[8303]: info: Output device has changed, restarting Shairport Sync Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:11:02 volumio sudo[8392]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:11:02 volumio sudo[8392]: pam_unix(sudo:session): session closed for user root Aug 23 20:11:02 volumio sudo[8393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:11:02 volumio sudo[8393]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:11:02 volumio volumio[8303]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:11:02 volumio volumio[8303]: info: ___________ START PLUGINS ___________ Aug 23 20:11:02 volumio systemd[1]: Stopping Music Player Daemon... Aug 23 20:11:02 volumio volumio[8303]: info: ControllerMpd::onStart: Initializing MPD Aug 23 20:11:02 volumio volumio[8303]: info: Creating MPD Configuration file Aug 23 20:11:02 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:11:02 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:11:02 volumio sudo[8402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 20:11:02 volumio sudo[8402]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:11:02 volumio sudo[8402]: pam_unix(sudo:session): session closed for user root Aug 23 20:11:02 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:11:02 volumio sudo[8404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 20:11:02 volumio sudo[8404]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:11:02 volumio volumio[8303]: Starting albumart workers Aug 23 20:11:02 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 23 20:11:02 volumio systemd[1]: mpd.service: Succeeded. Aug 23 20:11:02 volumio volumio[8303]: Starting albumart workers Aug 23 20:11:02 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 20:11:02 volumio systemd[1]: Starting Music Player Daemon... Aug 23 20:11:02 volumio volumio[8303]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:11:02 volumio volumio[8303]: Starting albumart workers Aug 23 20:11:02 volumio sudo[8409]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 23 20:11:02 volumio sudo[8409]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 20:11:02 volumio sudo[8409]: pam_unix(sudo:session): session closed for user root Aug 23 20:11:02 volumio volumio[8303]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:11:02 volumio volumio[8303]: info: [1724415062793] CoreMusicLibrary::Adding element Last_100 Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 20:11:02 volumio volumio[8303]: info: [1724415062796] CoreMusicLibrary::Adding element Webradio Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 20:11:02 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:11:02 volumio volumio[8303]: info: Initializing BBC Radios Aug 23 20:11:03 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 20:11:03 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 20:11:03 volumio volumio[8303]: info: [squeezelite_mc] Starting proxy server... Aug 23 20:11:03 volumio volumio[8303]: info: Volumio Calling Home Aug 23 20:11:03 volumio volumio[8303]: info: [squeezelite_mc] Proxy server started on port 45419 Aug 23 20:11:03 volumio volumio[8303]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 20:11:03 volumio volumio[8303]: info: CoreStateMachine::pushState Aug 23 20:11:03 volumio volumio[8303]: info: CorePlayQueue::getTrack 0 Aug 23 20:11:03 volumio volumio[8303]: info: CoreCommandRouter::volumioPushState Aug 23 20:11:03 volumio volumio[8303]: info: [squeezelite_mc] Server discovery started Aug 23 20:11:03 volumio volumio[8303]: info: [squeezelite_mc] Player finder started Aug 23 20:11:03 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:11:03 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 20:11:03 volumio volumio[8303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 20:11:03 volumio volumio[8303]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Aug 23 20:11:03 volumio volumio[8303]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:11:03 volumio volumio[8303]: Error: send ENETUNREACH 255.255.255.255:3483 Aug 23 20:11:03 volumio volumio[8303]: at doSend (dgram.js:692:16) Aug 23 20:11:03 volumio volumio[8303]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Aug 23 20:11:03 volumio volumio[8303]: at afterDns (dgram.js:638:5) Aug 23 20:11:03 volumio volumio[8303]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 23 20:11:03 volumio volumio[8303]: errno: -101, Aug 23 20:11:03 volumio volumio[8303]: code: 'ENETUNREACH', Aug 23 20:11:03 volumio volumio[8303]: syscall: 'send', Aug 23 20:11:03 volumio volumio[8303]: address: '255.255.255.255', Aug 23 20:11:03 volumio volumio[8303]: port: 3483 Aug 23 20:11:03 volumio volumio[8303]: } Aug 23 20:11:03 volumio volumio[8303]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 20:11:03 volumio volumio-remote-updater[598]: [2024-08-23 20:11:03] [connect] Successful connection Aug 23 20:11:03 volumio mpd[8414]: Aug 23 20:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 23 20:11:03 volumio systemd[1]: Started Music Player Daemon. Aug 23 20:11:03 volumio sudo[8393]: pam_unix(sudo:session): session closed for user root Aug 23 20:11:03 volumio sudo[8404]: pam_unix(sudo:session): session closed for user root Aug 23 20:11:03 volumio sudo[8465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 20:10 Aug 23 20:11:03 volumio sudo[8465]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"