-- Logs begin at Wed 2024-10-02 22:20:58 EDT, end at Sat 2024-10-05 03:17:13 EDT. -- Oct 05 03:16:00 volumio-living volumio[18332]: info: Loading plugin "outputs"... Oct 05 03:16:00 volumio-living volumio[18332]: info: Loading plugin "albumart"... Oct 05 03:16:00 volumio-living volumio[18332]: info: Plugin example_plugin is not enabled Oct 05 03:16:00 volumio-living volumio[18332]: info: Loading plugin "inputs"... Oct 05 03:16:00 volumio-living volumio[18332]: info: Loading plugin "updater_comm"... Oct 05 03:16:00 volumio-living volumio[18332]: info: Plugin mpdemulation is not enabled Oct 05 03:16:00 volumio-living volumio[18332]: info: Loading plugin "rest_api"... Oct 05 03:16:00 volumio-living volumio[18332]: info: Loading plugin "websocket"... Oct 05 03:16:00 volumio-living volumio[18332]: info: Starting Socket.io Server version 2.3.0 Oct 05 03:16:00 volumio-living volumio[18332]: info: Loading plugin "Systeminfo"... Oct 05 03:16:00 volumio-living volumio[18332]: Forking 3 albumart workers Oct 05 03:16:00 volumio-living volumio[18332]: info: Loading i18n strings for locale zh_TW Oct 05 03:16:00 volumio-living volumio[18332]: Updating browse sources language Oct 05 03:16:00 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:01 volumio-living sudo[18363]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::initPlayerControls Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:01 volumio-living volumio[18332]: Express server listening on port 3000 Oct 05 03:16:01 volumio-living volumio[18332]: [Metrics] WebUI: 5s 191.40ms Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreStateMachine::resetVolumioState Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreStateMachine::getcurrentVolume Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreStateMachine::pushState Oct 05 03:16:01 volumio-living volumio[18332]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 03:16:01 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728112559 101 Oct 05 03:16:01 volumio-living volumio[18332]: 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 Oct 05 03:16:01 volumio-living volumio[18332]: info: Reloading queue from file Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreStateMachine::setRepeat null single undefined Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreStateMachine::pushState Oct 05 03:16:01 volumio-living volumio[18332]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreStateMachine::setRandom null Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreStateMachine::pushState Oct 05 03:16:01 volumio-living volumio[18332]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:01 volumio-living volumio[18332]: info: Setting Device type: Raspberry PI Oct 05 03:16:01 volumio-living volumio[18332]: info: Completed loading Core Plugins Oct 05 03:16:01 volumio-living volumio[18332]: info: Preparing to generate the ALSA configuration file Oct 05 03:16:01 volumio-living volumio[18332]: info: Asound.conf file unchanged, so no further update is needed Oct 05 03:16:01 volumio-living volumio[18332]: info: Output device has changed, restarting MPD Oct 05 03:16:01 volumio-living sudo[18406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 03:16:01 volumio-living sudo[18406]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:01 volumio-living volumio[18332]: Starting albumart workers Oct 05 03:16:01 volumio-living sudo[18406]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:01 volumio-living volumio[18332]: info: ___________ START PLUGINS ___________ Oct 05 03:16:01 volumio-living volumio[18332]: info: ControllerMpd::onStart: Initializing MPD Oct 05 03:16:01 volumio-living volumio[18332]: info: Creating MPD Configuration file Oct 05 03:16:01 volumio-living sudo[18409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 03:16:01 volumio-living sudo[18409]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:01 volumio-living systemd[1]: Stopping Music Player Daemon... Oct 05 03:16:01 volumio-living systemd[1]: mpd.service: Succeeded. Oct 05 03:16:01 volumio-living systemd[1]: Stopped Music Player Daemon. Oct 05 03:16:01 volumio-living sudo[18412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 03:16:01 volumio-living sudo[18412]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:01 volumio-living volumio[18332]: info: [1728112561864] CoreMusicLibrary::Adding element Media Servers Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:01 volumio-living sudo[18412]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:01 volumio-living systemd[1]: Starting Music Player Daemon... Oct 05 03:16:01 volumio-living volumio[18332]: Starting albumart workers Oct 05 03:16:01 volumio-living sudo[18416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 03:16:01 volumio-living volumio[18332]: Starting albumart workers Oct 05 03:16:01 volumio-living sudo[18416]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:01 volumio-living systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 05 03:16:01 volumio-living systemd[1]: mpd.service: Succeeded. Oct 05 03:16:01 volumio-living systemd[1]: Stopped Music Player Daemon. Oct 05 03:16:01 volumio-living systemd[1]: Starting Music Player Daemon... Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:01 volumio-living volumio[18332]: info: [1728112561987] CoreMusicLibrary::Adding element Last_100 Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:01 volumio-living volumio[18332]: info: [1728112561998] CoreMusicLibrary::Adding element Webradio Oct 05 03:16:01 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:02 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:02 volumio-living volumio[18332]: info: Initializing BBC Radios Oct 05 03:16:02 volumio-living sudo[18421]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 05 03:16:02 volumio-living sudo[18421]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:02 volumio-living sudo[18421]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:02 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:02 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 03:16:02 volumio-living volumio[18332]: info: [squeezelite_mc] Starting proxy server... Oct 05 03:16:02 volumio-living volumio[18332]: info: Volumio Calling Home Oct 05 03:16:02 volumio-living volumio[18332]: info: [squeezelite_mc] Proxy server started on port 33553 Oct 05 03:16:02 volumio-living volumio[18332]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 03:16:02 volumio-living volumio[18332]: info: CoreStateMachine::pushState Oct 05 03:16:02 volumio-living volumio[18332]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:02 volumio-living volumio[18332]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:02 volumio-living volumio[18332]: info: [squeezelite_mc] Server discovery started Oct 05 03:16:02 volumio-living volumio[18332]: info: [squeezelite_mc] Player finder started Oct 05 03:16:02 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:02 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:02 volumio-living volumio[18332]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:02 volumio-living volumio[18332]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 05 03:16:02 volumio-living volumio[18332]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 03:16:02 volumio-living volumio[18332]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 05 03:16:02 volumio-living volumio[18332]: at doSend (dgram.js:692:16) Oct 05 03:16:02 volumio-living volumio[18332]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 05 03:16:02 volumio-living volumio[18332]: at afterDns (dgram.js:638:5) Oct 05 03:16:02 volumio-living volumio[18332]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 05 03:16:02 volumio-living volumio[18332]: errno: -101, Oct 05 03:16:02 volumio-living volumio[18332]: code: 'ENETUNREACH', Oct 05 03:16:02 volumio-living volumio[18332]: syscall: 'send', Oct 05 03:16:02 volumio-living volumio[18332]: address: '255.255.255.255', Oct 05 03:16:02 volumio-living volumio[18332]: port: 3483 Oct 05 03:16:02 volumio-living volumio[18332]: } Oct 05 03:16:02 volumio-living volumio[18332]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 03:16:03 volumio-living sudo[18472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 03:15 Oct 05 03:16:03 volumio-living sudo[18472]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:03 volumio-living sudo[18472]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:03 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:03] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 05 03:16:03 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:03] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 05 03:16:03 volumio-living systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 05 03:16:03 volumio-living sudo[18409]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:03 volumio-living sudo[18416]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:03 volumio-living systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 05 03:16:03 volumio-living systemd[1]: Started dynamicswap service. Oct 05 03:16:03 volumio-living systemd[1]: dynamicswap.service: Succeeded. Oct 05 03:16:03 volumio-living systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 05 03:16:03 volumio-living systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Oct 05 03:16:03 volumio-living systemd[1]: Stopped Volumio Backend Module. Oct 05 03:16:03 volumio-living systemd[1]: Started Volumio Backend Module. Oct 05 03:16:03 volumio-living systemd[1]: Started dynamicswap service. Oct 05 03:16:03 volumio-living systemd[1]: dynamicswap.service: Succeeded. Oct 05 03:16:03 volumio-living mpd[18429]: Oct 05 03:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 05 03:16:03 volumio-living systemd[1]: Started Music Player Daemon. Oct 05 03:16:04 volumio-living wpa_supplicant[852]: wlan0: Failed to initiate sched scan Oct 05 03:16:05 volumio-living volumio[18486]: info: ------------------------------------------- Oct 05 03:16:05 volumio-living volumio[18486]: info: ----- Volumio3 ---- Oct 05 03:16:05 volumio-living volumio[18486]: info: ------------------------------------------- Oct 05 03:16:05 volumio-living volumio[18486]: info: ----- System startup ---- Oct 05 03:16:05 volumio-living volumio[18486]: info: ------------------------------------------- Oct 05 03:16:06 volumio-living volumio[18486]: info: MYVOLUMIO Environment detected Oct 05 03:16:06 volumio-living volumio[18486]: info: Plugin folders cleanup Oct 05 03:16:06 volumio-living volumio[18486]: info: Scanning into folder /volumio/app/plugins/ Oct 05 03:16:06 volumio-living volumio[18486]: info: Scanning category audio_interface Oct 05 03:16:06 volumio-living volumio[18486]: info: Scanning category miscellanea Oct 05 03:16:06 volumio-living volumio[18486]: info: Scanning category music_service Oct 05 03:16:06 volumio-living volumio[18486]: info: Scanning category plugins.json Oct 05 03:16:06 volumio-living volumio[18486]: info: Scanning category system_controller Oct 05 03:16:06 volumio-living volumio[18486]: info: Scanning category user_interface Oct 05 03:16:06 volumio-living volumio[18486]: info: Scanning into folder /data/plugins/ Oct 05 03:16:06 volumio-living volumio[18486]: info: Scanning category music_service Oct 05 03:16:06 volumio-living volumio[18486]: info: Scanning category user_interface Oct 05 03:16:06 volumio-living volumio[18486]: info: Plugin folders cleanup completed Oct 05 03:16:06 volumio-living volumio[18486]: info: ------------------------------------------- Oct 05 03:16:06 volumio-living volumio[18486]: info: ----- Core plugins startup ---- Oct 05 03:16:06 volumio-living volumio[18486]: info: ------------------------------------------- Oct 05 03:16:06 volumio-living volumio[18486]: info: Loading plugins from folder /volumio/app/plugins/ Oct 05 03:16:06 volumio-living volumio[18486]: info: Adding plugin upnp to MyMusic Plugins Oct 05 03:16:06 volumio-living volumio[18486]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 05 03:16:06 volumio-living volumio[18486]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 05 03:16:06 volumio-living volumio[18486]: info: Loading plugins from folder /data/plugins/ Oct 05 03:16:06 volumio-living volumio[18486]: info: Loading plugin "system"... Oct 05 03:16:06 volumio-living volumio[18486]: info: Loading plugin "appearance"... Oct 05 03:16:07 volumio-living volumio[18486]: info: Loading plugin "network"... Oct 05 03:16:07 volumio-living volumio[18486]: info: Refreshing Cached IP Addresses Oct 05 03:16:07 volumio-living sudo[18516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 03:16:07 volumio-living sudo[18516]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:07 volumio-living sudo[18516]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:07 volumio-living sudo[18518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 03:16:07 volumio-living volumio[18486]: info: Loading plugin "services"... Oct 05 03:16:07 volumio-living sudo[18518]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:07 volumio-living volumio[18486]: info: Loading plugin "alsa_controller"... Oct 05 03:16:07 volumio-living sudo[18518]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:07 volumio-living sudo[18521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 05 03:16:07 volumio-living sudo[18521]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:07 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:07 volumio-living volumio[18486]: info: Loading plugin "wizard"... Oct 05 03:16:07 volumio-living volumio[18486]: info: Loading plugin "networkfs"... Oct 05 03:16:07 volumio-living volumio[18486]: info: Starting Udev Watcher for removable devices Oct 05 03:16:07 volumio-living volumio[18486]: info: Ignoring mount for partition: boot Oct 05 03:16:07 volumio-living volumio[18486]: info: Ignoring mount for partition: volumio Oct 05 03:16:07 volumio-living volumio[18486]: info: Ignoring mount for partition: volumio_data Oct 05 03:16:07 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:07 volumio-living volumio[18486]: info: Loading plugin "volumio_command_line_client"... Oct 05 03:16:07 volumio-living volumio[18486]: info: Loading plugin "upnp"... Oct 05 03:16:07 volumio-living volumio[18486]: info: [1728112567404] Starting Upmpd Daemon Oct 05 03:16:07 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:07 volumio-living volumio[18486]: info: Loading plugin "my_music"... Oct 05 03:16:07 volumio-living volumio[18486]: info: Loading plugin "mpd"... Oct 05 03:16:07 volumio-living volumio[18486]: info: Loading plugin "upnp_browser"... Oct 05 03:16:07 volumio-living volumio[18486]: info: Loading plugin "alarm-clock"... Oct 05 03:16:08 volumio-living volumio[18486]: info: Plugin airplay_emulation is not enabled Oct 05 03:16:08 volumio-living volumio[18486]: info: Loading plugin "last_100"... Oct 05 03:16:08 volumio-living volumio[18486]: info: Loading plugin "webradio"... Oct 05 03:16:08 volumio-living volumio[18486]: info: Loading plugin "i2s_dacs"... Oct 05 03:16:08 volumio-living volumio[18486]: info: I2S DAC not set, start Auto-detection Oct 05 03:16:08 volumio-living volumio[18486]: info: Loading plugin "volumiodiscovery"... Oct 05 03:16:08 volumio-living volumio[18486]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:08 volumio-living volumio[18486]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:08 volumio-living volumio[18486]: *** WARNING *** For more information see Oct 05 03:16:08 volumio-living volumio[18486]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:08 volumio-living node[18486]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:08 volumio-living volumio[18486]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:08 volumio-living volumio[18486]: *** WARNING *** For more information see Oct 05 03:16:08 volumio-living node[18486]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:08 volumio-living node[18486]: *** WARNING *** For more information see Oct 05 03:16:08 volumio-living node[18486]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:08 volumio-living node[18486]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:08 volumio-living node[18486]: *** WARNING *** For more information see Oct 05 03:16:08 volumio-living volumio[18486]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 05 03:16:08 volumio-living volumio[18486]: info: Discovery: Started advertising with name: Volumio-Living Oct 05 03:16:08 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:08 volumio-living volumio[18486]: info: Loading plugin "squeezelite_mc"... Oct 05 03:16:08 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:08] [connect] Successful connection Oct 05 03:16:09 volumio-living volumio[18486]: info: Loading plugin "outputs"... Oct 05 03:16:09 volumio-living volumio[18486]: info: Loading plugin "albumart"... Oct 05 03:16:09 volumio-living volumio[18486]: info: Plugin example_plugin is not enabled Oct 05 03:16:09 volumio-living volumio[18486]: info: Loading plugin "inputs"... Oct 05 03:16:09 volumio-living volumio[18486]: info: Loading plugin "updater_comm"... Oct 05 03:16:09 volumio-living volumio[18486]: info: Plugin mpdemulation is not enabled Oct 05 03:16:09 volumio-living volumio[18486]: info: Loading plugin "rest_api"... Oct 05 03:16:09 volumio-living volumio[18486]: info: Loading plugin "websocket"... Oct 05 03:16:09 volumio-living volumio[18486]: info: Starting Socket.io Server version 2.3.0 Oct 05 03:16:09 volumio-living volumio[18486]: info: Loading plugin "Systeminfo"... Oct 05 03:16:09 volumio-living volumio[18486]: Forking 3 albumart workers Oct 05 03:16:09 volumio-living volumio[18486]: info: Loading i18n strings for locale zh_TW Oct 05 03:16:09 volumio-living volumio[18486]: Updating browse sources language Oct 05 03:16:09 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:09 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:09 volumio-living volumio[18486]: info: CoreCommandRouter::initPlayerControls Oct 05 03:16:09 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:09 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:09 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:09 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:09 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:09 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:09 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:09 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:09 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:09 volumio-living volumio[18486]: Express server listening on port 3000 Oct 05 03:16:09 volumio-living volumio[18486]: [Metrics] WebUI: 5s 4.59ms Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreStateMachine::resetVolumioState Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreStateMachine::getcurrentVolume Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreStateMachine::pushState Oct 05 03:16:10 volumio-living volumio[18486]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 03:16:10 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728112568 101 Oct 05 03:16:10 volumio-living volumio[18486]: 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 Oct 05 03:16:10 volumio-living volumio[18486]: info: Reloading queue from file Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreStateMachine::setRepeat null single undefined Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreStateMachine::pushState Oct 05 03:16:10 volumio-living volumio[18486]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreStateMachine::setRandom null Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreStateMachine::pushState Oct 05 03:16:10 volumio-living volumio[18486]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:10 volumio-living volumio[18486]: info: Setting Device type: Raspberry PI Oct 05 03:16:10 volumio-living volumio[18486]: info: Completed loading Core Plugins Oct 05 03:16:10 volumio-living volumio[18486]: info: Preparing to generate the ALSA configuration file Oct 05 03:16:10 volumio-living volumio[18486]: info: Asound.conf file unchanged, so no further update is needed Oct 05 03:16:10 volumio-living volumio[18486]: info: Output device has changed, restarting MPD Oct 05 03:16:10 volumio-living volumio[18486]: info: ___________ START PLUGINS ___________ Oct 05 03:16:10 volumio-living volumio[18486]: info: ControllerMpd::onStart: Initializing MPD Oct 05 03:16:10 volumio-living volumio[18486]: info: Creating MPD Configuration file Oct 05 03:16:10 volumio-living sudo[18565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 03:16:10 volumio-living sudo[18565]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:10 volumio-living sudo[18566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 03:16:10 volumio-living sudo[18565]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:10 volumio-living sudo[18566]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:10 volumio-living sudo[18570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 03:16:10 volumio-living sudo[18570]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:10 volumio-living sudo[18570]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:10 volumio-living volumio[18486]: info: [1728112570508] CoreMusicLibrary::Adding element Media Servers Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:10 volumio-living systemd[1]: Stopping Music Player Daemon... Oct 05 03:16:10 volumio-living sudo[18573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 03:16:10 volumio-living sudo[18573]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:10 volumio-living systemd[1]: mpd.service: Succeeded. Oct 05 03:16:10 volumio-living systemd[1]: Stopped Music Player Daemon. Oct 05 03:16:10 volumio-living systemd[1]: Starting Music Player Daemon... Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:10 volumio-living volumio[18486]: Starting albumart workers Oct 05 03:16:10 volumio-living volumio[18486]: info: [1728112570638] CoreMusicLibrary::Adding element Last_100 Oct 05 03:16:10 volumio-living systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:10 volumio-living systemd[1]: mpd.service: Succeeded. Oct 05 03:16:10 volumio-living systemd[1]: Stopped Music Player Daemon. Oct 05 03:16:10 volumio-living volumio[18486]: Starting albumart workers Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:10 volumio-living volumio[18486]: info: [1728112570644] CoreMusicLibrary::Adding element Webradio Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:10 volumio-living systemd[1]: Starting Music Player Daemon... Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:10 volumio-living volumio[18486]: info: Initializing BBC Radios Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:10 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 03:16:10 volumio-living volumio[18486]: Starting albumart workers Oct 05 03:16:10 volumio-living volumio[18486]: info: [squeezelite_mc] Starting proxy server... Oct 05 03:16:10 volumio-living sudo[18576]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 05 03:16:10 volumio-living sudo[18576]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:10 volumio-living volumio[18486]: info: Volumio Calling Home Oct 05 03:16:10 volumio-living sudo[18576]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:10 volumio-living volumio[18486]: info: [squeezelite_mc] Proxy server started on port 41181 Oct 05 03:16:11 volumio-living volumio[18486]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 03:16:11 volumio-living volumio[18486]: info: CoreStateMachine::pushState Oct 05 03:16:11 volumio-living volumio[18486]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:11 volumio-living volumio[18486]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:11 volumio-living volumio[18486]: info: [squeezelite_mc] Server discovery started Oct 05 03:16:11 volumio-living volumio[18486]: info: [squeezelite_mc] Player finder started Oct 05 03:16:11 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:11 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:11 volumio-living volumio[18486]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:11 volumio-living volumio[18486]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 05 03:16:11 volumio-living volumio[18486]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 03:16:11 volumio-living volumio[18486]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 05 03:16:11 volumio-living volumio[18486]: at doSend (dgram.js:692:16) Oct 05 03:16:11 volumio-living volumio[18486]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 05 03:16:11 volumio-living volumio[18486]: at afterDns (dgram.js:638:5) Oct 05 03:16:11 volumio-living volumio[18486]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 05 03:16:11 volumio-living volumio[18486]: errno: -101, Oct 05 03:16:11 volumio-living volumio[18486]: code: 'ENETUNREACH', Oct 05 03:16:11 volumio-living volumio[18486]: syscall: 'send', Oct 05 03:16:11 volumio-living volumio[18486]: address: '255.255.255.255', Oct 05 03:16:11 volumio-living volumio[18486]: port: 3483 Oct 05 03:16:11 volumio-living volumio[18486]: } Oct 05 03:16:11 volumio-living volumio[18486]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 03:16:12 volumio-living sudo[18627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 03:15 Oct 05 03:16:12 volumio-living sudo[18627]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:12 volumio-living sudo[18627]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:12 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:12] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 05 03:16:12 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:12] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 05 03:16:12 volumio-living systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 05 03:16:12 volumio-living sudo[18566]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:12 volumio-living sudo[18573]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:12 volumio-living sudo[18521]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:12 volumio-living systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 05 03:16:12 volumio-living systemd[1]: Started dynamicswap service. Oct 05 03:16:12 volumio-living systemd[1]: dynamicswap.service: Succeeded. Oct 05 03:16:12 volumio-living systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 05 03:16:12 volumio-living systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Oct 05 03:16:12 volumio-living systemd[1]: Stopped Volumio Backend Module. Oct 05 03:16:12 volumio-living mpd[18588]: Oct 05 03:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 05 03:16:12 volumio-living systemd[1]: Started Volumio Backend Module. Oct 05 03:16:12 volumio-living systemd[1]: Started dynamicswap service. Oct 05 03:16:12 volumio-living systemd[1]: Started Music Player Daemon. Oct 05 03:16:12 volumio-living systemd[1]: dynamicswap.service: Succeeded. Oct 05 03:16:12 volumio-living wpa_supplicant[852]: wlan0: Failed to initiate sched scan Oct 05 03:16:14 volumio-living volumio[18641]: info: ------------------------------------------- Oct 05 03:16:14 volumio-living volumio[18641]: info: ----- Volumio3 ---- Oct 05 03:16:14 volumio-living volumio[18641]: info: ------------------------------------------- Oct 05 03:16:14 volumio-living volumio[18641]: info: ----- System startup ---- Oct 05 03:16:14 volumio-living volumio[18641]: info: ------------------------------------------- Oct 05 03:16:14 volumio-living volumio[18641]: info: MYVOLUMIO Environment detected Oct 05 03:16:14 volumio-living volumio[18641]: info: Plugin folders cleanup Oct 05 03:16:14 volumio-living volumio[18641]: info: Scanning into folder /volumio/app/plugins/ Oct 05 03:16:14 volumio-living volumio[18641]: info: Scanning category audio_interface Oct 05 03:16:14 volumio-living volumio[18641]: info: Scanning category miscellanea Oct 05 03:16:15 volumio-living volumio[18641]: info: Scanning category music_service Oct 05 03:16:15 volumio-living volumio[18641]: info: Scanning category plugins.json Oct 05 03:16:15 volumio-living volumio[18641]: info: Scanning category system_controller Oct 05 03:16:15 volumio-living volumio[18641]: info: Scanning category user_interface Oct 05 03:16:15 volumio-living volumio[18641]: info: Scanning into folder /data/plugins/ Oct 05 03:16:15 volumio-living volumio[18641]: info: Scanning category music_service Oct 05 03:16:15 volumio-living volumio[18641]: info: Scanning category user_interface Oct 05 03:16:15 volumio-living volumio[18641]: info: Plugin folders cleanup completed Oct 05 03:16:15 volumio-living volumio[18641]: info: ------------------------------------------- Oct 05 03:16:15 volumio-living volumio[18641]: info: ----- Core plugins startup ---- Oct 05 03:16:15 volumio-living volumio[18641]: info: ------------------------------------------- Oct 05 03:16:15 volumio-living volumio[18641]: info: Loading plugins from folder /volumio/app/plugins/ Oct 05 03:16:15 volumio-living volumio[18641]: info: Adding plugin upnp to MyMusic Plugins Oct 05 03:16:15 volumio-living volumio[18641]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 05 03:16:15 volumio-living volumio[18641]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 05 03:16:15 volumio-living volumio[18641]: info: Loading plugins from folder /data/plugins/ Oct 05 03:16:15 volumio-living volumio[18641]: info: Loading plugin "system"... Oct 05 03:16:15 volumio-living volumio[18641]: info: Loading plugin "appearance"... Oct 05 03:16:15 volumio-living volumio[18641]: info: Loading plugin "network"... Oct 05 03:16:15 volumio-living volumio[18641]: info: Refreshing Cached IP Addresses Oct 05 03:16:16 volumio-living sudo[18670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 03:16:16 volumio-living sudo[18670]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:16 volumio-living sudo[18670]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:16 volumio-living sudo[18672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 03:16:16 volumio-living volumio[18641]: info: Loading plugin "services"... Oct 05 03:16:16 volumio-living sudo[18672]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:16 volumio-living volumio[18641]: info: Loading plugin "alsa_controller"... Oct 05 03:16:16 volumio-living sudo[18672]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:16 volumio-living sudo[18675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 05 03:16:16 volumio-living sudo[18675]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:16 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:16 volumio-living volumio[18641]: info: Loading plugin "wizard"... Oct 05 03:16:16 volumio-living volumio[18641]: info: Loading plugin "networkfs"... Oct 05 03:16:16 volumio-living volumio[18641]: info: Starting Udev Watcher for removable devices Oct 05 03:16:16 volumio-living volumio[18641]: info: Ignoring mount for partition: boot Oct 05 03:16:16 volumio-living volumio[18641]: info: Ignoring mount for partition: volumio Oct 05 03:16:16 volumio-living volumio[18641]: info: Ignoring mount for partition: volumio_data Oct 05 03:16:16 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:16 volumio-living volumio[18641]: info: Loading plugin "volumio_command_line_client"... Oct 05 03:16:16 volumio-living volumio[18641]: info: Loading plugin "upnp"... Oct 05 03:16:16 volumio-living volumio[18641]: info: [1728112576172] Starting Upmpd Daemon Oct 05 03:16:16 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:16 volumio-living volumio[18641]: info: Loading plugin "my_music"... Oct 05 03:16:16 volumio-living volumio[18641]: info: Loading plugin "mpd"... Oct 05 03:16:16 volumio-living volumio[18641]: info: Loading plugin "upnp_browser"... Oct 05 03:16:16 volumio-living volumio[18641]: info: Loading plugin "alarm-clock"... Oct 05 03:16:16 volumio-living volumio[18641]: info: Plugin airplay_emulation is not enabled Oct 05 03:16:16 volumio-living volumio[18641]: info: Loading plugin "last_100"... Oct 05 03:16:16 volumio-living volumio[18641]: info: Loading plugin "webradio"... Oct 05 03:16:17 volumio-living volumio[18641]: info: Loading plugin "i2s_dacs"... Oct 05 03:16:17 volumio-living volumio[18641]: info: I2S DAC not set, start Auto-detection Oct 05 03:16:17 volumio-living volumio[18641]: info: Loading plugin "volumiodiscovery"... Oct 05 03:16:17 volumio-living volumio[18641]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:17 volumio-living volumio[18641]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:17 volumio-living volumio[18641]: *** WARNING *** For more information see Oct 05 03:16:17 volumio-living volumio[18641]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:17 volumio-living node[18641]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:17 volumio-living volumio[18641]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:17 volumio-living volumio[18641]: *** WARNING *** For more information see Oct 05 03:16:17 volumio-living node[18641]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:17 volumio-living node[18641]: *** WARNING *** For more information see Oct 05 03:16:17 volumio-living node[18641]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:17 volumio-living node[18641]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:17 volumio-living node[18641]: *** WARNING *** For more information see Oct 05 03:16:17 volumio-living volumio[18641]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 05 03:16:17 volumio-living volumio[18641]: info: Discovery: Started advertising with name: Volumio-Living Oct 05 03:16:17 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:17 volumio-living volumio[18641]: info: Loading plugin "squeezelite_mc"... Oct 05 03:16:17 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:17] [connect] Successful connection Oct 05 03:16:17 volumio-living volumio[18641]: info: Loading plugin "outputs"... Oct 05 03:16:17 volumio-living volumio[18641]: info: Loading plugin "albumart"... Oct 05 03:16:17 volumio-living volumio[18641]: info: Plugin example_plugin is not enabled Oct 05 03:16:17 volumio-living volumio[18641]: info: Loading plugin "inputs"... Oct 05 03:16:17 volumio-living volumio[18641]: info: Loading plugin "updater_comm"... Oct 05 03:16:18 volumio-living volumio[18641]: info: Plugin mpdemulation is not enabled Oct 05 03:16:18 volumio-living volumio[18641]: info: Loading plugin "rest_api"... Oct 05 03:16:18 volumio-living volumio[18641]: info: Loading plugin "websocket"... Oct 05 03:16:18 volumio-living volumio[18641]: info: Starting Socket.io Server version 2.3.0 Oct 05 03:16:18 volumio-living volumio[18641]: info: Loading plugin "Systeminfo"... Oct 05 03:16:18 volumio-living volumio[18641]: Forking 3 albumart workers Oct 05 03:16:18 volumio-living volumio[18641]: info: Loading i18n strings for locale zh_TW Oct 05 03:16:18 volumio-living volumio[18641]: Updating browse sources language Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreCommandRouter::initPlayerControls Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:18 volumio-living volumio[18641]: Express server listening on port 3000 Oct 05 03:16:18 volumio-living volumio[18641]: [Metrics] WebUI: 4s 966.98ms Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreStateMachine::resetVolumioState Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreStateMachine::getcurrentVolume Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreStateMachine::pushState Oct 05 03:16:18 volumio-living volumio[18641]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:18 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 03:16:18 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728112577 101 Oct 05 03:16:19 volumio-living volumio[18641]: 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 Oct 05 03:16:19 volumio-living volumio[18641]: info: Reloading queue from file Oct 05 03:16:19 volumio-living volumio[18641]: info: CoreStateMachine::setRepeat null single undefined Oct 05 03:16:19 volumio-living volumio[18641]: info: CoreStateMachine::pushState Oct 05 03:16:19 volumio-living volumio[18641]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:19 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 03:16:19 volumio-living volumio[18641]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:19 volumio-living volumio[18641]: info: CoreStateMachine::setRandom null Oct 05 03:16:19 volumio-living volumio[18641]: info: CoreStateMachine::pushState Oct 05 03:16:19 volumio-living volumio[18641]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:19 volumio-living volumio[18641]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:19 volumio-living volumio[18641]: info: Setting Device type: Raspberry PI Oct 05 03:16:19 volumio-living volumio[18641]: info: Completed loading Core Plugins Oct 05 03:16:19 volumio-living volumio[18641]: info: Preparing to generate the ALSA configuration file Oct 05 03:16:19 volumio-living volumio[18641]: info: Asound.conf file unchanged, so no further update is needed Oct 05 03:16:19 volumio-living volumio[18641]: info: Output device has changed, restarting MPD Oct 05 03:16:19 volumio-living volumio[18641]: info: ___________ START PLUGINS ___________ Oct 05 03:16:19 volumio-living volumio[18641]: info: ControllerMpd::onStart: Initializing MPD Oct 05 03:16:19 volumio-living volumio[18641]: info: Creating MPD Configuration file Oct 05 03:16:19 volumio-living volumio[18641]: Starting albumart workers Oct 05 03:16:19 volumio-living sudo[18719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 03:16:19 volumio-living sudo[18719]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:19 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:19 volumio-living sudo[18721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 03:16:19 volumio-living volumio[18641]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:19 volumio-living sudo[18721]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:19 volumio-living volumio[18641]: info: [1728112579370] CoreMusicLibrary::Adding element Media Servers Oct 05 03:16:19 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:19 volumio-living sudo[18719]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:19 volumio-living volumio[18641]: Starting albumart workers Oct 05 03:16:19 volumio-living volumio[18641]: Starting albumart workers Oct 05 03:16:19 volumio-living systemd[1]: Stopping Music Player Daemon... Oct 05 03:16:19 volumio-living sudo[18724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 03:16:19 volumio-living sudo[18727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 03:16:19 volumio-living sudo[18724]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:19 volumio-living sudo[18727]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:19 volumio-living systemd[1]: mpd.service: Succeeded. Oct 05 03:16:19 volumio-living systemd[1]: Stopped Music Player Daemon. Oct 05 03:16:19 volumio-living volumio[18641]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:19 volumio-living volumio[18641]: info: [1728112579459] CoreMusicLibrary::Adding element Last_100 Oct 05 03:16:19 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:19 volumio-living sudo[18724]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:19 volumio-living volumio[18641]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:19 volumio-living volumio[18641]: info: [1728112579473] CoreMusicLibrary::Adding element Webradio Oct 05 03:16:19 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:19 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:19 volumio-living volumio[18641]: info: Initializing BBC Radios Oct 05 03:16:19 volumio-living systemd[1]: Starting Music Player Daemon... Oct 05 03:16:19 volumio-living systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 05 03:16:19 volumio-living systemd[1]: mpd.service: Succeeded. Oct 05 03:16:19 volumio-living systemd[1]: Stopped Music Player Daemon. Oct 05 03:16:19 volumio-living systemd[1]: Starting Music Player Daemon... Oct 05 03:16:19 volumio-living sudo[18733]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 05 03:16:19 volumio-living sudo[18733]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:19 volumio-living sudo[18733]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:19 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:19 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 03:16:19 volumio-living volumio[18641]: info: [squeezelite_mc] Starting proxy server... Oct 05 03:16:19 volumio-living volumio[18641]: info: Volumio Calling Home Oct 05 03:16:19 volumio-living volumio[18641]: info: [squeezelite_mc] Proxy server started on port 45933 Oct 05 03:16:20 volumio-living volumio[18641]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 03:16:20 volumio-living volumio[18641]: info: CoreStateMachine::pushState Oct 05 03:16:20 volumio-living volumio[18641]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:20 volumio-living volumio[18641]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:20 volumio-living volumio[18641]: info: [squeezelite_mc] Server discovery started Oct 05 03:16:20 volumio-living volumio[18641]: info: [squeezelite_mc] Player finder started Oct 05 03:16:20 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:20 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:20 volumio-living volumio[18641]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:20 volumio-living volumio[18641]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 05 03:16:20 volumio-living volumio[18641]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 03:16:20 volumio-living volumio[18641]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 05 03:16:20 volumio-living volumio[18641]: at doSend (dgram.js:692:16) Oct 05 03:16:20 volumio-living volumio[18641]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 05 03:16:20 volumio-living volumio[18641]: at afterDns (dgram.js:638:5) Oct 05 03:16:20 volumio-living volumio[18641]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 05 03:16:20 volumio-living volumio[18641]: errno: -101, Oct 05 03:16:20 volumio-living volumio[18641]: code: 'ENETUNREACH', Oct 05 03:16:20 volumio-living volumio[18641]: syscall: 'send', Oct 05 03:16:20 volumio-living volumio[18641]: address: '255.255.255.255', Oct 05 03:16:20 volumio-living volumio[18641]: port: 3483 Oct 05 03:16:20 volumio-living volumio[18641]: } Oct 05 03:16:20 volumio-living volumio[18641]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 03:16:20 volumio-living sudo[18782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 03:15 Oct 05 03:16:20 volumio-living sudo[18782]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:20 volumio-living sudo[18782]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:21 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:21] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 05 03:16:21 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:21] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 05 03:16:21 volumio-living systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 05 03:16:21 volumio-living sudo[18727]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:21 volumio-living sudo[18675]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:21 volumio-living sudo[18721]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:21 volumio-living systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 05 03:16:21 volumio-living systemd[1]: Started dynamicswap service. Oct 05 03:16:21 volumio-living systemd[1]: dynamicswap.service: Succeeded. Oct 05 03:16:21 volumio-living systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 05 03:16:21 volumio-living systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Oct 05 03:16:21 volumio-living systemd[1]: Stopped Volumio Backend Module. Oct 05 03:16:21 volumio-living systemd[1]: Started Volumio Backend Module. Oct 05 03:16:21 volumio-living systemd[1]: Started dynamicswap service. Oct 05 03:16:21 volumio-living systemd[1]: dynamicswap.service: Succeeded. Oct 05 03:16:21 volumio-living mpd[18740]: Oct 05 03:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 05 03:16:21 volumio-living systemd[1]: Started Music Player Daemon. Oct 05 03:16:21 volumio-living wpa_supplicant[852]: wlan0: Failed to initiate sched scan Oct 05 03:16:23 volumio-living volumio[18796]: info: ------------------------------------------- Oct 05 03:16:23 volumio-living volumio[18796]: info: ----- Volumio3 ---- Oct 05 03:16:23 volumio-living volumio[18796]: info: ------------------------------------------- Oct 05 03:16:23 volumio-living volumio[18796]: info: ----- System startup ---- Oct 05 03:16:23 volumio-living volumio[18796]: info: ------------------------------------------- Oct 05 03:16:23 volumio-living volumio[18796]: info: MYVOLUMIO Environment detected Oct 05 03:16:23 volumio-living volumio[18796]: info: Plugin folders cleanup Oct 05 03:16:23 volumio-living volumio[18796]: info: Scanning into folder /volumio/app/plugins/ Oct 05 03:16:23 volumio-living volumio[18796]: info: Scanning category audio_interface Oct 05 03:16:23 volumio-living volumio[18796]: info: Scanning category miscellanea Oct 05 03:16:23 volumio-living volumio[18796]: info: Scanning category music_service Oct 05 03:16:23 volumio-living volumio[18796]: info: Scanning category plugins.json Oct 05 03:16:23 volumio-living volumio[18796]: info: Scanning category system_controller Oct 05 03:16:23 volumio-living volumio[18796]: info: Scanning category user_interface Oct 05 03:16:23 volumio-living volumio[18796]: info: Scanning into folder /data/plugins/ Oct 05 03:16:23 volumio-living volumio[18796]: info: Scanning category music_service Oct 05 03:16:23 volumio-living volumio[18796]: info: Scanning category user_interface Oct 05 03:16:23 volumio-living volumio[18796]: info: Plugin folders cleanup completed Oct 05 03:16:23 volumio-living volumio[18796]: info: ------------------------------------------- Oct 05 03:16:23 volumio-living volumio[18796]: info: ----- Core plugins startup ---- Oct 05 03:16:23 volumio-living volumio[18796]: info: ------------------------------------------- Oct 05 03:16:23 volumio-living volumio[18796]: info: Loading plugins from folder /volumio/app/plugins/ Oct 05 03:16:23 volumio-living volumio[18796]: info: Adding plugin upnp to MyMusic Plugins Oct 05 03:16:23 volumio-living volumio[18796]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 05 03:16:23 volumio-living volumio[18796]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 05 03:16:23 volumio-living volumio[18796]: info: Loading plugins from folder /data/plugins/ Oct 05 03:16:23 volumio-living volumio[18796]: info: Loading plugin "system"... Oct 05 03:16:23 volumio-living volumio[18796]: info: Loading plugin "appearance"... Oct 05 03:16:24 volumio-living nmbd[698]: [2024/10/05 03:16:24.230744, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Oct 05 03:16:24 volumio-living nmbd[698]: reload_interfaces: No subnets to listen to. Waiting.. Oct 05 03:16:24 volumio-living volumio[18796]: info: Loading plugin "network"... Oct 05 03:16:24 volumio-living volumio[18796]: info: Refreshing Cached IP Addresses Oct 05 03:16:24 volumio-living sudo[18826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 03:16:24 volumio-living sudo[18826]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:24 volumio-living sudo[18826]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:24 volumio-living sudo[18828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 03:16:24 volumio-living volumio[18796]: info: Loading plugin "services"... Oct 05 03:16:24 volumio-living sudo[18828]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:24 volumio-living volumio[18796]: info: Loading plugin "alsa_controller"... Oct 05 03:16:24 volumio-living sudo[18828]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:24 volumio-living sudo[18831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 05 03:16:24 volumio-living sudo[18831]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:24 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:24 volumio-living volumio[18796]: info: Loading plugin "wizard"... Oct 05 03:16:24 volumio-living volumio[18796]: info: Loading plugin "networkfs"... Oct 05 03:16:24 volumio-living volumio[18796]: info: Starting Udev Watcher for removable devices Oct 05 03:16:24 volumio-living volumio[18796]: info: Ignoring mount for partition: boot Oct 05 03:16:24 volumio-living volumio[18796]: info: Ignoring mount for partition: volumio Oct 05 03:16:24 volumio-living volumio[18796]: info: Ignoring mount for partition: volumio_data Oct 05 03:16:24 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:24 volumio-living volumio[18796]: info: Loading plugin "volumio_command_line_client"... Oct 05 03:16:24 volumio-living volumio[18796]: info: Loading plugin "upnp"... Oct 05 03:16:24 volumio-living volumio[18796]: info: [1728112584903] Starting Upmpd Daemon Oct 05 03:16:24 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:24 volumio-living volumio[18796]: info: Loading plugin "my_music"... Oct 05 03:16:24 volumio-living volumio[18796]: info: Loading plugin "mpd"... Oct 05 03:16:25 volumio-living volumio[18796]: info: Loading plugin "upnp_browser"... Oct 05 03:16:25 volumio-living volumio[18796]: info: Loading plugin "alarm-clock"... Oct 05 03:16:25 volumio-living volumio[18796]: info: Plugin airplay_emulation is not enabled Oct 05 03:16:25 volumio-living volumio[18796]: info: Loading plugin "last_100"... Oct 05 03:16:25 volumio-living volumio[18796]: info: Loading plugin "webradio"... Oct 05 03:16:25 volumio-living volumio[18796]: info: Loading plugin "i2s_dacs"... Oct 05 03:16:25 volumio-living volumio[18796]: info: I2S DAC not set, start Auto-detection Oct 05 03:16:25 volumio-living volumio[18796]: info: Loading plugin "volumiodiscovery"... Oct 05 03:16:25 volumio-living volumio[18796]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:25 volumio-living volumio[18796]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:25 volumio-living volumio[18796]: *** WARNING *** For more information see Oct 05 03:16:25 volumio-living volumio[18796]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:25 volumio-living node[18796]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:25 volumio-living volumio[18796]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:25 volumio-living volumio[18796]: *** WARNING *** For more information see Oct 05 03:16:25 volumio-living node[18796]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:25 volumio-living node[18796]: *** WARNING *** For more information see Oct 05 03:16:25 volumio-living node[18796]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:25 volumio-living node[18796]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:25 volumio-living node[18796]: *** WARNING *** For more information see Oct 05 03:16:25 volumio-living volumio[18796]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 05 03:16:25 volumio-living volumio[18796]: info: Discovery: Started advertising with name: Volumio-Living Oct 05 03:16:25 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:25 volumio-living volumio[18796]: info: Loading plugin "squeezelite_mc"... Oct 05 03:16:26 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:26] [connect] Successful connection Oct 05 03:16:26 volumio-living volumio[18796]: info: Loading plugin "outputs"... Oct 05 03:16:26 volumio-living volumio[18796]: info: Loading plugin "albumart"... Oct 05 03:16:26 volumio-living volumio[18796]: info: Plugin example_plugin is not enabled Oct 05 03:16:26 volumio-living volumio[18796]: info: Loading plugin "inputs"... Oct 05 03:16:26 volumio-living volumio[18796]: info: Loading plugin "updater_comm"... Oct 05 03:16:26 volumio-living volumio[18796]: info: Plugin mpdemulation is not enabled Oct 05 03:16:26 volumio-living volumio[18796]: info: Loading plugin "rest_api"... Oct 05 03:16:26 volumio-living volumio[18796]: info: Loading plugin "websocket"... Oct 05 03:16:26 volumio-living volumio[18796]: info: Starting Socket.io Server version 2.3.0 Oct 05 03:16:26 volumio-living volumio[18796]: info: Loading plugin "Systeminfo"... Oct 05 03:16:26 volumio-living volumio[18796]: Forking 3 albumart workers Oct 05 03:16:27 volumio-living volumio[18796]: info: Loading i18n strings for locale zh_TW Oct 05 03:16:27 volumio-living volumio[18796]: Updating browse sources language Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::initPlayerControls Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:27 volumio-living volumio[18796]: Express server listening on port 3000 Oct 05 03:16:27 volumio-living volumio[18796]: [Metrics] WebUI: 5s 46.78ms Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreStateMachine::resetVolumioState Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreStateMachine::getcurrentVolume Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreStateMachine::pushState Oct 05 03:16:27 volumio-living volumio[18796]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 03:16:27 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728112586 101 Oct 05 03:16:27 volumio-living volumio[18796]: 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 Oct 05 03:16:27 volumio-living volumio[18796]: info: Reloading queue from file Oct 05 03:16:27 volumio-living volumio[18796]: info: Setting Device type: Raspberry PI Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreStateMachine::setRepeat null single undefined Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreStateMachine::pushState Oct 05 03:16:27 volumio-living volumio[18796]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreStateMachine::setRandom null Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreStateMachine::pushState Oct 05 03:16:27 volumio-living volumio[18796]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:27 volumio-living volumio[18796]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:27 volumio-living volumio[18796]: info: Completed loading Core Plugins Oct 05 03:16:27 volumio-living volumio[18796]: info: Preparing to generate the ALSA configuration file Oct 05 03:16:27 volumio-living volumio[18796]: info: Asound.conf file unchanged, so no further update is needed Oct 05 03:16:27 volumio-living volumio[18796]: info: Output device has changed, restarting MPD Oct 05 03:16:27 volumio-living volumio[18796]: Starting albumart workers Oct 05 03:16:28 volumio-living volumio[18796]: Starting albumart workers Oct 05 03:16:28 volumio-living volumio[18796]: info: ___________ START PLUGINS ___________ Oct 05 03:16:28 volumio-living sudo[18874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 03:16:28 volumio-living sudo[18874]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:28 volumio-living volumio[18796]: info: ControllerMpd::onStart: Initializing MPD Oct 05 03:16:28 volumio-living volumio[18796]: info: Creating MPD Configuration file Oct 05 03:16:28 volumio-living sudo[18874]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:28 volumio-living volumio[18796]: Starting albumart workers Oct 05 03:16:28 volumio-living sudo[18876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 03:16:28 volumio-living sudo[18876]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:28 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:28 volumio-living volumio[18796]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:28 volumio-living volumio[18796]: info: [1728112588086] CoreMusicLibrary::Adding element Media Servers Oct 05 03:16:28 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:28 volumio-living systemd[1]: Stopping Music Player Daemon... Oct 05 03:16:28 volumio-living sudo[18879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 03:16:28 volumio-living sudo[18879]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:28 volumio-living systemd[1]: mpd.service: Succeeded. Oct 05 03:16:28 volumio-living systemd[1]: Stopped Music Player Daemon. Oct 05 03:16:28 volumio-living sudo[18882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 03:16:28 volumio-living sudo[18879]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:28 volumio-living sudo[18882]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:28 volumio-living volumio[18796]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:28 volumio-living volumio[18796]: info: [1728112588190] CoreMusicLibrary::Adding element Last_100 Oct 05 03:16:28 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:28 volumio-living volumio[18796]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:28 volumio-living volumio[18796]: info: [1728112588193] CoreMusicLibrary::Adding element Webradio Oct 05 03:16:28 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:28 volumio-living systemd[1]: Starting Music Player Daemon... Oct 05 03:16:28 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:28 volumio-living volumio[18796]: info: Initializing BBC Radios Oct 05 03:16:28 volumio-living systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 05 03:16:28 volumio-living systemd[1]: mpd.service: Succeeded. Oct 05 03:16:28 volumio-living systemd[1]: Stopped Music Player Daemon. Oct 05 03:16:28 volumio-living systemd[1]: Starting Music Player Daemon... Oct 05 03:16:28 volumio-living sudo[18886]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 05 03:16:28 volumio-living sudo[18886]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:28 volumio-living sudo[18886]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:28 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:28 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 03:16:28 volumio-living volumio[18796]: info: [squeezelite_mc] Starting proxy server... Oct 05 03:16:28 volumio-living volumio[18796]: info: Volumio Calling Home Oct 05 03:16:28 volumio-living volumio[18796]: info: [squeezelite_mc] Proxy server started on port 41445 Oct 05 03:16:28 volumio-living volumio[18796]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 03:16:28 volumio-living volumio[18796]: info: CoreStateMachine::pushState Oct 05 03:16:28 volumio-living volumio[18796]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:28 volumio-living volumio[18796]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:28 volumio-living volumio[18796]: info: [squeezelite_mc] Server discovery started Oct 05 03:16:28 volumio-living volumio[18796]: info: [squeezelite_mc] Player finder started Oct 05 03:16:28 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:28 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:28 volumio-living volumio[18796]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:28 volumio-living volumio[18796]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 05 03:16:29 volumio-living volumio[18796]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 03:16:29 volumio-living volumio[18796]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 05 03:16:29 volumio-living volumio[18796]: at doSend (dgram.js:692:16) Oct 05 03:16:29 volumio-living volumio[18796]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 05 03:16:29 volumio-living volumio[18796]: at afterDns (dgram.js:638:5) Oct 05 03:16:29 volumio-living volumio[18796]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 05 03:16:29 volumio-living volumio[18796]: errno: -101, Oct 05 03:16:29 volumio-living volumio[18796]: code: 'ENETUNREACH', Oct 05 03:16:29 volumio-living volumio[18796]: syscall: 'send', Oct 05 03:16:29 volumio-living volumio[18796]: address: '255.255.255.255', Oct 05 03:16:29 volumio-living volumio[18796]: port: 3483 Oct 05 03:16:29 volumio-living volumio[18796]: } Oct 05 03:16:29 volumio-living volumio[18796]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 03:16:29 volumio-living sudo[18937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 03:15 Oct 05 03:16:29 volumio-living sudo[18937]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:29 volumio-living sudo[18937]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:29 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:29] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 05 03:16:29 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:29] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 05 03:16:29 volumio-living systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 05 03:16:29 volumio-living sudo[18831]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:29 volumio-living sudo[18882]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:29 volumio-living sudo[18876]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:29 volumio-living systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 05 03:16:29 volumio-living systemd[1]: Started dynamicswap service. Oct 05 03:16:29 volumio-living systemd[1]: dynamicswap.service: Succeeded. Oct 05 03:16:30 volumio-living systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 05 03:16:30 volumio-living systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Oct 05 03:16:30 volumio-living systemd[1]: Started dynamicswap service. Oct 05 03:16:30 volumio-living systemd[1]: Stopped Volumio Backend Module. Oct 05 03:16:30 volumio-living systemd[1]: Started Volumio Backend Module. Oct 05 03:16:30 volumio-living systemd[1]: dynamicswap.service: Succeeded. Oct 05 03:16:30 volumio-living mpd[18898]: Oct 05 03:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 05 03:16:30 volumio-living systemd[1]: Started Music Player Daemon. Oct 05 03:16:30 volumio-living wpa_supplicant[852]: wlan0: Failed to initiate sched scan Oct 05 03:16:31 volumio-living volumio[18953]: info: ------------------------------------------- Oct 05 03:16:31 volumio-living volumio[18953]: info: ----- Volumio3 ---- Oct 05 03:16:31 volumio-living volumio[18953]: info: ------------------------------------------- Oct 05 03:16:31 volumio-living volumio[18953]: info: ----- System startup ---- Oct 05 03:16:31 volumio-living volumio[18953]: info: ------------------------------------------- Oct 05 03:16:32 volumio-living volumio[18953]: info: MYVOLUMIO Environment detected Oct 05 03:16:32 volumio-living volumio[18953]: info: Plugin folders cleanup Oct 05 03:16:32 volumio-living volumio[18953]: info: Scanning into folder /volumio/app/plugins/ Oct 05 03:16:32 volumio-living volumio[18953]: info: Scanning category audio_interface Oct 05 03:16:32 volumio-living volumio[18953]: info: Scanning category miscellanea Oct 05 03:16:32 volumio-living volumio[18953]: info: Scanning category music_service Oct 05 03:16:32 volumio-living volumio[18953]: info: Scanning category plugins.json Oct 05 03:16:32 volumio-living volumio[18953]: info: Scanning category system_controller Oct 05 03:16:32 volumio-living volumio[18953]: info: Scanning category user_interface Oct 05 03:16:32 volumio-living volumio[18953]: info: Scanning into folder /data/plugins/ Oct 05 03:16:32 volumio-living volumio[18953]: info: Scanning category music_service Oct 05 03:16:32 volumio-living volumio[18953]: info: Scanning category user_interface Oct 05 03:16:32 volumio-living volumio[18953]: info: Plugin folders cleanup completed Oct 05 03:16:32 volumio-living volumio[18953]: info: ------------------------------------------- Oct 05 03:16:32 volumio-living volumio[18953]: info: ----- Core plugins startup ---- Oct 05 03:16:32 volumio-living volumio[18953]: info: ------------------------------------------- Oct 05 03:16:32 volumio-living volumio[18953]: info: Loading plugins from folder /volumio/app/plugins/ Oct 05 03:16:32 volumio-living volumio[18953]: info: Adding plugin upnp to MyMusic Plugins Oct 05 03:16:32 volumio-living volumio[18953]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 05 03:16:32 volumio-living volumio[18953]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 05 03:16:32 volumio-living volumio[18953]: info: Loading plugins from folder /data/plugins/ Oct 05 03:16:32 volumio-living volumio[18953]: info: Loading plugin "system"... Oct 05 03:16:32 volumio-living volumio[18953]: info: Loading plugin "appearance"... Oct 05 03:16:33 volumio-living volumio[18953]: info: Loading plugin "network"... Oct 05 03:16:33 volumio-living volumio[18953]: info: Refreshing Cached IP Addresses Oct 05 03:16:33 volumio-living sudo[18981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 03:16:33 volumio-living sudo[18981]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:33 volumio-living sudo[18981]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:33 volumio-living sudo[18983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 03:16:33 volumio-living volumio[18953]: info: Loading plugin "services"... Oct 05 03:16:33 volumio-living sudo[18983]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:33 volumio-living volumio[18953]: info: Loading plugin "alsa_controller"... Oct 05 03:16:33 volumio-living sudo[18983]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:33 volumio-living sudo[18986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 05 03:16:33 volumio-living sudo[18986]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:33 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:33 volumio-living volumio[18953]: info: Loading plugin "wizard"... Oct 05 03:16:33 volumio-living volumio[18953]: info: Loading plugin "networkfs"... Oct 05 03:16:33 volumio-living volumio[18953]: info: Starting Udev Watcher for removable devices Oct 05 03:16:33 volumio-living volumio[18953]: info: Ignoring mount for partition: boot Oct 05 03:16:33 volumio-living volumio[18953]: info: Ignoring mount for partition: volumio Oct 05 03:16:33 volumio-living volumio[18953]: info: Ignoring mount for partition: volumio_data Oct 05 03:16:33 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:33 volumio-living volumio[18953]: info: Loading plugin "volumio_command_line_client"... Oct 05 03:16:33 volumio-living volumio[18953]: info: Loading plugin "upnp"... Oct 05 03:16:33 volumio-living volumio[18953]: info: [1728112593639] Starting Upmpd Daemon Oct 05 03:16:33 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:33 volumio-living volumio[18953]: info: Loading plugin "my_music"... Oct 05 03:16:33 volumio-living volumio[18953]: info: Loading plugin "mpd"... Oct 05 03:16:34 volumio-living volumio[18953]: info: Loading plugin "upnp_browser"... Oct 05 03:16:34 volumio-living volumio[18953]: info: Loading plugin "alarm-clock"... Oct 05 03:16:34 volumio-living volumio[18953]: info: Plugin airplay_emulation is not enabled Oct 05 03:16:34 volumio-living volumio[18953]: info: Loading plugin "last_100"... Oct 05 03:16:34 volumio-living volumio[18953]: info: Loading plugin "webradio"... Oct 05 03:16:34 volumio-living volumio[18953]: info: Loading plugin "i2s_dacs"... Oct 05 03:16:34 volumio-living volumio[18953]: info: I2S DAC not set, start Auto-detection Oct 05 03:16:34 volumio-living volumio[18953]: info: Loading plugin "volumiodiscovery"... Oct 05 03:16:34 volumio-living volumio[18953]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:34 volumio-living volumio[18953]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:34 volumio-living volumio[18953]: *** WARNING *** For more information see Oct 05 03:16:34 volumio-living node[18953]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:34 volumio-living volumio[18953]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:34 volumio-living volumio[18953]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:34 volumio-living volumio[18953]: *** WARNING *** For more information see Oct 05 03:16:34 volumio-living node[18953]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:34 volumio-living node[18953]: *** WARNING *** For more information see Oct 05 03:16:34 volumio-living node[18953]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:34 volumio-living node[18953]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:34 volumio-living node[18953]: *** WARNING *** For more information see Oct 05 03:16:34 volumio-living volumio[18953]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 05 03:16:34 volumio-living volumio[18953]: info: Discovery: Started advertising with name: Volumio-Living Oct 05 03:16:34 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:34 volumio-living volumio[18953]: info: Loading plugin "squeezelite_mc"... Oct 05 03:16:34 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:34] [connect] Successful connection Oct 05 03:16:35 volumio-living volumio[18953]: info: Loading plugin "outputs"... Oct 05 03:16:35 volumio-living volumio[18953]: info: Loading plugin "albumart"... Oct 05 03:16:35 volumio-living volumio[18953]: info: Plugin example_plugin is not enabled Oct 05 03:16:35 volumio-living volumio[18953]: info: Loading plugin "inputs"... Oct 05 03:16:35 volumio-living volumio[18953]: info: Loading plugin "updater_comm"... Oct 05 03:16:35 volumio-living volumio[18953]: info: Plugin mpdemulation is not enabled Oct 05 03:16:35 volumio-living volumio[18953]: info: Loading plugin "rest_api"... Oct 05 03:16:35 volumio-living volumio[18953]: info: Loading plugin "websocket"... Oct 05 03:16:35 volumio-living volumio[18953]: info: Starting Socket.io Server version 2.3.0 Oct 05 03:16:35 volumio-living volumio[18953]: info: Loading plugin "Systeminfo"... Oct 05 03:16:35 volumio-living volumio[18953]: Forking 3 albumart workers Oct 05 03:16:35 volumio-living volumio[18953]: info: Loading i18n strings for locale zh_TW Oct 05 03:16:35 volumio-living volumio[18953]: Updating browse sources language Oct 05 03:16:35 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::initPlayerControls Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:36 volumio-living volumio[18953]: Express server listening on port 3000 Oct 05 03:16:36 volumio-living volumio[18953]: [Metrics] WebUI: 5s 35.08ms Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreStateMachine::resetVolumioState Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreStateMachine::getcurrentVolume Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreStateMachine::pushState Oct 05 03:16:36 volumio-living volumio[18953]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 03:16:36 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728112594 101 Oct 05 03:16:36 volumio-living volumio[18953]: 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 Oct 05 03:16:36 volumio-living volumio[18953]: info: Reloading queue from file Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreStateMachine::setRepeat null single undefined Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreStateMachine::pushState Oct 05 03:16:36 volumio-living volumio[18953]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreStateMachine::setRandom null Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreStateMachine::pushState Oct 05 03:16:36 volumio-living volumio[18953]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:36 volumio-living volumio[18953]: info: Setting Device type: Raspberry PI Oct 05 03:16:36 volumio-living volumio[18953]: info: Completed loading Core Plugins Oct 05 03:16:36 volumio-living volumio[18953]: info: Preparing to generate the ALSA configuration file Oct 05 03:16:36 volumio-living volumio[18953]: info: Asound.conf file unchanged, so no further update is needed Oct 05 03:16:36 volumio-living volumio[18953]: info: Output device has changed, restarting MPD Oct 05 03:16:36 volumio-living sudo[19068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 03:16:36 volumio-living sudo[19068]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:36 volumio-living volumio[18953]: info: ___________ START PLUGINS ___________ Oct 05 03:16:36 volumio-living sudo[19068]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:36 volumio-living sudo[19070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 03:16:36 volumio-living volumio[18953]: info: ControllerMpd::onStart: Initializing MPD Oct 05 03:16:36 volumio-living volumio[18953]: info: Creating MPD Configuration file Oct 05 03:16:36 volumio-living sudo[19070]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:36 volumio-living systemd[1]: Stopping Music Player Daemon... Oct 05 03:16:36 volumio-living systemd[1]: mpd.service: Succeeded. Oct 05 03:16:36 volumio-living systemd[1]: Stopped Music Player Daemon. Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:36 volumio-living volumio[18953]: info: [1728112596791] CoreMusicLibrary::Adding element Media Servers Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:36 volumio-living sudo[19076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 03:16:36 volumio-living sudo[19076]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:36 volumio-living volumio[18953]: info: [1728112596842] CoreMusicLibrary::Adding element Last_100 Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:36 volumio-living systemd[1]: Starting Music Player Daemon... Oct 05 03:16:36 volumio-living sudo[19074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 03:16:36 volumio-living sudo[19074]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:36 volumio-living sudo[19074]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:36 volumio-living systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 05 03:16:36 volumio-living systemd[1]: mpd.service: Succeeded. Oct 05 03:16:36 volumio-living systemd[1]: Stopped Music Player Daemon. Oct 05 03:16:36 volumio-living systemd[1]: Starting Music Player Daemon... Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:36 volumio-living volumio[18953]: info: [1728112596931] CoreMusicLibrary::Adding element Webradio Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:36 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:36 volumio-living volumio[18953]: info: Initializing BBC Radios Oct 05 03:16:36 volumio-living volumio[18953]: Starting albumart workers Oct 05 03:16:36 volumio-living volumio[18953]: Starting albumart workers Oct 05 03:16:36 volumio-living sudo[19082]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 05 03:16:36 volumio-living sudo[19082]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:37 volumio-living sudo[19082]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:37 volumio-living volumio[18953]: Starting albumart workers Oct 05 03:16:37 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:37 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 03:16:37 volumio-living volumio[18953]: info: [squeezelite_mc] Starting proxy server... Oct 05 03:16:37 volumio-living volumio[18953]: info: Volumio Calling Home Oct 05 03:16:37 volumio-living volumio[18953]: info: [squeezelite_mc] Proxy server started on port 36061 Oct 05 03:16:37 volumio-living volumio[18953]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 03:16:37 volumio-living volumio[18953]: info: CoreStateMachine::pushState Oct 05 03:16:37 volumio-living volumio[18953]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:37 volumio-living volumio[18953]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:37 volumio-living volumio[18953]: info: [squeezelite_mc] Server discovery started Oct 05 03:16:37 volumio-living volumio[18953]: info: [squeezelite_mc] Player finder started Oct 05 03:16:37 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:37 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:37 volumio-living volumio[18953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:37 volumio-living volumio[18953]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 05 03:16:37 volumio-living volumio[18953]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 03:16:37 volumio-living volumio[18953]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 05 03:16:37 volumio-living volumio[18953]: at doSend (dgram.js:692:16) Oct 05 03:16:37 volumio-living volumio[18953]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 05 03:16:37 volumio-living volumio[18953]: at afterDns (dgram.js:638:5) Oct 05 03:16:37 volumio-living volumio[18953]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 05 03:16:37 volumio-living volumio[18953]: errno: -101, Oct 05 03:16:37 volumio-living volumio[18953]: code: 'ENETUNREACH', Oct 05 03:16:37 volumio-living volumio[18953]: syscall: 'send', Oct 05 03:16:37 volumio-living volumio[18953]: address: '255.255.255.255', Oct 05 03:16:37 volumio-living volumio[18953]: port: 3483 Oct 05 03:16:37 volumio-living volumio[18953]: } Oct 05 03:16:37 volumio-living volumio[18953]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 03:16:38 volumio-living sudo[19133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 03:15 Oct 05 03:16:38 volumio-living sudo[19133]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:38 volumio-living sudo[19133]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:38 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:38] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 05 03:16:38 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:38] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 05 03:16:38 volumio-living systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 05 03:16:38 volumio-living sudo[19070]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:38 volumio-living sudo[19076]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:38 volumio-living sudo[18986]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:38 volumio-living systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 05 03:16:38 volumio-living systemd[1]: Started dynamicswap service. Oct 05 03:16:38 volumio-living systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 05 03:16:38 volumio-living systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Oct 05 03:16:38 volumio-living systemd[1]: dynamicswap.service: Succeeded. Oct 05 03:16:38 volumio-living systemd[1]: Stopped Volumio Backend Module. Oct 05 03:16:38 volumio-living systemd[1]: Started Volumio Backend Module. Oct 05 03:16:38 volumio-living mpd[19085]: Oct 05 03:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 05 03:16:38 volumio-living systemd[1]: Started Music Player Daemon. Oct 05 03:16:39 volumio-living wpa_supplicant[852]: wlan0: Failed to initiate sched scan Oct 05 03:16:40 volumio-living volumio[19146]: info: ------------------------------------------- Oct 05 03:16:40 volumio-living volumio[19146]: info: ----- Volumio3 ---- Oct 05 03:16:40 volumio-living volumio[19146]: info: ------------------------------------------- Oct 05 03:16:40 volumio-living volumio[19146]: info: ----- System startup ---- Oct 05 03:16:40 volumio-living volumio[19146]: info: ------------------------------------------- Oct 05 03:16:41 volumio-living volumio[19146]: info: MYVOLUMIO Environment detected Oct 05 03:16:41 volumio-living volumio[19146]: info: Plugin folders cleanup Oct 05 03:16:41 volumio-living volumio[19146]: info: Scanning into folder /volumio/app/plugins/ Oct 05 03:16:41 volumio-living volumio[19146]: info: Scanning category audio_interface Oct 05 03:16:41 volumio-living volumio[19146]: info: Scanning category miscellanea Oct 05 03:16:41 volumio-living volumio[19146]: info: Scanning category music_service Oct 05 03:16:41 volumio-living volumio[19146]: info: Scanning category plugins.json Oct 05 03:16:41 volumio-living volumio[19146]: info: Scanning category system_controller Oct 05 03:16:41 volumio-living volumio[19146]: info: Scanning category user_interface Oct 05 03:16:41 volumio-living volumio[19146]: info: Scanning into folder /data/plugins/ Oct 05 03:16:41 volumio-living volumio[19146]: info: Scanning category music_service Oct 05 03:16:41 volumio-living volumio[19146]: info: Scanning category user_interface Oct 05 03:16:41 volumio-living volumio[19146]: info: Plugin folders cleanup completed Oct 05 03:16:41 volumio-living volumio[19146]: info: ------------------------------------------- Oct 05 03:16:41 volumio-living volumio[19146]: info: ----- Core plugins startup ---- Oct 05 03:16:41 volumio-living volumio[19146]: info: ------------------------------------------- Oct 05 03:16:41 volumio-living volumio[19146]: info: Loading plugins from folder /volumio/app/plugins/ Oct 05 03:16:41 volumio-living volumio[19146]: info: Adding plugin upnp to MyMusic Plugins Oct 05 03:16:41 volumio-living volumio[19146]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 05 03:16:41 volumio-living volumio[19146]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 05 03:16:41 volumio-living volumio[19146]: info: Loading plugins from folder /data/plugins/ Oct 05 03:16:41 volumio-living volumio[19146]: info: Loading plugin "system"... Oct 05 03:16:41 volumio-living volumio[19146]: info: Loading plugin "appearance"... Oct 05 03:16:42 volumio-living volumio[19146]: info: Loading plugin "network"... Oct 05 03:16:42 volumio-living volumio[19146]: info: Refreshing Cached IP Addresses Oct 05 03:16:42 volumio-living sudo[19169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 03:16:42 volumio-living sudo[19169]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:42 volumio-living sudo[19169]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:42 volumio-living sudo[19171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 03:16:42 volumio-living volumio[19146]: info: Loading plugin "services"... Oct 05 03:16:42 volumio-living sudo[19171]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:42 volumio-living volumio[19146]: info: Loading plugin "alsa_controller"... Oct 05 03:16:42 volumio-living sudo[19171]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:42 volumio-living sudo[19174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 05 03:16:42 volumio-living sudo[19174]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:42 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:42 volumio-living volumio[19146]: info: Loading plugin "wizard"... Oct 05 03:16:42 volumio-living volumio[19146]: info: Loading plugin "networkfs"... Oct 05 03:16:42 volumio-living volumio[19146]: info: Starting Udev Watcher for removable devices Oct 05 03:16:42 volumio-living volumio[19146]: info: Ignoring mount for partition: boot Oct 05 03:16:42 volumio-living volumio[19146]: info: Ignoring mount for partition: volumio Oct 05 03:16:42 volumio-living volumio[19146]: info: Ignoring mount for partition: volumio_data Oct 05 03:16:42 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:42 volumio-living volumio[19146]: info: Loading plugin "volumio_command_line_client"... Oct 05 03:16:42 volumio-living volumio[19146]: info: Loading plugin "upnp"... Oct 05 03:16:42 volumio-living volumio[19146]: info: [1728112602425] Starting Upmpd Daemon Oct 05 03:16:42 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:42 volumio-living volumio[19146]: info: Loading plugin "my_music"... Oct 05 03:16:42 volumio-living volumio[19146]: info: Loading plugin "mpd"... Oct 05 03:16:42 volumio-living volumio[19146]: info: Loading plugin "upnp_browser"... Oct 05 03:16:43 volumio-living volumio[19146]: info: Loading plugin "alarm-clock"... Oct 05 03:16:43 volumio-living volumio[19146]: info: Plugin airplay_emulation is not enabled Oct 05 03:16:43 volumio-living volumio[19146]: info: Loading plugin "last_100"... Oct 05 03:16:43 volumio-living volumio[19146]: info: Loading plugin "webradio"... Oct 05 03:16:43 volumio-living volumio[19146]: info: Loading plugin "i2s_dacs"... Oct 05 03:16:43 volumio-living volumio[19146]: info: I2S DAC not set, start Auto-detection Oct 05 03:16:43 volumio-living volumio[19146]: info: Loading plugin "volumiodiscovery"... Oct 05 03:16:43 volumio-living volumio[19146]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:43 volumio-living volumio[19146]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:43 volumio-living volumio[19146]: *** WARNING *** For more information see Oct 05 03:16:43 volumio-living node[19146]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:43 volumio-living volumio[19146]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:43 volumio-living volumio[19146]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:43 volumio-living volumio[19146]: *** WARNING *** For more information see Oct 05 03:16:43 volumio-living node[19146]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:43 volumio-living node[19146]: *** WARNING *** For more information see Oct 05 03:16:43 volumio-living node[19146]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:43 volumio-living node[19146]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:43 volumio-living node[19146]: *** WARNING *** For more information see Oct 05 03:16:43 volumio-living volumio[19146]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 05 03:16:43 volumio-living volumio[19146]: info: Discovery: Started advertising with name: Volumio-Living Oct 05 03:16:43 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:43 volumio-living volumio[19146]: info: Loading plugin "squeezelite_mc"... Oct 05 03:16:43 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:43] [connect] Successful connection Oct 05 03:16:44 volumio-living volumio[19146]: info: Loading plugin "outputs"... Oct 05 03:16:44 volumio-living volumio[19146]: info: Loading plugin "albumart"... Oct 05 03:16:44 volumio-living volumio[19146]: info: Plugin example_plugin is not enabled Oct 05 03:16:44 volumio-living volumio[19146]: info: Loading plugin "inputs"... Oct 05 03:16:44 volumio-living volumio[19146]: info: Loading plugin "updater_comm"... Oct 05 03:16:44 volumio-living volumio[19146]: info: Plugin mpdemulation is not enabled Oct 05 03:16:44 volumio-living volumio[19146]: info: Loading plugin "rest_api"... Oct 05 03:16:44 volumio-living volumio[19146]: info: Loading plugin "websocket"... Oct 05 03:16:44 volumio-living volumio[19146]: info: Starting Socket.io Server version 2.3.0 Oct 05 03:16:44 volumio-living volumio[19146]: info: Loading plugin "Systeminfo"... Oct 05 03:16:44 volumio-living volumio[19146]: Forking 3 albumart workers Oct 05 03:16:44 volumio-living volumio[19146]: info: Loading i18n strings for locale zh_TW Oct 05 03:16:44 volumio-living volumio[19146]: Updating browse sources language Oct 05 03:16:44 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::initPlayerControls Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:45 volumio-living volumio[19146]: Express server listening on port 3000 Oct 05 03:16:45 volumio-living volumio[19146]: [Metrics] WebUI: 5s 31.08ms Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreStateMachine::resetVolumioState Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreStateMachine::getcurrentVolume Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreStateMachine::pushState Oct 05 03:16:45 volumio-living volumio[19146]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 03:16:45 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728112603 101 Oct 05 03:16:45 volumio-living volumio[19146]: 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 Oct 05 03:16:45 volumio-living volumio[19146]: info: Reloading queue from file Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreStateMachine::setRepeat null single undefined Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreStateMachine::pushState Oct 05 03:16:45 volumio-living volumio[19146]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreStateMachine::setRandom null Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreStateMachine::pushState Oct 05 03:16:45 volumio-living volumio[19146]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:45 volumio-living volumio[19146]: info: Setting Device type: Raspberry PI Oct 05 03:16:45 volumio-living volumio[19146]: info: Completed loading Core Plugins Oct 05 03:16:45 volumio-living volumio[19146]: info: Preparing to generate the ALSA configuration file Oct 05 03:16:45 volumio-living volumio[19146]: info: Asound.conf file unchanged, so no further update is needed Oct 05 03:16:45 volumio-living volumio[19146]: info: Output device has changed, restarting MPD Oct 05 03:16:45 volumio-living volumio[19146]: Starting albumart workers Oct 05 03:16:45 volumio-living sudo[19217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 03:16:45 volumio-living sudo[19217]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:45 volumio-living volumio[19146]: info: ___________ START PLUGINS ___________ Oct 05 03:16:45 volumio-living volumio[19146]: info: ControllerMpd::onStart: Initializing MPD Oct 05 03:16:45 volumio-living volumio[19146]: info: Creating MPD Configuration file Oct 05 03:16:45 volumio-living sudo[19217]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:45 volumio-living volumio[19146]: Starting albumart workers Oct 05 03:16:45 volumio-living sudo[19223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 03:16:45 volumio-living sudo[19223]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:45 volumio-living sudo[19223]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:45 volumio-living sudo[19220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 03:16:45 volumio-living sudo[19220]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:45 volumio-living volumio[19146]: Starting albumart workers Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:45 volumio-living volumio[19146]: info: [1728112605623] CoreMusicLibrary::Adding element Media Servers Oct 05 03:16:45 volumio-living systemd[1]: Stopping Music Player Daemon... Oct 05 03:16:45 volumio-living sudo[19226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 03:16:45 volumio-living sudo[19226]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:45 volumio-living systemd[1]: mpd.service: Succeeded. Oct 05 03:16:45 volumio-living systemd[1]: Stopped Music Player Daemon. Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:45 volumio-living systemd[1]: Starting Music Player Daemon... Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:45 volumio-living volumio[19146]: info: [1728112605752] CoreMusicLibrary::Adding element Last_100 Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:45 volumio-living volumio[19146]: info: [1728112605756] CoreMusicLibrary::Adding element Webradio Oct 05 03:16:45 volumio-living systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 05 03:16:45 volumio-living systemd[1]: mpd.service: Succeeded. Oct 05 03:16:45 volumio-living systemd[1]: Stopped Music Player Daemon. Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:45 volumio-living systemd[1]: Starting Music Player Daemon... Oct 05 03:16:45 volumio-living volumio[19146]: info: Initializing BBC Radios Oct 05 03:16:45 volumio-living sudo[19229]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 05 03:16:45 volumio-living sudo[19229]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:45 volumio-living sudo[19229]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:45 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 03:16:45 volumio-living volumio[19146]: info: [squeezelite_mc] Starting proxy server... Oct 05 03:16:46 volumio-living volumio[19146]: info: Volumio Calling Home Oct 05 03:16:46 volumio-living volumio[19146]: info: [squeezelite_mc] Proxy server started on port 41761 Oct 05 03:16:46 volumio-living volumio[19146]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 03:16:46 volumio-living volumio[19146]: info: CoreStateMachine::pushState Oct 05 03:16:46 volumio-living volumio[19146]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:46 volumio-living volumio[19146]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:46 volumio-living volumio[19146]: info: [squeezelite_mc] Server discovery started Oct 05 03:16:46 volumio-living volumio[19146]: info: [squeezelite_mc] Player finder started Oct 05 03:16:46 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:46 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:46 volumio-living volumio[19146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:46 volumio-living volumio[19146]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 05 03:16:46 volumio-living volumio[19146]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 03:16:46 volumio-living volumio[19146]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 05 03:16:46 volumio-living volumio[19146]: at doSend (dgram.js:692:16) Oct 05 03:16:46 volumio-living volumio[19146]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 05 03:16:46 volumio-living volumio[19146]: at afterDns (dgram.js:638:5) Oct 05 03:16:46 volumio-living volumio[19146]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 05 03:16:46 volumio-living volumio[19146]: errno: -101, Oct 05 03:16:46 volumio-living volumio[19146]: code: 'ENETUNREACH', Oct 05 03:16:46 volumio-living volumio[19146]: syscall: 'send', Oct 05 03:16:46 volumio-living volumio[19146]: address: '255.255.255.255', Oct 05 03:16:46 volumio-living volumio[19146]: port: 3483 Oct 05 03:16:46 volumio-living volumio[19146]: } Oct 05 03:16:46 volumio-living volumio[19146]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 03:16:47 volumio-living sudo[19280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 03:15 Oct 05 03:16:47 volumio-living sudo[19280]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:47 volumio-living sudo[19280]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:47 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:47] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 05 03:16:47 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:47] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 05 03:16:47 volumio-living systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 05 03:16:47 volumio-living sudo[19226]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:47 volumio-living sudo[19174]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:47 volumio-living sudo[19220]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:47 volumio-living systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 05 03:16:47 volumio-living systemd[1]: Started dynamicswap service. Oct 05 03:16:47 volumio-living systemd[1]: dynamicswap.service: Succeeded. Oct 05 03:16:47 volumio-living systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 05 03:16:47 volumio-living systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9. Oct 05 03:16:47 volumio-living systemd[1]: Started dynamicswap service. Oct 05 03:16:47 volumio-living systemd[1]: Stopped Volumio Backend Module. Oct 05 03:16:47 volumio-living systemd[1]: Started Volumio Backend Module. Oct 05 03:16:47 volumio-living systemd[1]: dynamicswap.service: Succeeded. Oct 05 03:16:47 volumio-living mpd[19238]: Oct 05 03:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 05 03:16:47 volumio-living systemd[1]: Started Music Player Daemon. Oct 05 03:16:47 volumio-living wpa_supplicant[852]: wlan0: Failed to initiate sched scan Oct 05 03:16:49 volumio-living volumio[19294]: info: ------------------------------------------- Oct 05 03:16:49 volumio-living volumio[19294]: info: ----- Volumio3 ---- Oct 05 03:16:49 volumio-living volumio[19294]: info: ------------------------------------------- Oct 05 03:16:49 volumio-living volumio[19294]: info: ----- System startup ---- Oct 05 03:16:49 volumio-living volumio[19294]: info: ------------------------------------------- Oct 05 03:16:49 volumio-living volumio[19294]: info: MYVOLUMIO Environment detected Oct 05 03:16:49 volumio-living volumio[19294]: info: Plugin folders cleanup Oct 05 03:16:49 volumio-living volumio[19294]: info: Scanning into folder /volumio/app/plugins/ Oct 05 03:16:49 volumio-living volumio[19294]: info: Scanning category audio_interface Oct 05 03:16:49 volumio-living volumio[19294]: info: Scanning category miscellanea Oct 05 03:16:49 volumio-living volumio[19294]: info: Scanning category music_service Oct 05 03:16:49 volumio-living volumio[19294]: info: Scanning category plugins.json Oct 05 03:16:49 volumio-living volumio[19294]: info: Scanning category system_controller Oct 05 03:16:49 volumio-living volumio[19294]: info: Scanning category user_interface Oct 05 03:16:49 volumio-living volumio[19294]: info: Scanning into folder /data/plugins/ Oct 05 03:16:49 volumio-living volumio[19294]: info: Scanning category music_service Oct 05 03:16:49 volumio-living volumio[19294]: info: Scanning category user_interface Oct 05 03:16:49 volumio-living volumio[19294]: info: Plugin folders cleanup completed Oct 05 03:16:49 volumio-living volumio[19294]: info: ------------------------------------------- Oct 05 03:16:49 volumio-living volumio[19294]: info: ----- Core plugins startup ---- Oct 05 03:16:49 volumio-living volumio[19294]: info: ------------------------------------------- Oct 05 03:16:49 volumio-living volumio[19294]: info: Loading plugins from folder /volumio/app/plugins/ Oct 05 03:16:49 volumio-living volumio[19294]: info: Adding plugin upnp to MyMusic Plugins Oct 05 03:16:49 volumio-living volumio[19294]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 05 03:16:49 volumio-living volumio[19294]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 05 03:16:49 volumio-living volumio[19294]: info: Loading plugins from folder /data/plugins/ Oct 05 03:16:49 volumio-living volumio[19294]: info: Loading plugin "system"... Oct 05 03:16:50 volumio-living volumio[19294]: info: Loading plugin "appearance"... Oct 05 03:16:50 volumio-living volumio[19294]: info: Loading plugin "network"... Oct 05 03:16:50 volumio-living volumio[19294]: info: Refreshing Cached IP Addresses Oct 05 03:16:50 volumio-living sudo[19323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 03:16:50 volumio-living sudo[19323]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:50 volumio-living sudo[19323]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:50 volumio-living sudo[19325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 03:16:50 volumio-living sudo[19325]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:51 volumio-living volumio[19294]: info: Loading plugin "services"... Oct 05 03:16:51 volumio-living sudo[19325]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:51 volumio-living volumio[19294]: info: Loading plugin "alsa_controller"... Oct 05 03:16:51 volumio-living sudo[19328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 05 03:16:51 volumio-living sudo[19328]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:51 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:51 volumio-living volumio[19294]: info: Loading plugin "wizard"... Oct 05 03:16:51 volumio-living volumio[19294]: info: Loading plugin "networkfs"... Oct 05 03:16:51 volumio-living volumio[19294]: info: Starting Udev Watcher for removable devices Oct 05 03:16:51 volumio-living volumio[19294]: info: Ignoring mount for partition: boot Oct 05 03:16:51 volumio-living volumio[19294]: info: Ignoring mount for partition: volumio Oct 05 03:16:51 volumio-living volumio[19294]: info: Ignoring mount for partition: volumio_data Oct 05 03:16:51 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:51 volumio-living volumio[19294]: info: Loading plugin "volumio_command_line_client"... Oct 05 03:16:51 volumio-living volumio[19294]: info: Loading plugin "upnp"... Oct 05 03:16:51 volumio-living volumio[19294]: info: [1728112611139] Starting Upmpd Daemon Oct 05 03:16:51 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:51 volumio-living volumio[19294]: info: Loading plugin "my_music"... Oct 05 03:16:51 volumio-living volumio[19294]: info: Loading plugin "mpd"... Oct 05 03:16:51 volumio-living volumio[19294]: info: Loading plugin "upnp_browser"... Oct 05 03:16:51 volumio-living volumio[19294]: info: Loading plugin "alarm-clock"... Oct 05 03:16:51 volumio-living volumio[19294]: info: Plugin airplay_emulation is not enabled Oct 05 03:16:51 volumio-living volumio[19294]: info: Loading plugin "last_100"... Oct 05 03:16:51 volumio-living volumio[19294]: info: Loading plugin "webradio"... Oct 05 03:16:51 volumio-living volumio[19294]: info: Loading plugin "i2s_dacs"... Oct 05 03:16:51 volumio-living volumio[19294]: info: I2S DAC not set, start Auto-detection Oct 05 03:16:51 volumio-living volumio[19294]: info: Loading plugin "volumiodiscovery"... Oct 05 03:16:52 volumio-living volumio[19294]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:52 volumio-living volumio[19294]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:52 volumio-living volumio[19294]: *** WARNING *** For more information see Oct 05 03:16:52 volumio-living node[19294]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:52 volumio-living volumio[19294]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:52 volumio-living volumio[19294]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:52 volumio-living volumio[19294]: *** WARNING *** For more information see Oct 05 03:16:52 volumio-living node[19294]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:52 volumio-living node[19294]: *** WARNING *** For more information see Oct 05 03:16:52 volumio-living node[19294]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 05 03:16:52 volumio-living node[19294]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:16:52 volumio-living node[19294]: *** WARNING *** For more information see Oct 05 03:16:52 volumio-living volumio[19294]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 05 03:16:52 volumio-living volumio[19294]: info: Discovery: Started advertising with name: Volumio-Living Oct 05 03:16:52 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:52 volumio-living volumio[19294]: info: Loading plugin "squeezelite_mc"... Oct 05 03:16:52 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:52] [connect] Successful connection Oct 05 03:16:52 volumio-living volumio[19294]: info: Loading plugin "outputs"... Oct 05 03:16:52 volumio-living volumio[19294]: info: Loading plugin "albumart"... Oct 05 03:16:52 volumio-living volumio[19294]: info: Plugin example_plugin is not enabled Oct 05 03:16:52 volumio-living volumio[19294]: info: Loading plugin "inputs"... Oct 05 03:16:52 volumio-living volumio[19294]: info: Loading plugin "updater_comm"... Oct 05 03:16:53 volumio-living volumio[19294]: info: Plugin mpdemulation is not enabled Oct 05 03:16:53 volumio-living volumio[19294]: info: Loading plugin "rest_api"... Oct 05 03:16:53 volumio-living volumio[19294]: info: Loading plugin "websocket"... Oct 05 03:16:53 volumio-living volumio[19294]: info: Starting Socket.io Server version 2.3.0 Oct 05 03:16:53 volumio-living volumio[19294]: info: Loading plugin "Systeminfo"... Oct 05 03:16:53 volumio-living volumio[19294]: Forking 3 albumart workers Oct 05 03:16:53 volumio-living volumio[19294]: info: Loading i18n strings for locale zh_TW Oct 05 03:16:53 volumio-living volumio[19294]: Updating browse sources language Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreCommandRouter::initPlayerControls Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:53 volumio-living volumio[19294]: Express server listening on port 3000 Oct 05 03:16:53 volumio-living volumio[19294]: [Metrics] WebUI: 5s 21.66ms Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreStateMachine::resetVolumioState Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreStateMachine::getcurrentVolume Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreStateMachine::pushState Oct 05 03:16:53 volumio-living volumio[19294]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:53 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 03:16:53 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728112612 101 Oct 05 03:16:53 volumio-living volumio[19294]: 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 Oct 05 03:16:53 volumio-living volumio[19294]: info: Reloading queue from file Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreStateMachine::setRepeat null single undefined Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreStateMachine::pushState Oct 05 03:16:54 volumio-living volumio[19294]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreStateMachine::setRandom null Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreStateMachine::pushState Oct 05 03:16:54 volumio-living volumio[19294]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:54 volumio-living volumio[19294]: info: Setting Device type: Raspberry PI Oct 05 03:16:54 volumio-living volumio[19294]: info: Completed loading Core Plugins Oct 05 03:16:54 volumio-living volumio[19294]: info: Preparing to generate the ALSA configuration file Oct 05 03:16:54 volumio-living volumio[19294]: info: Asound.conf file unchanged, so no further update is needed Oct 05 03:16:54 volumio-living volumio[19294]: info: Output device has changed, restarting MPD Oct 05 03:16:54 volumio-living volumio[19294]: info: ___________ START PLUGINS ___________ Oct 05 03:16:54 volumio-living volumio[19294]: info: ControllerMpd::onStart: Initializing MPD Oct 05 03:16:54 volumio-living volumio[19294]: info: Creating MPD Configuration file Oct 05 03:16:54 volumio-living sudo[19372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 03:16:54 volumio-living sudo[19373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 03:16:54 volumio-living sudo[19373]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:54 volumio-living sudo[19372]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:54 volumio-living sudo[19376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 03:16:54 volumio-living sudo[19376]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:54 volumio-living sudo[19372]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:54 volumio-living volumio[19294]: Starting albumart workers Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:54 volumio-living volumio[19294]: info: [1728112614274] CoreMusicLibrary::Adding element Media Servers Oct 05 03:16:54 volumio-living systemd[1]: Stopping Music Player Daemon... Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:54 volumio-living sudo[19376]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:54 volumio-living volumio[19294]: info: [1728112614308] CoreMusicLibrary::Adding element Last_100 Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:54 volumio-living systemd[1]: mpd.service: Succeeded. Oct 05 03:16:54 volumio-living systemd[1]: Stopped Music Player Daemon. Oct 05 03:16:54 volumio-living sudo[19380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 03:16:54 volumio-living sudo[19380]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:16:54 volumio-living volumio[19294]: info: [1728112614383] CoreMusicLibrary::Adding element Webradio Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:16:54 volumio-living systemd[1]: Starting Music Player Daemon... Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:54 volumio-living volumio[19294]: info: Initializing BBC Radios Oct 05 03:16:54 volumio-living systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 05 03:16:54 volumio-living systemd[1]: mpd.service: Succeeded. Oct 05 03:16:54 volumio-living systemd[1]: Stopped Music Player Daemon. Oct 05 03:16:54 volumio-living systemd[1]: Starting Music Player Daemon... Oct 05 03:16:54 volumio-living volumio[19294]: Starting albumart workers Oct 05 03:16:54 volumio-living volumio[19294]: Starting albumart workers Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 03:16:54 volumio-living volumio[19294]: info: [squeezelite_mc] Starting proxy server... Oct 05 03:16:54 volumio-living volumio[19294]: info: Volumio Calling Home Oct 05 03:16:54 volumio-living sudo[19386]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 05 03:16:54 volumio-living sudo[19386]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:54 volumio-living sudo[19386]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:54 volumio-living volumio[19294]: info: [squeezelite_mc] Proxy server started on port 44219 Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreStateMachine::pushState Oct 05 03:16:54 volumio-living volumio[19294]: info: CorePlayQueue::getTrack 0 Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreCommandRouter::volumioPushState Oct 05 03:16:54 volumio-living volumio[19294]: info: [squeezelite_mc] Server discovery started Oct 05 03:16:54 volumio-living volumio[19294]: info: [squeezelite_mc] Player finder started Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:16:54 volumio-living volumio[19294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:16:54 volumio-living volumio[19294]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 05 03:16:55 volumio-living volumio[19294]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 03:16:55 volumio-living volumio[19294]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 05 03:16:55 volumio-living volumio[19294]: at doSend (dgram.js:692:16) Oct 05 03:16:55 volumio-living volumio[19294]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 05 03:16:55 volumio-living volumio[19294]: at afterDns (dgram.js:638:5) Oct 05 03:16:55 volumio-living volumio[19294]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 05 03:16:55 volumio-living volumio[19294]: errno: -101, Oct 05 03:16:55 volumio-living volumio[19294]: code: 'ENETUNREACH', Oct 05 03:16:55 volumio-living volumio[19294]: syscall: 'send', Oct 05 03:16:55 volumio-living volumio[19294]: address: '255.255.255.255', Oct 05 03:16:55 volumio-living volumio[19294]: port: 3483 Oct 05 03:16:55 volumio-living volumio[19294]: } Oct 05 03:16:55 volumio-living volumio[19294]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 03:16:55 volumio-living sudo[19434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 03:15 Oct 05 03:16:55 volumio-living sudo[19434]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:55 volumio-living sudo[19434]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:56 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:56] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 05 03:16:56 volumio-living volumio-remote-updater[586]: [2024-10-05 03:16:56] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 05 03:16:56 volumio-living systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 05 03:16:56 volumio-living sudo[19373]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:56 volumio-living sudo[19328]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:56 volumio-living sudo[19380]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:56 volumio-living systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 05 03:16:56 volumio-living systemd[1]: Started dynamicswap service. Oct 05 03:16:56 volumio-living systemd[1]: dynamicswap.service: Succeeded. Oct 05 03:16:56 volumio-living systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 05 03:16:56 volumio-living systemd[1]: volumio.service: Scheduled restart job, restart counter is at 10. Oct 05 03:16:56 volumio-living systemd[1]: Stopped Volumio Backend Module. Oct 05 03:16:56 volumio-living systemd[1]: Started Volumio Backend Module. Oct 05 03:16:56 volumio-living systemd[1]: Started dynamicswap service. Oct 05 03:16:56 volumio-living systemd[1]: dynamicswap.service: Succeeded. Oct 05 03:16:56 volumio-living mpd[19397]: Oct 05 03:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 05 03:16:56 volumio-living systemd[1]: Started Music Player Daemon. Oct 05 03:16:56 volumio-living wpa_supplicant[852]: wlan0: Failed to initiate sched scan Oct 05 03:16:57 volumio-living volumio[19447]: info: ------------------------------------------- Oct 05 03:16:57 volumio-living volumio[19447]: info: ----- Volumio3 ---- Oct 05 03:16:57 volumio-living volumio[19447]: info: ------------------------------------------- Oct 05 03:16:57 volumio-living volumio[19447]: info: ----- System startup ---- Oct 05 03:16:57 volumio-living volumio[19447]: info: ------------------------------------------- Oct 05 03:16:58 volumio-living volumio[19447]: info: MYVOLUMIO Environment detected Oct 05 03:16:58 volumio-living volumio[19447]: info: Plugin folders cleanup Oct 05 03:16:58 volumio-living volumio[19447]: info: Scanning into folder /volumio/app/plugins/ Oct 05 03:16:58 volumio-living volumio[19447]: info: Scanning category audio_interface Oct 05 03:16:58 volumio-living volumio[19447]: info: Scanning category miscellanea Oct 05 03:16:58 volumio-living volumio[19447]: info: Scanning category music_service Oct 05 03:16:58 volumio-living volumio[19447]: info: Scanning category plugins.json Oct 05 03:16:58 volumio-living volumio[19447]: info: Scanning category system_controller Oct 05 03:16:58 volumio-living volumio[19447]: info: Scanning category user_interface Oct 05 03:16:58 volumio-living volumio[19447]: info: Scanning into folder /data/plugins/ Oct 05 03:16:58 volumio-living volumio[19447]: info: Scanning category music_service Oct 05 03:16:58 volumio-living volumio[19447]: info: Scanning category user_interface Oct 05 03:16:58 volumio-living volumio[19447]: info: Plugin folders cleanup completed Oct 05 03:16:58 volumio-living volumio[19447]: info: ------------------------------------------- Oct 05 03:16:58 volumio-living volumio[19447]: info: ----- Core plugins startup ---- Oct 05 03:16:58 volumio-living volumio[19447]: info: ------------------------------------------- Oct 05 03:16:58 volumio-living volumio[19447]: info: Loading plugins from folder /volumio/app/plugins/ Oct 05 03:16:58 volumio-living volumio[19447]: info: Adding plugin upnp to MyMusic Plugins Oct 05 03:16:58 volumio-living volumio[19447]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 05 03:16:58 volumio-living volumio[19447]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 05 03:16:58 volumio-living volumio[19447]: info: Loading plugins from folder /data/plugins/ Oct 05 03:16:58 volumio-living volumio[19447]: info: Loading plugin "system"... Oct 05 03:16:58 volumio-living volumio[19447]: info: Loading plugin "appearance"... Oct 05 03:16:59 volumio-living volumio[19447]: info: Loading plugin "network"... Oct 05 03:16:59 volumio-living volumio[19447]: info: Refreshing Cached IP Addresses Oct 05 03:16:59 volumio-living sudo[19476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 03:16:59 volumio-living sudo[19476]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:59 volumio-living sudo[19476]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:59 volumio-living sudo[19478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 03:16:59 volumio-living volumio[19447]: info: Loading plugin "services"... Oct 05 03:16:59 volumio-living sudo[19478]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:59 volumio-living volumio[19447]: info: Loading plugin "alsa_controller"... Oct 05 03:16:59 volumio-living sudo[19478]: pam_unix(sudo:session): session closed for user root Oct 05 03:16:59 volumio-living sudo[19481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 05 03:16:59 volumio-living sudo[19481]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:16:59 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:16:59 volumio-living volumio[19447]: info: Loading plugin "wizard"... Oct 05 03:16:59 volumio-living volumio[19447]: info: Loading plugin "networkfs"... Oct 05 03:16:59 volumio-living volumio[19447]: info: Starting Udev Watcher for removable devices Oct 05 03:16:59 volumio-living volumio[19447]: info: Ignoring mount for partition: boot Oct 05 03:16:59 volumio-living volumio[19447]: info: Ignoring mount for partition: volumio Oct 05 03:16:59 volumio-living volumio[19447]: info: Ignoring mount for partition: volumio_data Oct 05 03:16:59 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:59 volumio-living volumio[19447]: info: Loading plugin "volumio_command_line_client"... Oct 05 03:16:59 volumio-living volumio[19447]: info: Loading plugin "upnp"... Oct 05 03:16:59 volumio-living volumio[19447]: info: [1728112619807] Starting Upmpd Daemon Oct 05 03:16:59 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:16:59 volumio-living volumio[19447]: info: Loading plugin "my_music"... Oct 05 03:16:59 volumio-living volumio[19447]: info: Loading plugin "mpd"... Oct 05 03:17:00 volumio-living volumio[19447]: info: Loading plugin "upnp_browser"... Oct 05 03:17:00 volumio-living volumio[19447]: info: Loading plugin "alarm-clock"... Oct 05 03:17:00 volumio-living volumio[19447]: info: Plugin airplay_emulation is not enabled Oct 05 03:17:00 volumio-living volumio[19447]: info: Loading plugin "last_100"... Oct 05 03:17:00 volumio-living volumio[19447]: info: Loading plugin "webradio"... Oct 05 03:17:00 volumio-living volumio[19447]: info: Loading plugin "i2s_dacs"... Oct 05 03:17:00 volumio-living volumio[19447]: info: I2S DAC not set, start Auto-detection Oct 05 03:17:00 volumio-living volumio[19447]: info: Loading plugin "volumiodiscovery"... Oct 05 03:17:00 volumio-living volumio[19447]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 05 03:17:00 volumio-living volumio[19447]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:17:00 volumio-living volumio[19447]: *** WARNING *** For more information see Oct 05 03:17:00 volumio-living node[19447]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 05 03:17:00 volumio-living volumio[19447]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 05 03:17:00 volumio-living volumio[19447]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:17:00 volumio-living volumio[19447]: *** WARNING *** For more information see Oct 05 03:17:00 volumio-living node[19447]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:17:00 volumio-living node[19447]: *** WARNING *** For more information see Oct 05 03:17:00 volumio-living node[19447]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 05 03:17:00 volumio-living node[19447]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:17:00 volumio-living node[19447]: *** WARNING *** For more information see Oct 05 03:17:00 volumio-living volumio[19447]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 05 03:17:00 volumio-living volumio[19447]: info: Discovery: Started advertising with name: Volumio-Living Oct 05 03:17:00 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:17:00 volumio-living volumio[19447]: info: Loading plugin "squeezelite_mc"... Oct 05 03:17:01 volumio-living volumio-remote-updater[586]: [2024-10-05 03:17:01] [connect] Successful connection Oct 05 03:17:01 volumio-living volumio[19447]: info: Loading plugin "outputs"... Oct 05 03:17:01 volumio-living volumio[19447]: info: Loading plugin "albumart"... Oct 05 03:17:01 volumio-living volumio[19447]: info: Plugin example_plugin is not enabled Oct 05 03:17:01 volumio-living volumio[19447]: info: Loading plugin "inputs"... Oct 05 03:17:01 volumio-living volumio[19447]: info: Loading plugin "updater_comm"... Oct 05 03:17:01 volumio-living volumio[19447]: info: Plugin mpdemulation is not enabled Oct 05 03:17:01 volumio-living volumio[19447]: info: Loading plugin "rest_api"... Oct 05 03:17:01 volumio-living volumio[19447]: info: Loading plugin "websocket"... Oct 05 03:17:01 volumio-living volumio[19447]: info: Starting Socket.io Server version 2.3.0 Oct 05 03:17:01 volumio-living volumio[19447]: info: Loading plugin "Systeminfo"... Oct 05 03:17:01 volumio-living volumio[19447]: Forking 3 albumart workers Oct 05 03:17:02 volumio-living volumio[19447]: info: Loading i18n strings for locale zh_TW Oct 05 03:17:02 volumio-living volumio[19447]: Updating browse sources language Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreCommandRouter::initPlayerControls Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:17:02 volumio-living volumio[19447]: Express server listening on port 3000 Oct 05 03:17:02 volumio-living volumio[19447]: [Metrics] WebUI: 5s 11.91ms Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreStateMachine::resetVolumioState Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreStateMachine::getcurrentVolume Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreStateMachine::pushState Oct 05 03:17:02 volumio-living volumio[19447]: info: CorePlayQueue::getTrack 0 Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreCommandRouter::volumioPushState Oct 05 03:17:02 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 03:17:02 volumio-living volumio[19447]: Starting albumart workers Oct 05 03:17:02 volumio-living volumio[19447]: Starting albumart workers Oct 05 03:17:02 volumio-living volumio[19447]: Starting albumart workers Oct 05 03:17:02 volumio-living volumio-remote-updater[586]: [2024-10-05 03:17:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728112621 101 Oct 05 03:17:02 volumio-living volumio[19447]: 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 Oct 05 03:17:03 volumio-living volumio[19447]: info: Reloading queue from file Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreStateMachine::setRepeat null single undefined Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreStateMachine::pushState Oct 05 03:17:03 volumio-living volumio[19447]: info: CorePlayQueue::getTrack 0 Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreCommandRouter::volumioPushState Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreStateMachine::setRandom null Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreStateMachine::pushState Oct 05 03:17:03 volumio-living volumio[19447]: info: CorePlayQueue::getTrack 0 Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreCommandRouter::volumioPushState Oct 05 03:17:03 volumio-living volumio[19447]: info: Setting Device type: Raspberry PI Oct 05 03:17:03 volumio-living volumio[19447]: info: Completed loading Core Plugins Oct 05 03:17:03 volumio-living volumio[19447]: info: Preparing to generate the ALSA configuration file Oct 05 03:17:03 volumio-living volumio[19447]: info: Asound.conf file unchanged, so no further update is needed Oct 05 03:17:03 volumio-living volumio[19447]: info: Output device has changed, restarting MPD Oct 05 03:17:03 volumio-living volumio[19447]: info: ___________ START PLUGINS ___________ Oct 05 03:17:03 volumio-living volumio[19447]: info: ControllerMpd::onStart: Initializing MPD Oct 05 03:17:03 volumio-living volumio[19447]: info: Creating MPD Configuration file Oct 05 03:17:03 volumio-living sudo[19525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 03:17:03 volumio-living sudo[19525]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:17:03 volumio-living sudo[19526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 03:17:03 volumio-living sudo[19525]: pam_unix(sudo:session): session closed for user root Oct 05 03:17:03 volumio-living sudo[19526]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:17:03 volumio-living volumio[19447]: info: [1728112623269] CoreMusicLibrary::Adding element Media Servers Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:17:03 volumio-living sudo[19532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 03:17:03 volumio-living sudo[19532]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:17:03 volumio-living volumio[19447]: info: [1728112623310] CoreMusicLibrary::Adding element Last_100 Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:17:03 volumio-living volumio[19447]: info: [1728112623315] CoreMusicLibrary::Adding element Webradio Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:17:03 volumio-living systemd[1]: Stopping Music Player Daemon... Oct 05 03:17:03 volumio-living sudo[19530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 03:17:03 volumio-living sudo[19530]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:17:03 volumio-living systemd[1]: mpd.service: Succeeded. Oct 05 03:17:03 volumio-living systemd[1]: Stopped Music Player Daemon. Oct 05 03:17:03 volumio-living volumio[19447]: info: Initializing BBC Radios Oct 05 03:17:03 volumio-living sudo[19530]: pam_unix(sudo:session): session closed for user root Oct 05 03:17:03 volumio-living systemd[1]: Starting Music Player Daemon... Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 03:17:03 volumio-living sudo[19535]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 05 03:17:03 volumio-living sudo[19535]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:17:03 volumio-living sudo[19535]: pam_unix(sudo:session): session closed for user root Oct 05 03:17:03 volumio-living volumio[19447]: info: [squeezelite_mc] Starting proxy server... Oct 05 03:17:03 volumio-living volumio[19447]: info: Volumio Calling Home Oct 05 03:17:03 volumio-living volumio[19447]: info: [squeezelite_mc] Proxy server started on port 39649 Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreStateMachine::pushState Oct 05 03:17:03 volumio-living volumio[19447]: info: CorePlayQueue::getTrack 0 Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreCommandRouter::volumioPushState Oct 05 03:17:03 volumio-living volumio[19447]: info: [squeezelite_mc] Server discovery started Oct 05 03:17:03 volumio-living volumio[19447]: info: [squeezelite_mc] Player finder started Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:03 volumio-living volumio[19447]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:17:03 volumio-living volumio[19447]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 05 03:17:04 volumio-living volumio[19447]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 03:17:04 volumio-living volumio[19447]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 05 03:17:04 volumio-living volumio[19447]: at doSend (dgram.js:692:16) Oct 05 03:17:04 volumio-living volumio[19447]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 05 03:17:04 volumio-living volumio[19447]: at afterDns (dgram.js:638:5) Oct 05 03:17:04 volumio-living volumio[19447]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 05 03:17:04 volumio-living volumio[19447]: errno: -101, Oct 05 03:17:04 volumio-living volumio[19447]: code: 'ENETUNREACH', Oct 05 03:17:04 volumio-living volumio[19447]: syscall: 'send', Oct 05 03:17:04 volumio-living volumio[19447]: address: '255.255.255.255', Oct 05 03:17:04 volumio-living volumio[19447]: port: 3483 Oct 05 03:17:04 volumio-living volumio[19447]: } Oct 05 03:17:04 volumio-living volumio[19447]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 03:17:04 volumio-living sudo[19586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 03:16 Oct 05 03:17:04 volumio-living sudo[19586]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:17:04 volumio-living sudo[19586]: pam_unix(sudo:session): session closed for user root Oct 05 03:17:04 volumio-living volumio-remote-updater[586]: [2024-10-05 03:17:04] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 05 03:17:04 volumio-living volumio-remote-updater[586]: [2024-10-05 03:17:04] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 05 03:17:04 volumio-living systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 05 03:17:04 volumio-living sudo[19526]: pam_unix(sudo:session): session closed for user root Oct 05 03:17:04 volumio-living sudo[19532]: pam_unix(sudo:session): session closed for user root Oct 05 03:17:04 volumio-living sudo[19481]: pam_unix(sudo:session): session closed for user root Oct 05 03:17:04 volumio-living systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 05 03:17:04 volumio-living systemd[1]: Started dynamicswap service. Oct 05 03:17:04 volumio-living systemd[1]: dynamicswap.service: Succeeded. Oct 05 03:17:05 volumio-living systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 05 03:17:05 volumio-living systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11. Oct 05 03:17:05 volumio-living systemd[1]: Started dynamicswap service. Oct 05 03:17:05 volumio-living systemd[1]: Stopped Volumio Backend Module. Oct 05 03:17:05 volumio-living systemd[1]: Started Volumio Backend Module. Oct 05 03:17:05 volumio-living systemd[1]: dynamicswap.service: Succeeded. Oct 05 03:17:05 volumio-living mpd[19547]: Oct 05 03:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 05 03:17:05 volumio-living systemd[1]: Started Music Player Daemon. Oct 05 03:17:05 volumio-living wpa_supplicant[852]: wlan0: Trying to associate with SSID 'Home' Oct 05 03:17:06 volumio-living volumio[19600]: info: ------------------------------------------- Oct 05 03:17:06 volumio-living volumio[19600]: info: ----- Volumio3 ---- Oct 05 03:17:06 volumio-living volumio[19600]: info: ------------------------------------------- Oct 05 03:17:06 volumio-living volumio[19600]: info: ----- System startup ---- Oct 05 03:17:06 volumio-living volumio[19600]: info: ------------------------------------------- Oct 05 03:17:07 volumio-living volumio[19600]: info: MYVOLUMIO Environment detected Oct 05 03:17:07 volumio-living volumio[19600]: info: Plugin folders cleanup Oct 05 03:17:07 volumio-living volumio[19600]: info: Scanning into folder /volumio/app/plugins/ Oct 05 03:17:07 volumio-living volumio[19600]: info: Scanning category audio_interface Oct 05 03:17:07 volumio-living volumio[19600]: info: Scanning category miscellanea Oct 05 03:17:07 volumio-living volumio[19600]: info: Scanning category music_service Oct 05 03:17:07 volumio-living volumio[19600]: info: Scanning category plugins.json Oct 05 03:17:07 volumio-living volumio[19600]: info: Scanning category system_controller Oct 05 03:17:07 volumio-living volumio[19600]: info: Scanning category user_interface Oct 05 03:17:07 volumio-living volumio[19600]: info: Scanning into folder /data/plugins/ Oct 05 03:17:07 volumio-living volumio[19600]: info: Scanning category music_service Oct 05 03:17:07 volumio-living volumio[19600]: info: Scanning category user_interface Oct 05 03:17:07 volumio-living volumio[19600]: info: Plugin folders cleanup completed Oct 05 03:17:07 volumio-living volumio[19600]: info: ------------------------------------------- Oct 05 03:17:07 volumio-living volumio[19600]: info: ----- Core plugins startup ---- Oct 05 03:17:07 volumio-living volumio[19600]: info: ------------------------------------------- Oct 05 03:17:07 volumio-living volumio[19600]: info: Loading plugins from folder /volumio/app/plugins/ Oct 05 03:17:07 volumio-living volumio[19600]: info: Adding plugin upnp to MyMusic Plugins Oct 05 03:17:07 volumio-living volumio[19600]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 05 03:17:07 volumio-living volumio[19600]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 05 03:17:07 volumio-living volumio[19600]: info: Loading plugins from folder /data/plugins/ Oct 05 03:17:07 volumio-living volumio[19600]: info: Loading plugin "system"... Oct 05 03:17:07 volumio-living volumio[19600]: info: Loading plugin "appearance"... Oct 05 03:17:08 volumio-living dhcpcd[864]: wlan0: carrier acquired Oct 05 03:17:08 volumio-living wpa_supplicant[852]: wlan0: Associated with 36:de:4b:76:33:8b Oct 05 03:17:08 volumio-living wpa_supplicant[852]: wlan0: CTRL-EVENT-CONNECTED - Connection to 36:de:4b:76:33:8b completed [id=0 id_str=] Oct 05 03:17:08 volumio-living wpa_supplicant[852]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 05 03:17:08 volumio-living wpa_supplicant[852]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Oct 05 03:17:08 volumio-living dhcpcd[864]: wlan0: IAID eb:80:5a:79 Oct 05 03:17:08 volumio-living dhcpcd[864]: wlan0: carrier lost Oct 05 03:17:08 volumio-living dhcpcd[864]: wlan0: carrier acquired Oct 05 03:17:08 volumio-living dhcpcd[864]: wlan0: IAID eb:80:5a:79 Oct 05 03:17:08 volumio-living volumio[19600]: info: Loading plugin "network"... Oct 05 03:17:08 volumio-living volumio[19600]: info: Refreshing Cached IP Addresses Oct 05 03:17:08 volumio-living sudo[19640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 03:17:08 volumio-living sudo[19640]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:17:08 volumio-living sudo[19640]: pam_unix(sudo:session): session closed for user root Oct 05 03:17:08 volumio-living sudo[19642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 03:17:08 volumio-living volumio[19600]: info: Loading plugin "services"... Oct 05 03:17:08 volumio-living sudo[19642]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:17:08 volumio-living volumio[19600]: info: Loading plugin "alsa_controller"... Oct 05 03:17:08 volumio-living sudo[19642]: pam_unix(sudo:session): session closed for user root Oct 05 03:17:08 volumio-living sudo[19645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 05 03:17:08 volumio-living sudo[19645]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:17:08 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:17:08 volumio-living volumio[19600]: info: Loading plugin "wizard"... Oct 05 03:17:08 volumio-living volumio[19600]: info: Loading plugin "networkfs"... Oct 05 03:17:08 volumio-living volumio[19600]: info: Starting Udev Watcher for removable devices Oct 05 03:17:08 volumio-living volumio[19600]: info: Ignoring mount for partition: boot Oct 05 03:17:08 volumio-living volumio[19600]: info: Ignoring mount for partition: volumio Oct 05 03:17:08 volumio-living volumio[19600]: info: Ignoring mount for partition: volumio_data Oct 05 03:17:08 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:17:08 volumio-living volumio[19600]: info: Loading plugin "volumio_command_line_client"... Oct 05 03:17:08 volumio-living volumio[19600]: info: Loading plugin "upnp"... Oct 05 03:17:08 volumio-living volumio[19600]: info: [1728112628698] Starting Upmpd Daemon Oct 05 03:17:08 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:17:08 volumio-living volumio[19600]: info: Loading plugin "my_music"... Oct 05 03:17:08 volumio-living dhcpcd[864]: wlan0: rebinding lease of 192.168.3.65 Oct 05 03:17:08 volumio-living volumio[19600]: info: Loading plugin "mpd"... Oct 05 03:17:08 volumio-living dhcpcd[864]: wlan0: probing address 192.168.3.65/22 Oct 05 03:17:08 volumio-living dhcpcd[864]: wlan0: soliciting an IPv6 router Oct 05 03:17:09 volumio-living volumio[19600]: info: Loading plugin "upnp_browser"... Oct 05 03:17:09 volumio-living volumio[19600]: info: Loading plugin "alarm-clock"... Oct 05 03:17:09 volumio-living volumio[19600]: info: Plugin airplay_emulation is not enabled Oct 05 03:17:09 volumio-living volumio[19600]: info: Loading plugin "last_100"... Oct 05 03:17:09 volumio-living volumio[19600]: info: Loading plugin "webradio"... Oct 05 03:17:09 volumio-living volumio[19600]: info: Loading plugin "i2s_dacs"... Oct 05 03:17:09 volumio-living volumio[19600]: info: I2S DAC not set, start Auto-detection Oct 05 03:17:09 volumio-living volumio[19600]: info: Loading plugin "volumiodiscovery"... Oct 05 03:17:09 volumio-living volumio[19600]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 05 03:17:09 volumio-living volumio[19600]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:17:09 volumio-living volumio[19600]: *** WARNING *** For more information see Oct 05 03:17:09 volumio-living node[19600]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 05 03:17:09 volumio-living volumio[19600]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 05 03:17:09 volumio-living volumio[19600]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:17:09 volumio-living volumio[19600]: *** WARNING *** For more information see Oct 05 03:17:09 volumio-living node[19600]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:17:09 volumio-living node[19600]: *** WARNING *** For more information see Oct 05 03:17:09 volumio-living node[19600]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 05 03:17:09 volumio-living node[19600]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 03:17:09 volumio-living node[19600]: *** WARNING *** For more information see Oct 05 03:17:09 volumio-living volumio[19600]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 05 03:17:09 volumio-living volumio[19600]: info: Discovery: Started advertising with name: Volumio-Living Oct 05 03:17:09 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 03:17:09 volumio-living volumio[19600]: info: Loading plugin "squeezelite_mc"... Oct 05 03:17:09 volumio-living volumio-remote-updater[586]: [2024-10-05 03:17:09] [connect] Successful connection Oct 05 03:17:10 volumio-living volumio[19600]: info: Loading plugin "outputs"... Oct 05 03:17:10 volumio-living volumio[19600]: info: Loading plugin "albumart"... Oct 05 03:17:10 volumio-living volumio[19600]: info: Plugin example_plugin is not enabled Oct 05 03:17:10 volumio-living volumio[19600]: info: Loading plugin "inputs"... Oct 05 03:17:10 volumio-living volumio[19600]: info: Loading plugin "updater_comm"... Oct 05 03:17:10 volumio-living volumio[19600]: info: Plugin mpdemulation is not enabled Oct 05 03:17:10 volumio-living volumio[19600]: info: Loading plugin "rest_api"... Oct 05 03:17:10 volumio-living wpa_supplicant[852]: RRM: Ignoring radio measurement request: Not RRM network Oct 05 03:17:10 volumio-living volumio[19600]: info: Loading plugin "websocket"... Oct 05 03:17:10 volumio-living volumio[19600]: info: Starting Socket.io Server version 2.3.0 Oct 05 03:17:10 volumio-living volumio[19600]: info: Loading plugin "Systeminfo"... Oct 05 03:17:10 volumio-living volumio[19600]: Forking 3 albumart workers Oct 05 03:17:10 volumio-living volumio[19600]: info: Loading i18n strings for locale zh_TW Oct 05 03:17:10 volumio-living volumio[19600]: Updating browse sources language Oct 05 03:17:10 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::initPlayerControls Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:17:11 volumio-living volumio[19600]: Express server listening on port 3000 Oct 05 03:17:11 volumio-living volumio[19600]: [Metrics] WebUI: 5s 11.61ms Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreStateMachine::resetVolumioState Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreStateMachine::getcurrentVolume Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreStateMachine::pushState Oct 05 03:17:11 volumio-living volumio[19600]: info: CorePlayQueue::getTrack 0 Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::volumioPushState Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 03:17:11 volumio-living volumio-remote-updater[586]: [2024-10-05 03:17:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728112629 101 Oct 05 03:17:11 volumio-living volumio[19600]: 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 Oct 05 03:17:11 volumio-living volumio[19600]: info: Reloading queue from file Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreStateMachine::setRepeat null single undefined Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreStateMachine::pushState Oct 05 03:17:11 volumio-living volumio[19600]: info: CorePlayQueue::getTrack 0 Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::volumioPushState Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreStateMachine::setRandom null Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreStateMachine::pushState Oct 05 03:17:11 volumio-living volumio[19600]: info: CorePlayQueue::getTrack 0 Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::volumioPushState Oct 05 03:17:11 volumio-living volumio[19600]: info: Setting Device type: Raspberry PI Oct 05 03:17:11 volumio-living volumio[19600]: info: Completed loading Core Plugins Oct 05 03:17:11 volumio-living volumio[19600]: info: Preparing to generate the ALSA configuration file Oct 05 03:17:11 volumio-living volumio[19600]: info: Asound.conf file unchanged, so no further update is needed Oct 05 03:17:11 volumio-living volumio[19600]: info: Output device has changed, restarting MPD Oct 05 03:17:11 volumio-living volumio[19600]: info: ___________ START PLUGINS ___________ Oct 05 03:17:11 volumio-living volumio[19600]: info: ControllerMpd::onStart: Initializing MPD Oct 05 03:17:11 volumio-living volumio[19600]: info: Creating MPD Configuration file Oct 05 03:17:11 volumio-living volumio[19600]: Starting albumart workers Oct 05 03:17:11 volumio-living sudo[19688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 03:17:11 volumio-living sudo[19688]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:17:11 volumio-living sudo[19690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 03:17:11 volumio-living sudo[19690]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:17:11 volumio-living sudo[19688]: pam_unix(sudo:session): session closed for user root Oct 05 03:17:11 volumio-living sudo[19693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 03:17:11 volumio-living sudo[19693]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:17:11 volumio-living volumio[19600]: Starting albumart workers Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:17:11 volumio-living volumio[19600]: info: [1728112631854] CoreMusicLibrary::Adding element Media Servers Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:17:11 volumio-living sudo[19693]: pam_unix(sudo:session): session closed for user root Oct 05 03:17:11 volumio-living systemd[1]: Stopping Music Player Daemon... Oct 05 03:17:11 volumio-living systemd[1]: mpd.service: Succeeded. Oct 05 03:17:11 volumio-living systemd[1]: Stopped Music Player Daemon. Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:17:11 volumio-living volumio[19600]: info: [1728112631938] CoreMusicLibrary::Adding element Last_100 Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 03:17:11 volumio-living volumio[19600]: info: [1728112631942] CoreMusicLibrary::Adding element Webradio Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 03:17:11 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:17:11 volumio-living volumio[19600]: Starting albumart workers Oct 05 03:17:11 volumio-living volumio[19600]: info: Initializing BBC Radios Oct 05 03:17:11 volumio-living sudo[19697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 03:17:11 volumio-living sudo[19697]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:17:11 volumio-living systemd[1]: Starting Music Player Daemon... Oct 05 03:17:12 volumio-living systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 05 03:17:12 volumio-living systemd[1]: mpd.service: Succeeded. Oct 05 03:17:12 volumio-living systemd[1]: Stopped Music Player Daemon. Oct 05 03:17:12 volumio-living systemd[1]: Starting Music Player Daemon... Oct 05 03:17:12 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 03:17:12 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 03:17:12 volumio-living volumio[19600]: info: [squeezelite_mc] Starting proxy server... Oct 05 03:17:12 volumio-living volumio[19600]: info: Volumio Calling Home Oct 05 03:17:12 volumio-living sudo[19706]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 05 03:17:12 volumio-living sudo[19706]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 03:17:12 volumio-living sudo[19706]: pam_unix(sudo:session): session closed for user root Oct 05 03:17:12 volumio-living volumio[19600]: info: [squeezelite_mc] Proxy server started on port 41167 Oct 05 03:17:12 volumio-living volumio[19600]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 03:17:12 volumio-living volumio[19600]: info: CoreStateMachine::pushState Oct 05 03:17:12 volumio-living volumio[19600]: info: CorePlayQueue::getTrack 0 Oct 05 03:17:12 volumio-living volumio[19600]: info: CoreCommandRouter::volumioPushState Oct 05 03:17:12 volumio-living volumio[19600]: info: [squeezelite_mc] Server discovery started Oct 05 03:17:12 volumio-living volumio[19600]: info: [squeezelite_mc] Player finder started Oct 05 03:17:12 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:12 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 03:17:12 volumio-living volumio[19600]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 03:17:12 volumio-living volumio[19600]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 05 03:17:12 volumio-living volumio[19600]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 03:17:12 volumio-living volumio[19600]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 05 03:17:12 volumio-living volumio[19600]: at doSend (dgram.js:692:16) Oct 05 03:17:12 volumio-living volumio[19600]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 05 03:17:12 volumio-living volumio[19600]: at afterDns (dgram.js:638:5) Oct 05 03:17:12 volumio-living volumio[19600]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 05 03:17:12 volumio-living volumio[19600]: errno: -101, Oct 05 03:17:12 volumio-living volumio[19600]: code: 'ENETUNREACH', Oct 05 03:17:12 volumio-living volumio[19600]: syscall: 'send', Oct 05 03:17:12 volumio-living volumio[19600]: address: '255.255.255.255', Oct 05 03:17:12 volumio-living volumio[19600]: port: 3483 Oct 05 03:17:12 volumio-living volumio[19600]: } Oct 05 03:17:12 volumio-living volumio[19600]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 03:17:12 volumio-living sudo[19645]: pam_unix(sudo:session): session closed for user root Oct 05 03:17:13 volumio-living dhcpcd[864]: wlan0: leased 192.168.3.65 for 7200 seconds Oct 05 03:17:13 volumio-living avahi-daemon[567]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.65. Oct 05 03:17:13 volumio-living dhcpcd[864]: wlan0: adding route to 192.168.0.0/22 Oct 05 03:17:13 volumio-living dhcpcd[864]: wlan0: adding default route via 192.168.0.1 Oct 05 03:17:13 volumio-living avahi-daemon[567]: New relevant interface wlan0.IPv4 for mDNS. Oct 05 03:17:13 volumio-living avahi-daemon[567]: Registering new address record for 192.168.3.65 on wlan0.IPv4. Oct 05 03:17:13 volumio-living sudo[19772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 03:16 Oct 05 03:17:13 volumio-living sudo[19772]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"