-- Logs begin at Sun 2025-12-14 01:13:28 GMT, end at Mon 2025-12-22 21:07:54 GMT. -- Dec 22 21:06:17 dining-room volumiologrotate[590]: ls: cannot access '/var/log/samba/log.wb-DINING': No such file or directory Dec 22 21:06:17 dining-room volumiologrotate[590]: ls: cannot access 'ROOM': No such file or directory Dec 22 21:06:39 dining-room wpa_supplicant[873]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f0:9f:c2:7d:9a:77 reason=0 locally_generated=1 Dec 22 21:06:39 dining-room dhcpcd[885]: wlan0: carrier lost Dec 22 21:06:39 dining-room wpa_supplicant[873]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Dec 22 21:06:39 dining-room wpa_supplicant[873]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=GB Dec 22 21:06:40 dining-room avahi-daemon[584]: Withdrawing address record for 10.132.1.47 on wlan0. Dec 22 21:06:40 dining-room avahi-daemon[584]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.132.1.47. Dec 22 21:06:40 dining-room dhcpcd[885]: wlan0: deleting route to 10.132.1.0/24 Dec 22 21:06:40 dining-room dhcpcd[885]: wlan0: deleting default route via 10.132.1.254 Dec 22 21:06:40 dining-room avahi-daemon[584]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 22 21:06:40 dining-room volumio[30969]: info: Discovery: A device disappeared from network Dec 22 21:06:40 dining-room volumio[30969]: info: Volumio Network Manager: Network status updated: 0 Dec 22 21:06:42 dining-room ntpd[769]: Deleting interface #7 wlan0, 10.132.1.47#123, interface stats: received=4145, sent=4268, dropped=0, active_time=264520 secs Dec 22 21:06:42 dining-room ntpd[769]: 194.213.3.203 local addr 10.132.1.47 -> Dec 22 21:06:42 dining-room ntpd[769]: 178.62.68.79 local addr 10.132.1.47 -> Dec 22 21:06:42 dining-room ntpd[769]: 88.80.187.85 local addr 10.132.1.47 -> Dec 22 21:06:42 dining-room ntpd[769]: 46.101.52.249 local addr 10.132.1.47 -> Dec 22 21:06:42 dining-room ntpd[769]: 109.74.206.120 local addr 10.132.1.47 -> Dec 22 21:06:42 dining-room ntpd[769]: 139.162.255.65 local addr 10.132.1.47 -> Dec 22 21:06:42 dining-room ntpd[769]: 193.57.144.50 local addr 10.132.1.47 -> Dec 22 21:06:42 dining-room ntpd[769]: 185.177.149.33 local addr 10.132.1.47 -> Dec 22 21:06:42 dining-room ntpd[769]: 176.58.120.34 local addr 10.132.1.47 -> Dec 22 21:06:42 dining-room ntpd[769]: 185.16.161.37 local addr 10.132.1.47 -> Dec 22 21:06:42 dining-room ntpd[769]: 185.103.119.60 local addr 10.132.1.47 -> Dec 22 21:06:42 dining-room ntpd[769]: 162.159.200.1 local addr 10.132.1.47 -> Dec 22 21:06:42 dining-room ntpd[769]: 178.79.150.226 local addr 10.132.1.47 -> Dec 22 21:06:42 dining-room ntpd[769]: 139.143.5.31 local addr 10.132.1.47 -> Dec 22 21:06:42 dining-room ntpd[769]: 178.79.158.157 local addr 10.132.1.47 -> Dec 22 21:06:51 dining-room wpa_supplicant[873]: wlan0: Failed to initiate sched scan Dec 22 21:06:53 dining-room volumio[30969]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 21:06:53 dining-room volumio[30969]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 22 21:06:53 dining-room volumio[30969]: at doSend (dgram.js:692:16) Dec 22 21:06:53 dining-room volumio[30969]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 22 21:06:53 dining-room volumio[30969]: at afterDns (dgram.js:638:5) Dec 22 21:06:53 dining-room volumio[30969]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 22 21:06:53 dining-room volumio[30969]: errno: -101, Dec 22 21:06:53 dining-room volumio[30969]: code: 'ENETUNREACH', Dec 22 21:06:53 dining-room volumio[30969]: syscall: 'send', Dec 22 21:06:53 dining-room volumio[30969]: address: '255.255.255.255', Dec 22 21:06:53 dining-room volumio[30969]: port: 3483 Dec 22 21:06:53 dining-room volumio[30969]: } Dec 22 21:06:53 dining-room volumio[30969]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 21:06:54 dining-room sudo[8595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-22 21:05 Dec 22 21:06:54 dining-room sudo[8595]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:06:54 dining-room sudo[8595]: pam_unix(sudo:session): session closed for user root Dec 22 21:06:54 dining-room volumio-remote-updater[618]: [2025-12-22 21:06:54] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 22 21:06:54 dining-room volumio-remote-updater[618]: [2025-12-22 21:06:54] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 22 21:06:54 dining-room systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 22 21:06:54 dining-room systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 22 21:06:54 dining-room systemd[1]: Started dynamicswap service. Dec 22 21:06:54 dining-room systemd[1]: dynamicswap.service: Succeeded. Dec 22 21:06:54 dining-room systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 22 21:06:54 dining-room systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Dec 22 21:06:54 dining-room systemd[1]: Stopped Volumio Backend Module. Dec 22 21:06:54 dining-room systemd[1]: Started Volumio Backend Module. Dec 22 21:06:54 dining-room systemd[1]: Started dynamicswap service. Dec 22 21:06:55 dining-room systemd[1]: dynamicswap.service: Succeeded. Dec 22 21:06:57 dining-room wpa_supplicant[873]: wlan0: Failed to initiate sched scan Dec 22 21:06:57 dining-room volumio[8608]: info: ------------------------------------------- Dec 22 21:06:57 dining-room volumio[8608]: info: ----- Volumio3 ---- Dec 22 21:06:57 dining-room volumio[8608]: info: ------------------------------------------- Dec 22 21:06:57 dining-room volumio[8608]: info: ----- System startup ---- Dec 22 21:06:57 dining-room volumio[8608]: info: ------------------------------------------- Dec 22 21:06:58 dining-room volumio[8608]: info: MYVOLUMIO Environment detected Dec 22 21:06:58 dining-room volumio[8608]: info: Plugin folders cleanup Dec 22 21:06:58 dining-room volumio[8608]: info: Scanning into folder /volumio/app/plugins/ Dec 22 21:06:58 dining-room volumio[8608]: info: Scanning category audio_interface Dec 22 21:06:58 dining-room volumio[8608]: info: Scanning category miscellanea Dec 22 21:06:58 dining-room volumio[8608]: info: Scanning category music_service Dec 22 21:06:58 dining-room volumio[8608]: info: Scanning category plugins.json Dec 22 21:06:58 dining-room volumio[8608]: info: Scanning category system_controller Dec 22 21:06:58 dining-room volumio[8608]: info: Scanning category user_interface Dec 22 21:06:58 dining-room volumio[8608]: info: Scanning into folder /data/plugins/ Dec 22 21:06:58 dining-room volumio[8608]: info: Scanning category music_service Dec 22 21:06:58 dining-room volumio[8608]: info: Scanning category touch_display Dec 22 21:06:58 dining-room volumio[8608]: info: Scanning category user_interface Dec 22 21:06:58 dining-room volumio[8608]: info: Plugin folders cleanup completed Dec 22 21:06:58 dining-room volumio[8608]: info: ------------------------------------------- Dec 22 21:06:58 dining-room volumio[8608]: info: ----- Core plugins startup ---- Dec 22 21:06:58 dining-room volumio[8608]: info: ------------------------------------------- Dec 22 21:06:58 dining-room volumio[8608]: info: Loading plugins from folder /volumio/app/plugins/ Dec 22 21:06:58 dining-room volumio[8608]: info: Adding plugin upnp to MyMusic Plugins Dec 22 21:06:58 dining-room volumio[8608]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 22 21:06:58 dining-room volumio[8608]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 22 21:06:58 dining-room volumio[8608]: info: Loading plugins from folder /data/plugins/ Dec 22 21:06:58 dining-room volumio[8608]: info: Loading plugin "system"... Dec 22 21:06:58 dining-room volumio[8608]: info: Loading plugin "appearance"... Dec 22 21:06:59 dining-room volumio-remote-updater[618]: [2025-12-22 21:06:59] [connect] Successful connection Dec 22 21:07:00 dining-room volumio[8608]: info: Loading plugin "network"... Dec 22 21:07:00 dining-room volumio[8608]: info: Refreshing Cached IP Addresses Dec 22 21:07:00 dining-room sudo[8639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 21:07:00 dining-room sudo[8639]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:00 dining-room sudo[8639]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:00 dining-room sudo[8641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 21:07:00 dining-room sudo[8641]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:00 dining-room volumio[8608]: info: Loading plugin "services"... Dec 22 21:07:00 dining-room sudo[8641]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:00 dining-room volumio[8608]: info: Loading plugin "alsa_controller"... Dec 22 21:07:00 dining-room sudo[8654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 22 21:07:00 dining-room sudo[8654]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:00 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 21:07:00 dining-room volumio[8608]: info: Loading plugin "wizard"... Dec 22 21:07:00 dining-room volumio[8608]: info: Loading plugin "networkfs"... Dec 22 21:07:00 dining-room volumio[8608]: info: Starting Udev Watcher for removable devices Dec 22 21:07:00 dining-room volumio[8608]: info: Ignoring mount for partition: boot Dec 22 21:07:00 dining-room volumio[8608]: info: Ignoring mount for partition: volumio Dec 22 21:07:00 dining-room volumio[8608]: info: Ignoring mount for partition: volumio_data Dec 22 21:07:00 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 21:07:00 dining-room volumio[8608]: info: Loading plugin "volumio_command_line_client"... Dec 22 21:07:00 dining-room volumio[8608]: info: Loading plugin "upnp"... Dec 22 21:07:00 dining-room volumio[8608]: info: [1766437620636] Starting Upmpd Daemon Dec 22 21:07:00 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 21:07:00 dining-room volumio[8608]: info: Loading plugin "my_music"... Dec 22 21:07:00 dining-room volumio[8608]: info: Loading plugin "mpd"... Dec 22 21:07:01 dining-room sudo[8654]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:01 dining-room volumio[8608]: info: Loading plugin "upnp_browser"... Dec 22 21:07:01 dining-room volumio[8608]: info: Loading plugin "alarm-clock"... Dec 22 21:07:01 dining-room volumio[8608]: info: Loading plugin "airplay_emulation"... Dec 22 21:07:01 dining-room volumio[8608]: info: Starting Shairport Sync Dec 22 21:07:01 dining-room volumio[8608]: info: Loading plugin "last_100"... Dec 22 21:07:01 dining-room volumio[8608]: info: Loading plugin "webradio"... Dec 22 21:07:02 dining-room volumio[8608]: info: Loading plugin "i2s_dacs"... Dec 22 21:07:02 dining-room volumio[8608]: info: I2S DAC not set, start Auto-detection Dec 22 21:07:02 dining-room volumio[8608]: info: Loading plugin "volumiodiscovery"... Dec 22 21:07:02 dining-room volumio[8608]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 22 21:07:02 dining-room volumio[8608]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 21:07:02 dining-room volumio[8608]: *** WARNING *** For more information see Dec 22 21:07:02 dining-room volumio[8608]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 22 21:07:02 dining-room volumio[8608]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 21:07:02 dining-room node[8608]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 22 21:07:02 dining-room volumio[8608]: *** WARNING *** For more information see Dec 22 21:07:02 dining-room node[8608]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 21:07:02 dining-room node[8608]: *** WARNING *** For more information see Dec 22 21:07:02 dining-room node[8608]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 22 21:07:02 dining-room node[8608]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 21:07:02 dining-room node[8608]: *** WARNING *** For more information see Dec 22 21:07:02 dining-room volumio[8608]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 22 21:07:02 dining-room volumio[8608]: info: Discovery: Started advertising with name: Dining Room Dec 22 21:07:02 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 21:07:02 dining-room volumio[8608]: info: Loading plugin "squeezelite_mc"... Dec 22 21:07:03 dining-room wpa_supplicant[873]: wlan0: Failed to initiate sched scan Dec 22 21:07:03 dining-room volumio[8608]: info: Loading plugin "now_playing"... Dec 22 21:07:05 dining-room volumio[8608]: info: Loading plugin "outputs"... Dec 22 21:07:05 dining-room volumio[8608]: info: Loading plugin "albumart"... Dec 22 21:07:05 dining-room volumio[8608]: info: Plugin example_plugin is not enabled Dec 22 21:07:05 dining-room volumio[8608]: info: Loading plugin "inputs"... Dec 22 21:07:05 dining-room volumio[8608]: info: Loading plugin "updater_comm"... Dec 22 21:07:06 dining-room volumio[8608]: info: Plugin mpdemulation is not enabled Dec 22 21:07:06 dining-room volumio[8608]: info: Loading plugin "rest_api"... Dec 22 21:07:06 dining-room volumio[8608]: info: Loading plugin "websocket"... Dec 22 21:07:06 dining-room volumio[8608]: info: Starting Socket.io Server version 2.3.0 Dec 22 21:07:06 dining-room volumio[8608]: info: Loading plugin "touch_display"... Dec 22 21:07:06 dining-room volumio[8608]: Forking 3 albumart workers Dec 22 21:07:06 dining-room volumio[8608]: info: Applying required configuration parameters for plugin touch_display Dec 22 21:07:07 dining-room volumio[8608]: info: Loading i18n strings for locale en Dec 22 21:07:07 dining-room volumio[8608]: Updating browse sources language Dec 22 21:07:07 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:07 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 21:07:07 dining-room volumio[8608]: info: CoreCommandRouter::initPlayerControls Dec 22 21:07:07 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:07 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:07 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:07 dining-room volumio[8608]: Starting albumart workers Dec 22 21:07:07 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:07 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:07 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:07 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:07 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:07 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 21:07:07 dining-room volumio[8608]: Express server listening on port 3000 Dec 22 21:07:07 dining-room volumio[8608]: [Metrics] WebUI: 10s 772.45ms Dec 22 21:07:07 dining-room volumio[8608]: info: CoreStateMachine::resetVolumioState Dec 22 21:07:07 dining-room volumio[8608]: info: CoreStateMachine::getcurrentVolume Dec 22 21:07:07 dining-room volumio[8608]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 21:07:07 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:07 dining-room volumio[8608]: info: Cannot read play queue from file Dec 22 21:07:07 dining-room volumio[8608]: Starting albumart workers Dec 22 21:07:07 dining-room volumio[8608]: info: Volumio Network Manager: Network status updated: 0 Dec 22 21:07:07 dining-room volumio[8608]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 22 21:07:07 dining-room volumio[8608]: Starting albumart workers Dec 22 21:07:08 dining-room volumio[8608]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Dec 22 21:07:08 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Dec 22 21:07:08 dining-room volumio[8608]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Dec 22 21:07:08 dining-room volumio[8608]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 22 21:07:08 dining-room volumio[8608]: info: Listing playlists Dec 22 21:07:08 dining-room volumio[8608]: info: CoreCommandRouter::volumioGetQueue Dec 22 21:07:08 dining-room volumio[8608]: info: CoreStateMachine::getQueue Dec 22 21:07:08 dining-room volumio[8608]: info: CorePlayQueue::getQueue Dec 22 21:07:08 dining-room volumio[8608]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 21:07:08 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:08 dining-room volumio[8608]: info: CoreCommandRouter::volumioGetState Dec 22 21:07:08 dining-room volumio[8608]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:08 dining-room volumio[8608]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Dec 22 21:07:08 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Dec 22 21:07:08 dining-room volumio[8608]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Dec 22 21:07:08 dining-room volumio[8608]: info: Listing playlists Dec 22 21:07:08 dining-room volumio[8608]: info: CoreCommandRouter::volumioGetQueue Dec 22 21:07:08 dining-room volumio[8608]: info: CoreStateMachine::getQueue Dec 22 21:07:08 dining-room volumio[8608]: info: CorePlayQueue::getQueue Dec 22 21:07:08 dining-room volumio[8608]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 21:07:08 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:08 dining-room volumio[8608]: info: CoreCommandRouter::volumioGetState Dec 22 21:07:08 dining-room volumio[8608]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:08 dining-room volumio[8608]: info: VolumeController:: Volume=100 Mute =false Dec 22 21:07:08 dining-room volumio[8608]: info: CoreStateMachine::pushState Dec 22 21:07:08 dining-room volumio[8608]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:08 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 21:07:08 dining-room volumio[8608]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:08 dining-room volumio[8608]: info: CoreStateMachine::updateTrackBlock Dec 22 21:07:08 dining-room volumio[8608]: info: CorePlayQueue::getTrackBlock Dec 22 21:07:08 dining-room volumio[8608]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 21:07:08 dining-room volumio[8608]: info: CoreStateMachine::setRepeat null single undefined Dec 22 21:07:08 dining-room volumio[8608]: info: CoreStateMachine::pushState Dec 22 21:07:08 dining-room volumio[8608]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:08 dining-room volumio[8608]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:08 dining-room volumio[8608]: info: CoreStateMachine::setRandom null Dec 22 21:07:08 dining-room volumio[8608]: info: CoreStateMachine::pushState Dec 22 21:07:08 dining-room volumio[8608]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:08 dining-room volumio[8608]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:08 dining-room volumio[8608]: info: Setting Device type: Raspberry PI Dec 22 21:07:08 dining-room volumio[8608]: info: VolumeController:: Volume=100 Mute =false Dec 22 21:07:08 dining-room volumio[8608]: info: CoreStateMachine::pushState Dec 22 21:07:08 dining-room volumio[8608]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:08 dining-room volumio[8608]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:08 dining-room wpa_supplicant[873]: wlan0: Failed to initiate sched scan Dec 22 21:07:08 dining-room volumio[8608]: info: Completed loading Core Plugins Dec 22 21:07:08 dining-room volumio[8608]: info: Preparing to generate the ALSA configuration file Dec 22 21:07:08 dining-room volumio[8608]: info: Asound.conf file unchanged, so no further update is needed Dec 22 21:07:08 dining-room volumio[8608]: info: Output device has changed, restarting MPD Dec 22 21:07:08 dining-room volumio[8608]: info: Output device has changed, restarting Shairport Sync Dec 22 21:07:08 dining-room sudo[8725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 21:07:08 dining-room sudo[8725]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:08 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:08 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:08 dining-room sudo[8725]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:08 dining-room sudo[8727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 21:07:08 dining-room sudo[8727]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:08 dining-room systemd[1]: Stopping Music Player Daemon... Dec 22 21:07:08 dining-room volumio[8608]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 21:07:08 dining-room volumio[8608]: info: ___________ START PLUGINS ___________ Dec 22 21:07:09 dining-room volumio[8608]: info: ControllerMpd::onStart: Initializing MPD Dec 22 21:07:09 dining-room volumio[8608]: info: Creating MPD Configuration file Dec 22 21:07:09 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 21:07:09 dining-room volumio[8608]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 21:07:09 dining-room volumio[8608]: info: [1766437629059] CoreMusicLibrary::Adding element Media Servers Dec 22 21:07:09 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:09 dining-room sudo[8744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 21:07:09 dining-room sudo[8742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 21:07:09 dining-room sudo[8744]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:09 dining-room systemd[1]: mpd.service: Succeeded. Dec 22 21:07:09 dining-room systemd[1]: Stopped Music Player Daemon. Dec 22 21:07:09 dining-room sudo[8742]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:09 dining-room sudo[8742]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:09 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:09 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:09 dining-room volumio[8608]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 21:07:09 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:09 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:09 dining-room systemd[1]: Starting Music Player Daemon... Dec 22 21:07:09 dining-room systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 22 21:07:09 dining-room systemd[1]: mpd.service: Succeeded. Dec 22 21:07:09 dining-room systemd[1]: Stopped Music Player Daemon. Dec 22 21:07:09 dining-room systemd[1]: Starting Music Player Daemon... Dec 22 21:07:09 dining-room volumio[8608]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 21:07:09 dining-room volumio[8608]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 21:07:09 dining-room volumio[8608]: info: [1766437629234] CoreMusicLibrary::Adding element Last_100 Dec 22 21:07:09 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:09 dining-room volumio[8608]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 21:07:09 dining-room volumio[8608]: info: [1766437629239] CoreMusicLibrary::Adding element Webradio Dec 22 21:07:09 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:09 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 21:07:09 dining-room volumio[8608]: info: Initializing BBC Radios Dec 22 21:07:09 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 21:07:09 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:09 dining-room sudo[8755]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 21:07:09 dining-room sudo[8755]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:09 dining-room sudo[8755]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:09 dining-room volumio[8608]: info: [squeezelite_mc] Starting proxy server... Dec 22 21:07:09 dining-room volumio[8608]: info: [now-playing] ConfigUpdater: config is up to date. Dec 22 21:07:09 dining-room volumio[8608]: info: Loading i18n strings for locale en Dec 22 21:07:09 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 21:07:09 dining-room volumio[8608]: info: Volumio Calling Home Dec 22 21:07:09 dining-room volumio[8608]: info: [squeezelite_mc] Proxy server started on port 38983 Dec 22 21:07:09 dining-room sudo[8772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 22 21:07:09 dining-room sudo[8772]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:09 dining-room sudo[8774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 22 21:07:09 dining-room sudo[8774]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:09 dining-room sudo[8777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 22 21:07:09 dining-room sudo[8777]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:09 dining-room systemd[1]: Reloading. Dec 22 21:07:09 dining-room volumio[8608]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 21:07:09 dining-room volumio[8608]: info: [now-playing] App is listening on port 4004. Dec 22 21:07:09 dining-room volumio[8608]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Dec 22 21:07:09 dining-room volumio[8608]: Unhandled rejection Error: No sockets available, cannot start. Dec 22 21:07:09 dining-room volumio[8608]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 22 21:07:09 dining-room volumio[8608]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 22 21:07:09 dining-room volumio[8608]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 22 21:07:09 dining-room volumio[8608]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 22 21:07:09 dining-room volumio[8608]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 22 21:07:09 dining-room volumio[8608]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 22 21:07:09 dining-room volumio[8608]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 22 21:07:09 dining-room volumio[8608]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 22 21:07:09 dining-room volumio[8608]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 22 21:07:09 dining-room volumio[8608]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 22 21:07:09 dining-room volumio[8608]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 22 21:07:09 dining-room volumio[8608]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 22 21:07:09 dining-room volumio[8608]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 22 21:07:09 dining-room volumio[8608]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 22 21:07:09 dining-room volumio[8608]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 22 21:07:09 dining-room volumio[8608]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 22 21:07:09 dining-room volumio[8608]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Dec 22 21:07:09 dining-room volumio[8608]: info: MPD Permissions set Dec 22 21:07:09 dining-room volumio[8608]: info: MPD Permissions set Dec 22 21:07:10 dining-room volumio[8608]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Dec 22 21:07:10 dining-room systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 22 21:07:10 dining-room volumio[8608]: info: VolumeController:: Volume=100 Mute =false Dec 22 21:07:10 dining-room sudo[8805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Dec 22 21:07:10 dining-room volumio[8608]: info: CoreStateMachine::pushState Dec 22 21:07:10 dining-room volumio[8608]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:10 dining-room sudo[8805]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:10 dining-room sudo[8805]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:10 dining-room volumio[8608]: info: [squeezelite_mc] Server discovery started Dec 22 21:07:10 dining-room volumio[8608]: info: [squeezelite_mc] Player finder started Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 21:07:10 dining-room volumio[8608]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 22 21:07:10 dining-room volumio[8608]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 21:07:10 dining-room volumio[8608]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 22 21:07:10 dining-room volumio[8608]: at doSend (dgram.js:692:16) Dec 22 21:07:10 dining-room volumio[8608]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 22 21:07:10 dining-room volumio[8608]: at afterDns (dgram.js:638:5) Dec 22 21:07:10 dining-room volumio[8608]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 22 21:07:10 dining-room volumio[8608]: errno: -101, Dec 22 21:07:10 dining-room volumio[8608]: code: 'ENETUNREACH', Dec 22 21:07:10 dining-room volumio[8608]: syscall: 'send', Dec 22 21:07:10 dining-room volumio[8608]: address: '255.255.255.255', Dec 22 21:07:10 dining-room volumio[8608]: port: 3483 Dec 22 21:07:10 dining-room volumio[8608]: } Dec 22 21:07:10 dining-room volumio[8608]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 21:07:11 dining-room sudo[8777]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:11 dining-room systemd[1]: Reloading. Dec 22 21:07:11 dining-room sudo[8846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-22 21:06 Dec 22 21:07:11 dining-room sudo[8846]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:11 dining-room systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 22 21:07:11 dining-room sudo[8846]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:11 dining-room mpd[8767]: Dec 22 21:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 22 21:07:12 dining-room systemd[1]: Started Music Player Daemon. Dec 22 21:07:12 dining-room sudo[8744]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:12 dining-room systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 22 21:07:12 dining-room sudo[8727]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:12 dining-room sudo[8772]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:12 dining-room sudo[8774]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:12 dining-room systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 22 21:07:12 dining-room systemd[1]: Started dynamicswap service. Dec 22 21:07:12 dining-room systemd[1]: dynamicswap.service: Succeeded. Dec 22 21:07:12 dining-room volumio-remote-updater[618]: [2025-12-22 21:07:12] [info] asio async_connect error: system:111 (Connection refused) Dec 22 21:07:12 dining-room volumio-remote-updater[618]: [2025-12-22 21:07:12] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 22 21:07:12 dining-room volumio-remote-updater[618]: [2025-12-22 21:07:12] [error] handle_connect error: Underlying Transport Error Dec 22 21:07:12 dining-room systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 22 21:07:12 dining-room systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Dec 22 21:07:12 dining-room systemd[1]: Started dynamicswap service. Dec 22 21:07:12 dining-room systemd[1]: Stopped Volumio Backend Module. Dec 22 21:07:12 dining-room systemd[1]: Started Volumio Backend Module. Dec 22 21:07:12 dining-room systemd[1]: dynamicswap.service: Succeeded. Dec 22 21:07:12 dining-room nmbd[720]: [2025/12/22 21:07:12.794804, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Dec 22 21:07:12 dining-room nmbd[720]: reload_interfaces: No subnets to listen to. Waiting.. Dec 22 21:07:14 dining-room wpa_supplicant[873]: wlan0: Failed to initiate sched scan Dec 22 21:07:14 dining-room volumio[8862]: info: ------------------------------------------- Dec 22 21:07:14 dining-room volumio[8862]: info: ----- Volumio3 ---- Dec 22 21:07:14 dining-room volumio[8862]: info: ------------------------------------------- Dec 22 21:07:14 dining-room volumio[8862]: info: ----- System startup ---- Dec 22 21:07:14 dining-room volumio[8862]: info: ------------------------------------------- Dec 22 21:07:15 dining-room volumio[8862]: info: MYVOLUMIO Environment detected Dec 22 21:07:15 dining-room volumio[8862]: info: Plugin folders cleanup Dec 22 21:07:15 dining-room volumio[8862]: info: Scanning into folder /volumio/app/plugins/ Dec 22 21:07:15 dining-room volumio[8862]: info: Scanning category audio_interface Dec 22 21:07:15 dining-room volumio[8862]: info: Scanning category miscellanea Dec 22 21:07:15 dining-room volumio[8862]: info: Scanning category music_service Dec 22 21:07:15 dining-room volumio[8862]: info: Scanning category plugins.json Dec 22 21:07:15 dining-room volumio[8862]: info: Scanning category system_controller Dec 22 21:07:15 dining-room volumio[8862]: info: Scanning category user_interface Dec 22 21:07:15 dining-room volumio[8862]: info: Scanning into folder /data/plugins/ Dec 22 21:07:15 dining-room volumio[8862]: info: Scanning category music_service Dec 22 21:07:15 dining-room volumio[8862]: info: Scanning category touch_display Dec 22 21:07:15 dining-room volumio[8862]: info: Scanning category user_interface Dec 22 21:07:15 dining-room volumio[8862]: info: Plugin folders cleanup completed Dec 22 21:07:15 dining-room volumio[8862]: info: ------------------------------------------- Dec 22 21:07:15 dining-room volumio[8862]: info: ----- Core plugins startup ---- Dec 22 21:07:15 dining-room volumio[8862]: info: ------------------------------------------- Dec 22 21:07:15 dining-room volumio[8862]: info: Loading plugins from folder /volumio/app/plugins/ Dec 22 21:07:15 dining-room volumio[8862]: info: Adding plugin upnp to MyMusic Plugins Dec 22 21:07:15 dining-room volumio[8862]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 22 21:07:15 dining-room volumio[8862]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 22 21:07:15 dining-room volumio[8862]: info: Loading plugins from folder /data/plugins/ Dec 22 21:07:15 dining-room volumio[8862]: info: Loading plugin "system"... Dec 22 21:07:15 dining-room volumio[8862]: info: Loading plugin "appearance"... Dec 22 21:07:16 dining-room volumio[8862]: info: Loading plugin "network"... Dec 22 21:07:16 dining-room volumio[8862]: info: Refreshing Cached IP Addresses Dec 22 21:07:16 dining-room sudo[8892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 21:07:16 dining-room sudo[8892]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:16 dining-room sudo[8892]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:16 dining-room sudo[8894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 21:07:16 dining-room sudo[8894]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:16 dining-room sudo[8894]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:16 dining-room volumio[8862]: info: Loading plugin "services"... Dec 22 21:07:16 dining-room volumio[8862]: info: Loading plugin "alsa_controller"... Dec 22 21:07:16 dining-room sudo[8903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 22 21:07:16 dining-room sudo[8903]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:16 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 21:07:16 dining-room volumio[8862]: info: Loading plugin "wizard"... Dec 22 21:07:16 dining-room volumio[8862]: info: Loading plugin "networkfs"... Dec 22 21:07:16 dining-room volumio[8862]: info: Starting Udev Watcher for removable devices Dec 22 21:07:16 dining-room volumio[8862]: info: Ignoring mount for partition: boot Dec 22 21:07:16 dining-room volumio[8862]: info: Ignoring mount for partition: volumio Dec 22 21:07:16 dining-room volumio[8862]: info: Ignoring mount for partition: volumio_data Dec 22 21:07:16 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 21:07:16 dining-room volumio[8862]: info: Loading plugin "volumio_command_line_client"... Dec 22 21:07:16 dining-room volumio[8862]: info: Loading plugin "upnp"... Dec 22 21:07:16 dining-room volumio[8862]: info: [1766437636895] Starting Upmpd Daemon Dec 22 21:07:16 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 21:07:16 dining-room volumio[8862]: info: Loading plugin "my_music"... Dec 22 21:07:16 dining-room volumio[8862]: info: Loading plugin "mpd"... Dec 22 21:07:17 dining-room volumiologrotate[590]: ls: cannot access '/var/log/samba/log.wb-DINING': No such file or directory Dec 22 21:07:17 dining-room volumiologrotate[590]: ls: cannot access 'ROOM': No such file or directory Dec 22 21:07:17 dining-room volumio[8862]: info: Loading plugin "upnp_browser"... Dec 22 21:07:17 dining-room sudo[8903]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:17 dining-room volumio[8862]: info: Loading plugin "alarm-clock"... Dec 22 21:07:17 dining-room volumio[8862]: info: Loading plugin "airplay_emulation"... Dec 22 21:07:17 dining-room volumio[8862]: info: Starting Shairport Sync Dec 22 21:07:17 dining-room volumio[8862]: info: Loading plugin "last_100"... Dec 22 21:07:17 dining-room volumio[8862]: info: Loading plugin "webradio"... Dec 22 21:07:18 dining-room volumio[8862]: info: Loading plugin "i2s_dacs"... Dec 22 21:07:18 dining-room volumio[8862]: info: I2S DAC not set, start Auto-detection Dec 22 21:07:18 dining-room volumio[8862]: info: Loading plugin "volumiodiscovery"... Dec 22 21:07:18 dining-room volumio[8862]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 22 21:07:18 dining-room volumio[8862]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 21:07:18 dining-room volumio[8862]: *** WARNING *** For more information see Dec 22 21:07:18 dining-room node[8862]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 22 21:07:18 dining-room volumio[8862]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 22 21:07:18 dining-room volumio[8862]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 21:07:18 dining-room volumio[8862]: *** WARNING *** For more information see Dec 22 21:07:18 dining-room node[8862]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 21:07:18 dining-room node[8862]: *** WARNING *** For more information see Dec 22 21:07:18 dining-room node[8862]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 22 21:07:18 dining-room node[8862]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 21:07:18 dining-room node[8862]: *** WARNING *** For more information see Dec 22 21:07:18 dining-room volumio[8862]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 22 21:07:18 dining-room volumio[8862]: info: Discovery: Started advertising with name: Dining Room Dec 22 21:07:18 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 21:07:18 dining-room volumio[8862]: info: Loading plugin "squeezelite_mc"... Dec 22 21:07:19 dining-room volumio[8862]: info: Loading plugin "now_playing"... Dec 22 21:07:19 dining-room wpa_supplicant[873]: wlan0: Failed to initiate sched scan Dec 22 21:07:20 dining-room volumio[8862]: info: Loading plugin "outputs"... Dec 22 21:07:20 dining-room volumio[8862]: info: Loading plugin "albumart"... Dec 22 21:07:20 dining-room volumio[8862]: info: Plugin example_plugin is not enabled Dec 22 21:07:20 dining-room volumio[8862]: info: Loading plugin "inputs"... Dec 22 21:07:20 dining-room volumio[8862]: info: Loading plugin "updater_comm"... Dec 22 21:07:20 dining-room volumio[8862]: info: Plugin mpdemulation is not enabled Dec 22 21:07:20 dining-room volumio[8862]: info: Loading plugin "rest_api"... Dec 22 21:07:20 dining-room volumio[8862]: info: Loading plugin "websocket"... Dec 22 21:07:20 dining-room volumio[8862]: info: Starting Socket.io Server version 2.3.0 Dec 22 21:07:20 dining-room volumio[8862]: info: Loading plugin "touch_display"... Dec 22 21:07:21 dining-room volumio[8862]: Forking 3 albumart workers Dec 22 21:07:21 dining-room volumio[8862]: info: Applying required configuration parameters for plugin touch_display Dec 22 21:07:21 dining-room volumio[8862]: info: Loading i18n strings for locale en Dec 22 21:07:21 dining-room volumio[8862]: Updating browse sources language Dec 22 21:07:21 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:21 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 21:07:21 dining-room volumio[8862]: info: CoreCommandRouter::initPlayerControls Dec 22 21:07:21 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:21 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:21 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:21 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:21 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 21:07:21 dining-room volumio[8862]: Express server listening on port 3000 Dec 22 21:07:21 dining-room volumio[8862]: [Metrics] WebUI: 8s 205.34ms Dec 22 21:07:21 dining-room volumio[8862]: info: CoreStateMachine::resetVolumioState Dec 22 21:07:21 dining-room volumio[8862]: info: CoreStateMachine::getcurrentVolume Dec 22 21:07:22 dining-room volumio[8862]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 21:07:22 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:22 dining-room volumio[8862]: info: Cannot read play queue from file Dec 22 21:07:22 dining-room volumio[8862]: info: Volumio Network Manager: Network status updated: 0 Dec 22 21:07:22 dining-room volumio-remote-updater[618]: [2025-12-22 21:07:22] [connect] Successful connection Dec 22 21:07:22 dining-room volumio[8862]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 22 21:07:22 dining-room volumio[8862]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Dec 22 21:07:22 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Dec 22 21:07:22 dining-room volumio[8862]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Dec 22 21:07:22 dining-room volumio[8862]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 22 21:07:22 dining-room volumio[8862]: info: Listing playlists Dec 22 21:07:22 dining-room volumio[8862]: info: CoreCommandRouter::volumioGetQueue Dec 22 21:07:22 dining-room volumio[8862]: info: CoreStateMachine::getQueue Dec 22 21:07:22 dining-room volumio[8862]: info: CorePlayQueue::getQueue Dec 22 21:07:22 dining-room volumio[8862]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 21:07:22 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:22 dining-room volumio[8862]: info: CoreCommandRouter::volumioGetState Dec 22 21:07:22 dining-room volumio[8862]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:22 dining-room volumio[8862]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Dec 22 21:07:22 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Dec 22 21:07:22 dining-room volumio[8862]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Dec 22 21:07:22 dining-room volumio[8862]: info: Listing playlists Dec 22 21:07:22 dining-room volumio[8862]: info: CoreCommandRouter::volumioGetQueue Dec 22 21:07:22 dining-room volumio[8862]: info: CoreStateMachine::getQueue Dec 22 21:07:22 dining-room volumio[8862]: info: CorePlayQueue::getQueue Dec 22 21:07:22 dining-room volumio[8862]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 21:07:22 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:22 dining-room volumio[8862]: info: CoreCommandRouter::volumioGetState Dec 22 21:07:22 dining-room volumio[8862]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:22 dining-room volumio-remote-updater[618]: [2025-12-22 21:07:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766437642 101 Dec 22 21:07:22 dining-room volumio[8862]: Starting albumart workers Dec 22 21:07:22 dining-room volumio[8862]: 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: 3 Dec 22 21:07:22 dining-room volumio[8862]: Starting albumart workers Dec 22 21:07:22 dining-room volumio[8862]: info: VolumeController:: Volume=100 Mute =false Dec 22 21:07:22 dining-room volumio[8862]: info: CoreStateMachine::pushState Dec 22 21:07:22 dining-room volumio[8862]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:22 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 21:07:22 dining-room volumio[8862]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:22 dining-room volumio[8862]: info: CoreStateMachine::updateTrackBlock Dec 22 21:07:22 dining-room volumio[8862]: info: CorePlayQueue::getTrackBlock Dec 22 21:07:22 dining-room volumio[8862]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 21:07:22 dining-room volumio[8862]: Starting albumart workers Dec 22 21:07:22 dining-room volumio[8862]: info: CoreStateMachine::setRepeat null single undefined Dec 22 21:07:22 dining-room volumio[8862]: info: CoreStateMachine::pushState Dec 22 21:07:22 dining-room volumio[8862]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:22 dining-room volumio[8862]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:22 dining-room volumio[8862]: info: CoreStateMachine::setRandom null Dec 22 21:07:22 dining-room volumio[8862]: info: CoreStateMachine::pushState Dec 22 21:07:22 dining-room volumio[8862]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:22 dining-room volumio[8862]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:22 dining-room volumio[8862]: info: Setting Device type: Raspberry PI Dec 22 21:07:22 dining-room volumio[8862]: info: VolumeController:: Volume=100 Mute =false Dec 22 21:07:22 dining-room volumio[8862]: info: CoreStateMachine::pushState Dec 22 21:07:23 dining-room volumio[8862]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:23 dining-room volumio[8862]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:23 dining-room volumio[8862]: info: Completed loading Core Plugins Dec 22 21:07:23 dining-room volumio[8862]: info: Preparing to generate the ALSA configuration file Dec 22 21:07:23 dining-room volumio[8862]: info: Asound.conf file unchanged, so no further update is needed Dec 22 21:07:23 dining-room volumio[8862]: info: Output device has changed, restarting MPD Dec 22 21:07:23 dining-room volumio[8862]: info: Output device has changed, restarting Shairport Sync Dec 22 21:07:23 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:23 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:23 dining-room sudo[9008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 21:07:23 dining-room sudo[9008]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:23 dining-room sudo[9008]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:23 dining-room sudo[9010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 21:07:23 dining-room sudo[9010]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:23 dining-room volumio[8862]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 21:07:23 dining-room systemd[1]: Stopping Music Player Daemon... Dec 22 21:07:23 dining-room volumio[8862]: info: ___________ START PLUGINS ___________ Dec 22 21:07:23 dining-room volumio[8862]: info: ControllerMpd::onStart: Initializing MPD Dec 22 21:07:23 dining-room volumio[8862]: info: Creating MPD Configuration file Dec 22 21:07:23 dining-room systemd[1]: mpd.service: Succeeded. Dec 22 21:07:23 dining-room systemd[1]: Stopped Music Player Daemon. Dec 22 21:07:23 dining-room sudo[9018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 21:07:23 dining-room sudo[9018]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:23 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 21:07:23 dining-room volumio[8862]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 21:07:23 dining-room volumio[8862]: info: [1766437643431] CoreMusicLibrary::Adding element Media Servers Dec 22 21:07:23 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:23 dining-room systemd[1]: Starting Music Player Daemon... Dec 22 21:07:23 dining-room sudo[9018]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:23 dining-room sudo[9020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 21:07:23 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:23 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:23 dining-room sudo[9020]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:23 dining-room systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 22 21:07:23 dining-room systemd[1]: mpd.service: Succeeded. Dec 22 21:07:23 dining-room systemd[1]: Stopped Music Player Daemon. Dec 22 21:07:23 dining-room systemd[1]: Starting Music Player Daemon... Dec 22 21:07:23 dining-room volumio[8862]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 21:07:23 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:23 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:23 dining-room volumio[8862]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 21:07:23 dining-room volumio[8862]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 21:07:23 dining-room volumio[8862]: info: [1766437643684] CoreMusicLibrary::Adding element Last_100 Dec 22 21:07:23 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:23 dining-room volumio[8862]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 21:07:23 dining-room volumio[8862]: info: [1766437643691] CoreMusicLibrary::Adding element Webradio Dec 22 21:07:23 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:23 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 21:07:23 dining-room volumio[8862]: info: Initializing BBC Radios Dec 22 21:07:23 dining-room sudo[9026]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 21:07:23 dining-room sudo[9026]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:23 dining-room sudo[9026]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:23 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 21:07:23 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:23 dining-room volumio[8862]: info: [squeezelite_mc] Starting proxy server... Dec 22 21:07:23 dining-room volumio[8862]: info: [now-playing] ConfigUpdater: config is up to date. Dec 22 21:07:23 dining-room volumio[8862]: info: Loading i18n strings for locale en Dec 22 21:07:23 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 21:07:23 dining-room volumio[8862]: info: Volumio Calling Home Dec 22 21:07:24 dining-room volumio[8862]: info: [squeezelite_mc] Proxy server started on port 38203 Dec 22 21:07:24 dining-room sudo[9070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 22 21:07:24 dining-room sudo[9070]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:24 dining-room sudo[9072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 22 21:07:24 dining-room sudo[9072]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:24 dining-room sudo[9075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 22 21:07:24 dining-room sudo[9075]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:24 dining-room systemd[1]: Reloading. Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 21:07:24 dining-room volumio[8862]: info: [now-playing] App is listening on port 4004. Dec 22 21:07:24 dining-room volumio[8862]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Dec 22 21:07:24 dining-room volumio[8862]: Unhandled rejection Error: No sockets available, cannot start. Dec 22 21:07:24 dining-room volumio[8862]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 22 21:07:24 dining-room volumio[8862]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 22 21:07:24 dining-room volumio[8862]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 22 21:07:24 dining-room volumio[8862]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 22 21:07:24 dining-room volumio[8862]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 22 21:07:24 dining-room volumio[8862]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 22 21:07:24 dining-room volumio[8862]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 22 21:07:24 dining-room volumio[8862]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 22 21:07:24 dining-room volumio[8862]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 22 21:07:24 dining-room volumio[8862]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 22 21:07:24 dining-room volumio[8862]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 22 21:07:24 dining-room volumio[8862]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 22 21:07:24 dining-room volumio[8862]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 22 21:07:24 dining-room volumio[8862]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 22 21:07:24 dining-room volumio[8862]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 22 21:07:24 dining-room volumio[8862]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 22 21:07:24 dining-room volumio[8862]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Dec 22 21:07:24 dining-room volumio[8862]: info: MPD Permissions set Dec 22 21:07:24 dining-room volumio[8862]: info: MPD Permissions set Dec 22 21:07:24 dining-room volumio[8862]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: VolumeController:: Volume=100 Mute =false Dec 22 21:07:24 dining-room volumio[8862]: info: CoreStateMachine::pushState Dec 22 21:07:24 dining-room volumio[8862]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:24 dining-room systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 22 21:07:24 dining-room volumio[8862]: info: [squeezelite_mc] Server discovery started Dec 22 21:07:24 dining-room volumio[8862]: info: [squeezelite_mc] Player finder started Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 21:07:24 dining-room volumio[8862]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 22 21:07:24 dining-room volumio[8862]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 21:07:24 dining-room volumio[8862]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 22 21:07:24 dining-room volumio[8862]: at doSend (dgram.js:692:16) Dec 22 21:07:24 dining-room volumio[8862]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 22 21:07:24 dining-room volumio[8862]: at afterDns (dgram.js:638:5) Dec 22 21:07:24 dining-room volumio[8862]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 22 21:07:24 dining-room volumio[8862]: errno: -101, Dec 22 21:07:24 dining-room volumio[8862]: code: 'ENETUNREACH', Dec 22 21:07:24 dining-room volumio[8862]: syscall: 'send', Dec 22 21:07:24 dining-room volumio[8862]: address: '255.255.255.255', Dec 22 21:07:24 dining-room volumio[8862]: port: 3483 Dec 22 21:07:24 dining-room volumio[8862]: } Dec 22 21:07:24 dining-room volumio[8862]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 21:07:25 dining-room sudo[9075]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:25 dining-room systemd[1]: Reloading. Dec 22 21:07:25 dining-room sudo[9070]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:25 dining-room wpa_supplicant[873]: wlan0: Failed to initiate sched scan Dec 22 21:07:25 dining-room sudo[9126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-22 21:06 Dec 22 21:07:25 dining-room sudo[9126]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:25 dining-room sudo[9126]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:25 dining-room systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 22 21:07:25 dining-room mpd[9048]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 22 21:07:25 dining-room mpd[9048]: output: No 'audio_output' defined in config file Dec 22 21:07:25 dining-room volumio-remote-updater[618]: [2025-12-22 21:07:25] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 22 21:07:26 dining-room volumio-remote-updater[618]: [2025-12-22 21:07:25] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 22 21:07:26 dining-room mpd[9048]: output: Successfully detected a sndio audio device Dec 22 21:07:26 dining-room mpd[9048]: zeroconf: No global port, disabling zeroconf Dec 22 21:07:26 dining-room systemd[1]: Started Music Player Daemon. Dec 22 21:07:26 dining-room sudo[9010]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:26 dining-room sudo[9020]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:26 dining-room systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 22 21:07:26 dining-room sudo[9072]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:26 dining-room systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 22 21:07:26 dining-room systemd[1]: Started dynamicswap service. Dec 22 21:07:26 dining-room systemd[1]: dynamicswap.service: Succeeded. Dec 22 21:07:26 dining-room systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 22 21:07:26 dining-room systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Dec 22 21:07:26 dining-room systemd[1]: Stopped Volumio Backend Module. Dec 22 21:07:26 dining-room systemd[1]: Started Volumio Backend Module. Dec 22 21:07:26 dining-room systemd[1]: Started dynamicswap service. Dec 22 21:07:26 dining-room systemd[1]: dynamicswap.service: Succeeded. Dec 22 21:07:28 dining-room volumio[9141]: info: ------------------------------------------- Dec 22 21:07:28 dining-room volumio[9141]: info: ----- Volumio3 ---- Dec 22 21:07:28 dining-room volumio[9141]: info: ------------------------------------------- Dec 22 21:07:28 dining-room volumio[9141]: info: ----- System startup ---- Dec 22 21:07:28 dining-room volumio[9141]: info: ------------------------------------------- Dec 22 21:07:29 dining-room volumio[9141]: info: MYVOLUMIO Environment detected Dec 22 21:07:29 dining-room volumio[9141]: info: Plugin folders cleanup Dec 22 21:07:29 dining-room volumio[9141]: info: Scanning into folder /volumio/app/plugins/ Dec 22 21:07:29 dining-room volumio[9141]: info: Scanning category audio_interface Dec 22 21:07:29 dining-room volumio[9141]: info: Scanning category miscellanea Dec 22 21:07:29 dining-room volumio[9141]: info: Scanning category music_service Dec 22 21:07:29 dining-room volumio[9141]: info: Scanning category plugins.json Dec 22 21:07:29 dining-room volumio[9141]: info: Scanning category system_controller Dec 22 21:07:29 dining-room volumio[9141]: info: Scanning category user_interface Dec 22 21:07:29 dining-room volumio[9141]: info: Scanning into folder /data/plugins/ Dec 22 21:07:29 dining-room volumio[9141]: info: Scanning category music_service Dec 22 21:07:29 dining-room volumio[9141]: info: Scanning category touch_display Dec 22 21:07:29 dining-room volumio[9141]: info: Scanning category user_interface Dec 22 21:07:29 dining-room volumio[9141]: info: Plugin folders cleanup completed Dec 22 21:07:29 dining-room volumio[9141]: info: ------------------------------------------- Dec 22 21:07:29 dining-room volumio[9141]: info: ----- Core plugins startup ---- Dec 22 21:07:29 dining-room volumio[9141]: info: ------------------------------------------- Dec 22 21:07:29 dining-room volumio[9141]: info: Loading plugins from folder /volumio/app/plugins/ Dec 22 21:07:29 dining-room volumio[9141]: info: Adding plugin upnp to MyMusic Plugins Dec 22 21:07:29 dining-room volumio[9141]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 22 21:07:29 dining-room volumio[9141]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 22 21:07:29 dining-room volumio[9141]: info: Loading plugins from folder /data/plugins/ Dec 22 21:07:29 dining-room volumio[9141]: info: Loading plugin "system"... Dec 22 21:07:29 dining-room volumio[9141]: info: Loading plugin "appearance"... Dec 22 21:07:30 dining-room volumio[9141]: info: Loading plugin "network"... Dec 22 21:07:30 dining-room volumio[9141]: info: Refreshing Cached IP Addresses Dec 22 21:07:30 dining-room sudo[9169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 21:07:30 dining-room sudo[9169]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:30 dining-room sudo[9169]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:30 dining-room sudo[9171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 21:07:30 dining-room sudo[9171]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:30 dining-room sudo[9171]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:30 dining-room volumio[9141]: info: Loading plugin "services"... Dec 22 21:07:30 dining-room volumio[9141]: info: Loading plugin "alsa_controller"... Dec 22 21:07:30 dining-room sudo[9180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 22 21:07:30 dining-room sudo[9180]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:30 dining-room sudo[9180]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:31 dining-room volumio-remote-updater[618]: [2025-12-22 21:07:31] [connect] Successful connection Dec 22 21:07:31 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 21:07:31 dining-room volumio[9141]: info: Loading plugin "wizard"... Dec 22 21:07:31 dining-room volumio[9141]: info: Loading plugin "networkfs"... Dec 22 21:07:31 dining-room volumio[9141]: info: Starting Udev Watcher for removable devices Dec 22 21:07:31 dining-room volumio[9141]: info: Ignoring mount for partition: boot Dec 22 21:07:31 dining-room volumio[9141]: info: Ignoring mount for partition: volumio Dec 22 21:07:31 dining-room volumio[9141]: info: Ignoring mount for partition: volumio_data Dec 22 21:07:31 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 21:07:31 dining-room volumio[9141]: info: Loading plugin "volumio_command_line_client"... Dec 22 21:07:31 dining-room volumio[9141]: info: Loading plugin "upnp"... Dec 22 21:07:31 dining-room volumio[9141]: info: [1766437651142] Starting Upmpd Daemon Dec 22 21:07:31 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 21:07:31 dining-room volumio[9141]: info: Loading plugin "my_music"... Dec 22 21:07:31 dining-room volumio[9141]: info: Loading plugin "mpd"... Dec 22 21:07:31 dining-room wpa_supplicant[873]: wlan0: Failed to initiate sched scan Dec 22 21:07:31 dining-room volumio[9141]: info: Loading plugin "upnp_browser"... Dec 22 21:07:31 dining-room volumio[9141]: info: Loading plugin "alarm-clock"... Dec 22 21:07:32 dining-room volumio[9141]: info: Loading plugin "airplay_emulation"... Dec 22 21:07:32 dining-room volumio[9141]: info: Starting Shairport Sync Dec 22 21:07:32 dining-room volumio[9141]: info: Loading plugin "last_100"... Dec 22 21:07:32 dining-room volumio[9141]: info: Loading plugin "webradio"... Dec 22 21:07:32 dining-room volumio[9141]: info: Loading plugin "i2s_dacs"... Dec 22 21:07:32 dining-room volumio[9141]: info: I2S DAC not set, start Auto-detection Dec 22 21:07:32 dining-room volumio[9141]: info: Loading plugin "volumiodiscovery"... Dec 22 21:07:32 dining-room volumio[9141]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 22 21:07:32 dining-room volumio[9141]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 21:07:32 dining-room volumio[9141]: *** WARNING *** For more information see Dec 22 21:07:32 dining-room node[9141]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 22 21:07:32 dining-room volumio[9141]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 22 21:07:32 dining-room volumio[9141]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 21:07:32 dining-room volumio[9141]: *** WARNING *** For more information see Dec 22 21:07:32 dining-room node[9141]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 21:07:32 dining-room node[9141]: *** WARNING *** For more information see Dec 22 21:07:32 dining-room node[9141]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 22 21:07:32 dining-room node[9141]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 21:07:32 dining-room node[9141]: *** WARNING *** For more information see Dec 22 21:07:32 dining-room volumio[9141]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 22 21:07:32 dining-room volumio[9141]: info: Discovery: Started advertising with name: Dining Room Dec 22 21:07:32 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 21:07:32 dining-room volumio[9141]: info: Loading plugin "squeezelite_mc"... Dec 22 21:07:33 dining-room volumio[9141]: info: Loading plugin "now_playing"... Dec 22 21:07:34 dining-room volumio[9141]: info: Loading plugin "outputs"... Dec 22 21:07:34 dining-room volumio[9141]: info: Loading plugin "albumart"... Dec 22 21:07:34 dining-room volumio[9141]: info: Plugin example_plugin is not enabled Dec 22 21:07:34 dining-room volumio[9141]: info: Loading plugin "inputs"... Dec 22 21:07:34 dining-room volumio[9141]: info: Loading plugin "updater_comm"... Dec 22 21:07:35 dining-room volumio[9141]: info: Plugin mpdemulation is not enabled Dec 22 21:07:35 dining-room volumio[9141]: info: Loading plugin "rest_api"... Dec 22 21:07:35 dining-room volumio[9141]: info: Loading plugin "websocket"... Dec 22 21:07:35 dining-room volumio[9141]: info: Starting Socket.io Server version 2.3.0 Dec 22 21:07:35 dining-room volumio[9141]: info: Loading plugin "touch_display"... Dec 22 21:07:35 dining-room volumio[9141]: Forking 3 albumart workers Dec 22 21:07:35 dining-room volumio[9141]: info: Applying required configuration parameters for plugin touch_display Dec 22 21:07:35 dining-room volumio[9141]: info: Loading i18n strings for locale en Dec 22 21:07:35 dining-room volumio[9141]: Updating browse sources language Dec 22 21:07:35 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::initPlayerControls Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 21:07:36 dining-room volumio[9141]: Express server listening on port 3000 Dec 22 21:07:36 dining-room volumio[9141]: [Metrics] WebUI: 8s 146.03ms Dec 22 21:07:36 dining-room volumio[9141]: info: CoreStateMachine::resetVolumioState Dec 22 21:07:36 dining-room volumio[9141]: info: CoreStateMachine::getcurrentVolume Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:36 dining-room volumio[9141]: info: Cannot read play queue from file Dec 22 21:07:36 dining-room volumio[9141]: info: Volumio Network Manager: Network status updated: 0 Dec 22 21:07:36 dining-room volumio[9141]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 22 21:07:36 dining-room volumio[9141]: wlan0 Interface doesn't support scanning : Device or resource busy Dec 22 21:07:36 dining-room volumio[9141]: info: Cannot use regular scanning, forcing with ap-force Dec 22 21:07:36 dining-room sudo[9232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 22 21:07:36 dining-room sudo[9232]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:36 dining-room sudo[9232]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:36 dining-room volumio[9141]: command failed: Device or resource busy (-16) Dec 22 21:07:36 dining-room volumio[9141]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 22 21:07:36 dining-room volumio[9141]: command failed: Device or resource busy (-16) Dec 22 21:07:36 dining-room volumio[9141]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 22 21:07:36 dining-room volumio[9141]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Dec 22 21:07:36 dining-room volumio[9141]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Dec 22 21:07:36 dining-room volumio[9141]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 22 21:07:36 dining-room volumio[9141]: Starting albumart workers Dec 22 21:07:36 dining-room volumio[9141]: Starting albumart workers Dec 22 21:07:36 dining-room wpa_supplicant[873]: wlan0: Failed to initiate sched scan Dec 22 21:07:36 dining-room volumio[9141]: info: Listing playlists Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::volumioGetQueue Dec 22 21:07:36 dining-room volumio[9141]: info: CoreStateMachine::getQueue Dec 22 21:07:36 dining-room volumio[9141]: info: CorePlayQueue::getQueue Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::volumioGetState Dec 22 21:07:36 dining-room volumio[9141]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:36 dining-room volumio[9141]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Dec 22 21:07:36 dining-room volumio[9141]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Dec 22 21:07:36 dining-room volumio[9141]: info: Listing playlists Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::volumioGetQueue Dec 22 21:07:36 dining-room volumio[9141]: info: CoreStateMachine::getQueue Dec 22 21:07:36 dining-room volumio[9141]: info: CorePlayQueue::getQueue Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::volumioGetState Dec 22 21:07:36 dining-room volumio[9141]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:36 dining-room volumio[9141]: info: VolumeController:: Volume=100 Mute =false Dec 22 21:07:36 dining-room volumio[9141]: Starting albumart workers Dec 22 21:07:36 dining-room volumio[9141]: info: CoreStateMachine::pushState Dec 22 21:07:36 dining-room volumio[9141]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:36 dining-room volumio[9141]: info: CoreStateMachine::updateTrackBlock Dec 22 21:07:36 dining-room volumio[9141]: info: CorePlayQueue::getTrackBlock Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 21:07:36 dining-room volumio[9141]: info: CoreStateMachine::setRepeat null single undefined Dec 22 21:07:36 dining-room volumio[9141]: info: CoreStateMachine::pushState Dec 22 21:07:36 dining-room volumio[9141]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:36 dining-room volumio[9141]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:37 dining-room volumio[9141]: info: CoreStateMachine::setRandom null Dec 22 21:07:37 dining-room volumio[9141]: info: CoreStateMachine::pushState Dec 22 21:07:37 dining-room volumio[9141]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:37 dining-room volumio[9141]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:37 dining-room volumio[9141]: info: Setting Device type: Raspberry PI Dec 22 21:07:37 dining-room volumio[9141]: info: VolumeController:: Volume=100 Mute =false Dec 22 21:07:37 dining-room volumio[9141]: info: CoreStateMachine::pushState Dec 22 21:07:37 dining-room volumio[9141]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:37 dining-room volumio[9141]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:37 dining-room volumio[9141]: info: Completed loading Core Plugins Dec 22 21:07:37 dining-room volumio[9141]: info: Preparing to generate the ALSA configuration file Dec 22 21:07:37 dining-room volumio[9141]: info: Asound.conf file unchanged, so no further update is needed Dec 22 21:07:37 dining-room volumio[9141]: info: Output device has changed, restarting MPD Dec 22 21:07:37 dining-room sudo[9245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 21:07:37 dining-room sudo[9245]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:37 dining-room volumio[9141]: info: Output device has changed, restarting Shairport Sync Dec 22 21:07:37 dining-room sudo[9245]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:37 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:37 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:37 dining-room sudo[9248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 21:07:37 dining-room sudo[9248]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:37 dining-room systemd[1]: Stopping Music Player Daemon... Dec 22 21:07:37 dining-room systemd[1]: mpd.service: Succeeded. Dec 22 21:07:37 dining-room volumio[9141]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 21:07:37 dining-room systemd[1]: Stopped Music Player Daemon. Dec 22 21:07:37 dining-room volumio[9141]: info: ___________ START PLUGINS ___________ Dec 22 21:07:37 dining-room volumio[9141]: info: ControllerMpd::onStart: Initializing MPD Dec 22 21:07:37 dining-room volumio[9141]: info: Creating MPD Configuration file Dec 22 21:07:37 dining-room sudo[9255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 21:07:37 dining-room sudo[9255]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:37 dining-room systemd[1]: Starting Music Player Daemon... Dec 22 21:07:37 dining-room sudo[9255]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:37 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 21:07:37 dining-room volumio[9141]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 21:07:37 dining-room volumio[9141]: info: [1766437657715] CoreMusicLibrary::Adding element Media Servers Dec 22 21:07:37 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:37 dining-room sudo[9259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 21:07:37 dining-room sudo[9259]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:37 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:37 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:37 dining-room systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 22 21:07:37 dining-room systemd[1]: mpd.service: Succeeded. Dec 22 21:07:37 dining-room systemd[1]: Stopped Music Player Daemon. Dec 22 21:07:37 dining-room systemd[1]: Starting Music Player Daemon... Dec 22 21:07:37 dining-room volumio[9141]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 21:07:37 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:37 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 21:07:38 dining-room volumio[9141]: info: [1766437658029] CoreMusicLibrary::Adding element Last_100 Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:38 dining-room sudo[9269]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 21:07:38 dining-room sudo[9269]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 21:07:38 dining-room volumio[9141]: info: [1766437658038] CoreMusicLibrary::Adding element Webradio Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 21:07:38 dining-room volumio[9141]: info: Initializing BBC Radios Dec 22 21:07:38 dining-room sudo[9269]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: [squeezelite_mc] Starting proxy server... Dec 22 21:07:38 dining-room volumio[9141]: info: [now-playing] ConfigUpdater: config is up to date. Dec 22 21:07:38 dining-room volumio[9141]: info: Loading i18n strings for locale en Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 21:07:38 dining-room volumio[9141]: info: Volumio Calling Home Dec 22 21:07:38 dining-room volumio[9141]: info: [squeezelite_mc] Proxy server started on port 38159 Dec 22 21:07:38 dining-room sudo[9308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 22 21:07:38 dining-room sudo[9308]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:38 dining-room sudo[9311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 22 21:07:38 dining-room sudo[9311]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:38 dining-room sudo[9313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 22 21:07:38 dining-room sudo[9308]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:38 dining-room sudo[9313]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:38 dining-room systemd[1]: Reloading. Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 21:07:38 dining-room volumio[9141]: info: [now-playing] App is listening on port 4004. Dec 22 21:07:38 dining-room volumio[9141]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Dec 22 21:07:38 dining-room volumio[9141]: Unhandled rejection Error: No sockets available, cannot start. Dec 22 21:07:38 dining-room volumio[9141]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 22 21:07:38 dining-room volumio[9141]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 22 21:07:38 dining-room volumio[9141]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 22 21:07:38 dining-room volumio[9141]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 22 21:07:38 dining-room volumio[9141]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 22 21:07:38 dining-room volumio[9141]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 22 21:07:38 dining-room volumio[9141]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 22 21:07:38 dining-room volumio[9141]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 22 21:07:38 dining-room volumio[9141]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 22 21:07:38 dining-room volumio[9141]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 22 21:07:38 dining-room volumio[9141]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 22 21:07:38 dining-room volumio[9141]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 22 21:07:38 dining-room volumio[9141]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 22 21:07:38 dining-room volumio[9141]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 22 21:07:38 dining-room volumio[9141]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 22 21:07:38 dining-room volumio[9141]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 22 21:07:38 dining-room volumio[9141]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Dec 22 21:07:38 dining-room volumio[9141]: info: touch_display: systemctl stop getty@tty1.service succeeded. Dec 22 21:07:38 dining-room volumio[9141]: info: MPD Permissions set Dec 22 21:07:38 dining-room volumio[9141]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:38 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:39 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:39 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:39 dining-room volumio[9141]: info: VolumeController:: Volume=100 Mute =false Dec 22 21:07:39 dining-room volumio[9141]: info: CoreStateMachine::pushState Dec 22 21:07:39 dining-room volumio[9141]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:39 dining-room volumio[9141]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:39 dining-room systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 22 21:07:39 dining-room volumio[9141]: info: [squeezelite_mc] Server discovery started Dec 22 21:07:39 dining-room volumio[9141]: info: [squeezelite_mc] Player finder started Dec 22 21:07:39 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:39 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:39 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:39 dining-room volumio[9141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 21:07:39 dining-room volumio[9141]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 22 21:07:39 dining-room volumio[9141]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 21:07:39 dining-room volumio[9141]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 22 21:07:39 dining-room volumio[9141]: at doSend (dgram.js:692:16) Dec 22 21:07:39 dining-room volumio[9141]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 22 21:07:39 dining-room volumio[9141]: at afterDns (dgram.js:638:5) Dec 22 21:07:39 dining-room volumio[9141]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 22 21:07:39 dining-room volumio[9141]: errno: -101, Dec 22 21:07:39 dining-room volumio[9141]: code: 'ENETUNREACH', Dec 22 21:07:39 dining-room volumio[9141]: syscall: 'send', Dec 22 21:07:39 dining-room volumio[9141]: address: '255.255.255.255', Dec 22 21:07:39 dining-room volumio[9141]: port: 3483 Dec 22 21:07:39 dining-room volumio[9141]: } Dec 22 21:07:39 dining-room volumio[9141]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 21:07:39 dining-room systemd[1]: Reloading. Dec 22 21:07:39 dining-room sudo[9311]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:40 dining-room sudo[9364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-22 21:06 Dec 22 21:07:40 dining-room sudo[9364]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:40 dining-room systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 22 21:07:40 dining-room sudo[9364]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:40 dining-room mpd[9293]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 22 21:07:40 dining-room mpd[9293]: output: No 'audio_output' defined in config file Dec 22 21:07:40 dining-room mpd[9293]: output: Successfully detected a sndio audio device Dec 22 21:07:40 dining-room mpd[9293]: zeroconf: No global port, disabling zeroconf Dec 22 21:07:40 dining-room systemd[1]: Started Music Player Daemon. Dec 22 21:07:40 dining-room systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 22 21:07:40 dining-room sudo[9259]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:40 dining-room sudo[9313]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:40 dining-room sudo[9248]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:40 dining-room systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 22 21:07:40 dining-room systemd[1]: Started dynamicswap service. Dec 22 21:07:40 dining-room systemd[1]: dynamicswap.service: Succeeded. Dec 22 21:07:41 dining-room systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 22 21:07:41 dining-room systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Dec 22 21:07:41 dining-room systemd[1]: Started dynamicswap service. Dec 22 21:07:41 dining-room systemd[1]: Stopped Volumio Backend Module. Dec 22 21:07:41 dining-room systemd[1]: Started Volumio Backend Module. Dec 22 21:07:41 dining-room systemd[1]: dynamicswap.service: Succeeded. Dec 22 21:07:42 dining-room wpa_supplicant[873]: wlan0: Failed to initiate sched scan Dec 22 21:07:43 dining-room volumio[9380]: info: ------------------------------------------- Dec 22 21:07:43 dining-room volumio[9380]: info: ----- Volumio3 ---- Dec 22 21:07:43 dining-room volumio[9380]: info: ------------------------------------------- Dec 22 21:07:43 dining-room volumio[9380]: info: ----- System startup ---- Dec 22 21:07:43 dining-room volumio[9380]: info: ------------------------------------------- Dec 22 21:07:43 dining-room volumio-remote-updater[618]: [2025-12-22 21:07:43] [connect] Successful connection Dec 22 21:07:44 dining-room volumio[9380]: info: MYVOLUMIO Environment detected Dec 22 21:07:44 dining-room volumio[9380]: info: Plugin folders cleanup Dec 22 21:07:44 dining-room volumio[9380]: info: Scanning into folder /volumio/app/plugins/ Dec 22 21:07:44 dining-room volumio[9380]: info: Scanning category audio_interface Dec 22 21:07:44 dining-room volumio[9380]: info: Scanning category miscellanea Dec 22 21:07:44 dining-room volumio[9380]: info: Scanning category music_service Dec 22 21:07:44 dining-room volumio[9380]: info: Scanning category plugins.json Dec 22 21:07:44 dining-room volumio[9380]: info: Scanning category system_controller Dec 22 21:07:44 dining-room volumio[9380]: info: Scanning category user_interface Dec 22 21:07:44 dining-room volumio[9380]: info: Scanning into folder /data/plugins/ Dec 22 21:07:44 dining-room volumio[9380]: info: Scanning category music_service Dec 22 21:07:44 dining-room volumio[9380]: info: Scanning category touch_display Dec 22 21:07:44 dining-room volumio[9380]: info: Scanning category user_interface Dec 22 21:07:44 dining-room volumio[9380]: info: Plugin folders cleanup completed Dec 22 21:07:44 dining-room volumio[9380]: info: ------------------------------------------- Dec 22 21:07:44 dining-room volumio[9380]: info: ----- Core plugins startup ---- Dec 22 21:07:44 dining-room volumio[9380]: info: ------------------------------------------- Dec 22 21:07:44 dining-room volumio[9380]: info: Loading plugins from folder /volumio/app/plugins/ Dec 22 21:07:44 dining-room volumio[9380]: info: Adding plugin upnp to MyMusic Plugins Dec 22 21:07:44 dining-room volumio[9380]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 22 21:07:44 dining-room volumio[9380]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 22 21:07:44 dining-room volumio[9380]: info: Loading plugins from folder /data/plugins/ Dec 22 21:07:44 dining-room volumio[9380]: info: Loading plugin "system"... Dec 22 21:07:44 dining-room volumio[9380]: info: Loading plugin "appearance"... Dec 22 21:07:45 dining-room volumio[9380]: info: Loading plugin "network"... Dec 22 21:07:45 dining-room volumio[9380]: info: Refreshing Cached IP Addresses Dec 22 21:07:45 dining-room sudo[9406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 21:07:45 dining-room sudo[9406]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:45 dining-room sudo[9406]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:45 dining-room sudo[9408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 21:07:45 dining-room sudo[9408]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:45 dining-room sudo[9408]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:45 dining-room volumio[9380]: info: Loading plugin "services"... Dec 22 21:07:45 dining-room volumio[9380]: info: Loading plugin "alsa_controller"... Dec 22 21:07:45 dining-room sudo[9418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 22 21:07:45 dining-room sudo[9418]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:45 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 21:07:45 dining-room volumio[9380]: info: Loading plugin "wizard"... Dec 22 21:07:45 dining-room volumio[9380]: info: Loading plugin "networkfs"... Dec 22 21:07:45 dining-room volumio[9380]: info: Starting Udev Watcher for removable devices Dec 22 21:07:45 dining-room volumio[9380]: info: Ignoring mount for partition: boot Dec 22 21:07:45 dining-room volumio[9380]: info: Ignoring mount for partition: volumio Dec 22 21:07:45 dining-room volumio[9380]: info: Ignoring mount for partition: volumio_data Dec 22 21:07:45 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 21:07:45 dining-room volumio[9380]: info: Loading plugin "volumio_command_line_client"... Dec 22 21:07:45 dining-room volumio[9380]: info: Loading plugin "upnp"... Dec 22 21:07:45 dining-room volumio[9380]: info: [1766437665657] Starting Upmpd Daemon Dec 22 21:07:45 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 21:07:45 dining-room volumio[9380]: info: Loading plugin "my_music"... Dec 22 21:07:45 dining-room volumio[9380]: info: Loading plugin "mpd"... Dec 22 21:07:46 dining-room volumio[9380]: info: Loading plugin "upnp_browser"... Dec 22 21:07:46 dining-room sudo[9418]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:46 dining-room volumio[9380]: info: Loading plugin "alarm-clock"... Dec 22 21:07:46 dining-room volumio[9380]: info: Loading plugin "airplay_emulation"... Dec 22 21:07:46 dining-room volumio[9380]: info: Starting Shairport Sync Dec 22 21:07:46 dining-room volumio[9380]: info: Loading plugin "last_100"... Dec 22 21:07:46 dining-room volumio[9380]: info: Loading plugin "webradio"... Dec 22 21:07:46 dining-room volumio[9380]: info: Loading plugin "i2s_dacs"... Dec 22 21:07:46 dining-room volumio[9380]: info: I2S DAC not set, start Auto-detection Dec 22 21:07:46 dining-room volumio[9380]: info: Loading plugin "volumiodiscovery"... Dec 22 21:07:46 dining-room volumio[9380]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 22 21:07:46 dining-room volumio[9380]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 21:07:46 dining-room node[9380]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 22 21:07:46 dining-room volumio[9380]: *** WARNING *** For more information see Dec 22 21:07:46 dining-room volumio[9380]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 22 21:07:46 dining-room volumio[9380]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 21:07:46 dining-room volumio[9380]: *** WARNING *** For more information see Dec 22 21:07:46 dining-room node[9380]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 21:07:46 dining-room node[9380]: *** WARNING *** For more information see Dec 22 21:07:46 dining-room node[9380]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 22 21:07:46 dining-room node[9380]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 21:07:46 dining-room node[9380]: *** WARNING *** For more information see Dec 22 21:07:46 dining-room volumio[9380]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 22 21:07:46 dining-room volumio[9380]: info: Discovery: Started advertising with name: Dining Room Dec 22 21:07:46 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 21:07:46 dining-room volumio[9380]: info: Loading plugin "squeezelite_mc"... Dec 22 21:07:47 dining-room volumio[9380]: info: Loading plugin "now_playing"... Dec 22 21:07:48 dining-room wpa_supplicant[873]: wlan0: Trying to associate with SSID 'Garibaldi Lift Company' Dec 22 21:07:48 dining-room dhcpcd[885]: wlan0: carrier acquired Dec 22 21:07:48 dining-room wpa_supplicant[873]: wlan0: Associated with f0:9f:c2:7d:9a:77 Dec 22 21:07:48 dining-room wpa_supplicant[873]: wlan0: CTRL-EVENT-CONNECTED - Connection to f0:9f:c2:7d:9a:77 completed [id=0 id_str=] Dec 22 21:07:48 dining-room wpa_supplicant[873]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 22 21:07:48 dining-room wpa_supplicant[873]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=GB Dec 22 21:07:48 dining-room dhcpcd[885]: wlan0: IAID eb:b5:a8:c4 Dec 22 21:07:48 dining-room dhcpcd[885]: wlan0: carrier lost Dec 22 21:07:48 dining-room dhcpcd[885]: wlan0: carrier acquired Dec 22 21:07:48 dining-room dhcpcd[885]: wlan0: IAID eb:b5:a8:c4 Dec 22 21:07:48 dining-room dhcpcd[885]: wlan0: rebinding lease of 10.132.1.47 Dec 22 21:07:48 dining-room dhcpcd[885]: wlan0: soliciting an IPv6 router Dec 22 21:07:48 dining-room dhcpcd[885]: wlan0: probing address 10.132.1.47/24 Dec 22 21:07:49 dining-room volumio[9380]: info: Loading plugin "outputs"... Dec 22 21:07:49 dining-room volumio[9380]: info: Loading plugin "albumart"... Dec 22 21:07:49 dining-room volumio[9380]: info: Plugin example_plugin is not enabled Dec 22 21:07:49 dining-room volumio[9380]: info: Loading plugin "inputs"... Dec 22 21:07:49 dining-room volumio[9380]: info: Loading plugin "updater_comm"... Dec 22 21:07:49 dining-room volumio[9380]: info: Plugin mpdemulation is not enabled Dec 22 21:07:49 dining-room volumio[9380]: info: Loading plugin "rest_api"... Dec 22 21:07:49 dining-room volumio[9380]: info: Loading plugin "websocket"... Dec 22 21:07:49 dining-room volumio[9380]: info: Starting Socket.io Server version 2.3.0 Dec 22 21:07:49 dining-room volumio[9380]: info: Loading plugin "touch_display"... Dec 22 21:07:49 dining-room volumio[9380]: Forking 3 albumart workers Dec 22 21:07:50 dining-room volumio[9380]: info: Applying required configuration parameters for plugin touch_display Dec 22 21:07:50 dining-room volumio[9380]: info: Loading i18n strings for locale en Dec 22 21:07:50 dining-room volumio[9380]: Updating browse sources language Dec 22 21:07:50 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:50 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 21:07:50 dining-room volumio[9380]: info: CoreCommandRouter::initPlayerControls Dec 22 21:07:50 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:50 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:50 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:50 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:50 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:50 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:50 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:50 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:50 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 21:07:50 dining-room volumio[9380]: Express server listening on port 3000 Dec 22 21:07:50 dining-room volumio[9380]: [Metrics] WebUI: 8s 212.51ms Dec 22 21:07:50 dining-room volumio[9380]: info: CoreStateMachine::resetVolumioState Dec 22 21:07:50 dining-room volumio[9380]: info: CoreStateMachine::getcurrentVolume Dec 22 21:07:50 dining-room volumio[9380]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 21:07:50 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:50 dining-room volumio[9380]: info: Cannot read play queue from file Dec 22 21:07:50 dining-room volumio[9380]: info: Volumio Network Manager: Network status updated: 0 Dec 22 21:07:51 dining-room volumio[9380]: Starting albumart workers Dec 22 21:07:51 dining-room volumio[9380]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 22 21:07:51 dining-room volumio[9380]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Dec 22 21:07:51 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Dec 22 21:07:51 dining-room volumio[9380]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Dec 22 21:07:51 dining-room volumio[9380]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 22 21:07:51 dining-room volumio[9380]: Starting albumart workers Dec 22 21:07:51 dining-room volumio[9380]: info: Listing playlists Dec 22 21:07:51 dining-room volumio[9380]: info: CoreCommandRouter::volumioGetQueue Dec 22 21:07:51 dining-room volumio[9380]: info: CoreStateMachine::getQueue Dec 22 21:07:51 dining-room volumio[9380]: info: CorePlayQueue::getQueue Dec 22 21:07:51 dining-room volumio[9380]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 21:07:51 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:51 dining-room volumio[9380]: info: CoreCommandRouter::volumioGetState Dec 22 21:07:51 dining-room volumio[9380]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:51 dining-room volumio[9380]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Dec 22 21:07:51 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Dec 22 21:07:51 dining-room volumio[9380]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Dec 22 21:07:51 dining-room volumio[9380]: info: Listing playlists Dec 22 21:07:51 dining-room volumio[9380]: info: CoreCommandRouter::volumioGetQueue Dec 22 21:07:51 dining-room volumio[9380]: info: CoreStateMachine::getQueue Dec 22 21:07:51 dining-room volumio[9380]: info: CorePlayQueue::getQueue Dec 22 21:07:51 dining-room volumio[9380]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 21:07:51 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:51 dining-room volumio[9380]: info: CoreCommandRouter::volumioGetState Dec 22 21:07:51 dining-room volumio[9380]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:51 dining-room volumio[9380]: info: VolumeController:: Volume=100 Mute =false Dec 22 21:07:51 dining-room volumio[9380]: info: CoreStateMachine::pushState Dec 22 21:07:51 dining-room volumio[9380]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:51 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 21:07:51 dining-room volumio[9380]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:51 dining-room volumio[9380]: info: CoreStateMachine::updateTrackBlock Dec 22 21:07:51 dining-room volumio[9380]: info: CorePlayQueue::getTrackBlock Dec 22 21:07:51 dining-room volumio[9380]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 21:07:51 dining-room volumio[9380]: info: CoreStateMachine::setRepeat null single undefined Dec 22 21:07:51 dining-room volumio[9380]: info: CoreStateMachine::pushState Dec 22 21:07:51 dining-room volumio[9380]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:51 dining-room volumio[9380]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:51 dining-room volumio[9380]: info: CoreStateMachine::setRandom null Dec 22 21:07:51 dining-room volumio[9380]: info: CoreStateMachine::pushState Dec 22 21:07:51 dining-room volumio[9380]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:51 dining-room volumio[9380]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:51 dining-room volumio[9380]: info: Setting Device type: Raspberry PI Dec 22 21:07:51 dining-room volumio[9380]: Starting albumart workers Dec 22 21:07:51 dining-room volumio[9380]: info: VolumeController:: Volume=100 Mute =false Dec 22 21:07:51 dining-room volumio[9380]: info: CoreStateMachine::pushState Dec 22 21:07:51 dining-room volumio[9380]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:51 dining-room volumio[9380]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:51 dining-room volumio[9380]: info: Completed loading Core Plugins Dec 22 21:07:51 dining-room volumio[9380]: info: Preparing to generate the ALSA configuration file Dec 22 21:07:52 dining-room volumio[9380]: info: Asound.conf file unchanged, so no further update is needed Dec 22 21:07:52 dining-room volumio[9380]: info: Output device has changed, restarting MPD Dec 22 21:07:52 dining-room sudo[9495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 21:07:52 dining-room sudo[9495]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:52 dining-room volumio[9380]: info: Output device has changed, restarting Shairport Sync Dec 22 21:07:52 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:52 dining-room sudo[9495]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:52 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:52 dining-room sudo[9497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 21:07:52 dining-room sudo[9497]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:52 dining-room systemd[1]: Stopping Music Player Daemon... Dec 22 21:07:52 dining-room volumio[9380]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 21:07:52 dining-room systemd[1]: mpd.service: Succeeded. Dec 22 21:07:52 dining-room systemd[1]: Stopped Music Player Daemon. Dec 22 21:07:52 dining-room volumio[9380]: info: ___________ START PLUGINS ___________ Dec 22 21:07:52 dining-room volumio[9380]: info: ControllerMpd::onStart: Initializing MPD Dec 22 21:07:52 dining-room volumio[9380]: info: Creating MPD Configuration file Dec 22 21:07:52 dining-room systemd[1]: Starting Music Player Daemon... Dec 22 21:07:52 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 21:07:52 dining-room volumio[9380]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 21:07:52 dining-room volumio[9380]: info: [1766437672309] CoreMusicLibrary::Adding element Media Servers Dec 22 21:07:52 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:52 dining-room sudo[9505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 21:07:52 dining-room sudo[9505]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:52 dining-room sudo[9505]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:52 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:52 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:52 dining-room sudo[9507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 21:07:52 dining-room sudo[9507]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:52 dining-room sudo[9503]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 21:07:52 dining-room sudo[9503]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:52 dining-room sudo[9503]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:52 dining-room volumio[9380]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 21:07:52 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:52 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:52 dining-room systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 22 21:07:52 dining-room systemd[1]: mpd.service: Succeeded. Dec 22 21:07:52 dining-room systemd[1]: Stopped Music Player Daemon. Dec 22 21:07:52 dining-room systemd[1]: Starting Music Player Daemon... Dec 22 21:07:52 dining-room volumio[9380]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 21:07:52 dining-room volumio[9380]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 21:07:52 dining-room volumio[9380]: info: [1766437672576] CoreMusicLibrary::Adding element Last_100 Dec 22 21:07:52 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:52 dining-room volumio[9380]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 21:07:52 dining-room volumio[9380]: info: [1766437672581] CoreMusicLibrary::Adding element Webradio Dec 22 21:07:52 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 21:07:52 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 21:07:52 dining-room volumio[9380]: info: Initializing BBC Radios Dec 22 21:07:52 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 21:07:52 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 21:07:52 dining-room sudo[9526]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 21:07:52 dining-room sudo[9526]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:52 dining-room volumio[9380]: info: [squeezelite_mc] Starting proxy server... Dec 22 21:07:52 dining-room sudo[9526]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:52 dining-room volumio[9380]: info: [now-playing] ConfigUpdater: config is up to date. Dec 22 21:07:52 dining-room volumio[9380]: info: Loading i18n strings for locale en Dec 22 21:07:52 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 21:07:52 dining-room volumio[9380]: info: Volumio Calling Home Dec 22 21:07:52 dining-room volumio[9380]: info: [squeezelite_mc] Proxy server started on port 34329 Dec 22 21:07:52 dining-room sudo[9551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 22 21:07:52 dining-room sudo[9551]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:52 dining-room sudo[9553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 22 21:07:52 dining-room sudo[9553]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:52 dining-room sudo[9556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 22 21:07:52 dining-room sudo[9556]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:53 dining-room sudo[9551]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:53 dining-room systemd[1]: Reloading. Dec 22 21:07:53 dining-room volumio[9380]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 21:07:53 dining-room volumio[9380]: info: [now-playing] App is listening on port 4004. Dec 22 21:07:53 dining-room volumio[9380]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Dec 22 21:07:53 dining-room volumio[9380]: Unhandled rejection Error: No sockets available, cannot start. Dec 22 21:07:53 dining-room volumio[9380]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 22 21:07:53 dining-room volumio[9380]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 22 21:07:53 dining-room volumio[9380]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 22 21:07:53 dining-room volumio[9380]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 22 21:07:53 dining-room volumio[9380]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 22 21:07:53 dining-room volumio[9380]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 22 21:07:53 dining-room volumio[9380]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 22 21:07:53 dining-room volumio[9380]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 22 21:07:53 dining-room volumio[9380]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 22 21:07:53 dining-room volumio[9380]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 22 21:07:53 dining-room volumio[9380]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 22 21:07:53 dining-room volumio[9380]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 22 21:07:53 dining-room volumio[9380]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 22 21:07:53 dining-room volumio[9380]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 22 21:07:53 dining-room volumio[9380]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 22 21:07:53 dining-room volumio[9380]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 22 21:07:53 dining-room volumio[9380]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Dec 22 21:07:53 dining-room volumio[9380]: info: touch_display: systemctl stop getty@tty1.service succeeded. Dec 22 21:07:53 dining-room volumio[9380]: info: MPD Permissions set Dec 22 21:07:53 dining-room volumio[9380]: info: MPD Permissions set Dec 22 21:07:53 dining-room volumio[9380]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 22 21:07:53 dining-room volumio[9380]: info: VolumeController:: Volume=100 Mute =false Dec 22 21:07:53 dining-room volumio[9380]: info: CoreStateMachine::pushState Dec 22 21:07:53 dining-room volumio[9380]: info: CorePlayQueue::getTrack 0 Dec 22 21:07:53 dining-room volumio[9380]: info: CoreCommandRouter::volumioPushState Dec 22 21:07:53 dining-room volumio[9380]: info: [squeezelite_mc] Server discovery started Dec 22 21:07:53 dining-room volumio[9380]: info: [squeezelite_mc] Player finder started Dec 22 21:07:53 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:53 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:53 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 21:07:53 dining-room volumio[9380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 21:07:53 dining-room volumio[9380]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 22 21:07:53 dining-room volumio[9380]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 21:07:53 dining-room volumio[9380]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 22 21:07:53 dining-room volumio[9380]: at doSend (dgram.js:692:16) Dec 22 21:07:53 dining-room volumio[9380]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 22 21:07:53 dining-room volumio[9380]: at afterDns (dgram.js:638:5) Dec 22 21:07:53 dining-room volumio[9380]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 22 21:07:53 dining-room volumio[9380]: errno: -101, Dec 22 21:07:53 dining-room volumio[9380]: code: 'ENETUNREACH', Dec 22 21:07:53 dining-room volumio[9380]: syscall: 'send', Dec 22 21:07:53 dining-room volumio[9380]: address: '255.255.255.255', Dec 22 21:07:53 dining-room volumio[9380]: port: 3483 Dec 22 21:07:53 dining-room volumio[9380]: } Dec 22 21:07:53 dining-room volumio[9380]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 21:07:53 dining-room systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 22 21:07:54 dining-room sudo[9556]: pam_unix(sudo:session): session closed for user root Dec 22 21:07:54 dining-room systemd[1]: Reloading. Dec 22 21:07:54 dining-room sudo[9598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-22 21:06 Dec 22 21:07:54 dining-room sudo[9598]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 21:07:54 dining-room dhcpcd[885]: wlan0: leased 10.132.1.47 for 1800 seconds Dec 22 21:07:54 dining-room avahi-daemon[584]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.132.1.47. Dec 22 21:07:54 dining-room avahi-daemon[584]: New relevant interface wlan0.IPv4 for mDNS. Dec 22 21:07:54 dining-room avahi-daemon[584]: Registering new address record for 10.132.1.47 on wlan0.IPv4. Dec 22 21:07:54 dining-room dhcpcd[885]: wlan0: adding route to 10.132.1.0/24 Dec 22 21:07:54 dining-room dhcpcd[885]: wlan0: adding default route via 10.132.1.254 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"