Dec 10 17:03:37 volumio-4-home systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 10 17:03:37 volumio-4-home systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 10 17:03:37 volumio-4-home systemd[1]: setdatetime-helper.service: Consumed 1.667s CPU time. Dec 10 17:03:37 volumio-4-home ntpd[855]: IO: Listen normally on 3 eth0 192.168.1.15:123 Dec 10 17:03:37 volumio-4-home ntpd[855]: IO: new interface(s) found: waking up resolver Dec 10 17:03:37 volumio-4-home ntpd[855]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 10 17:03:37 volumio-4-home ntpd[855]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 10 17:03:37 volumio-4-home ntpd[855]: DNS: Pool taking: 193.42.63.23 Dec 10 17:03:37 volumio-4-home ntpd[855]: DNS: Pool taking: 54.38.114.34 Dec 10 17:03:37 volumio-4-home ntpd[855]: DNS: Pool taking: 82.65.248.56 Dec 10 17:03:37 volumio-4-home ntpd[855]: DNS: Pool taking: 172.232.44.156 Dec 10 17:03:37 volumio-4-home ntpd[855]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 10 17:03:38 volumio-4-home systemd[1]: Started smbd.service - Samba SMB Daemon. Dec 10 17:03:38 volumio-4-home systemd[1]: Reached target multi-user.target - Multi-User System. Dec 10 17:03:38 volumio-4-home systemd[1]: Reached target graphical.target - Graphical Interface. Dec 10 17:03:38 volumio-4-home systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Dec 10 17:03:38 volumio-4-home systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 10 17:03:38 volumio-4-home systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Dec 10 17:03:38 volumio-4-home systemd[1]: Startup finished in 15.438s (kernel) + 17.601s (userspace) = 33.039s. Dec 10 17:03:38 volumio-4-home ntpd[855]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 10 17:03:38 volumio-4-home ntpd[855]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 10 17:03:38 volumio-4-home ntpd[855]: DNS: Pool skipping: 54.38.114.34 Dec 10 17:03:38 volumio-4-home ntpd[855]: DNS: Pool taking: 31.58.102.164 Dec 10 17:03:38 volumio-4-home ntpd[855]: DNS: Pool taking: 79.143.250.33 Dec 10 17:03:38 volumio-4-home ntpd[855]: DNS: Pool taking: 38.143.19.16 Dec 10 17:03:38 volumio-4-home ntpd[855]: DNS: Pool taking: 2a01:e0a:431:b527::a123 Dec 10 17:03:38 volumio-4-home ntpd[855]: DNS: Pool taking: 2600:3c07::f03c:94ff:fee2:c558 Dec 10 17:03:38 volumio-4-home ntpd[855]: DNS: Pool taking: 2001:41d0:8:7a7d::1 Dec 10 17:03:38 volumio-4-home ntpd[855]: DNS: Pool taking: 2001:41d0:1004:2398:c3::123 Dec 10 17:03:38 volumio-4-home ntpd[855]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 10 17:03:39 volumio-4-home volumio-remote-updater[680]: [2025-12-10 17:03:39] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 10 17:03:39 volumio-4-home volumio-remote-updater[680]: [2025-12-10 17:03:39] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 10 17:03:39 volumio-4-home volumio-remote-updater[680]: [2025-12-10 17:03:39] [error] handle_connect error: Connection refused Dec 10 17:03:39 volumio-4-home ntpd[855]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 10 17:03:39 volumio-4-home ntpd[855]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 10 17:03:39 volumio-4-home ntpd[855]: DNS: Pool taking: 109.190.177.205 Dec 10 17:03:39 volumio-4-home ntpd[855]: DNS: Pool taking: 5.196.76.84 Dec 10 17:03:39 volumio-4-home ntpd[855]: DNS: Pool taking: 51.178.79.86 Dec 10 17:03:39 volumio-4-home ntpd[855]: DNS: Pool skipping: 172.232.44.156 Dec 10 17:03:39 volumio-4-home ntpd[855]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 10 17:03:40 volumio-4-home ntpd[855]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 10 17:03:40 volumio-4-home ntpd[855]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 10 17:03:40 volumio-4-home ntpd[855]: DNS: Pool taking: 162.159.200.123 Dec 10 17:03:40 volumio-4-home ntpd[855]: DNS: Pool taking: 82.64.81.218 Dec 10 17:03:40 volumio-4-home ntpd[855]: DNS: Pool taking: 185.254.100.25 Dec 10 17:03:40 volumio-4-home ntpd[855]: DNS: Pool skipping: 79.143.250.33 Dec 10 17:03:40 volumio-4-home ntpd[855]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 10 17:03:40 volumio-4-home volumio[1125]: info: ------------------------------------------- Dec 10 17:03:40 volumio-4-home volumio[1125]: info: ----- Volumio3 ---- Dec 10 17:03:40 volumio-4-home volumio[1125]: info: ------------------------------------------- Dec 10 17:03:40 volumio-4-home volumio[1125]: info: ----- System startup ---- Dec 10 17:03:40 volumio-4-home volumio[1125]: info: ------------------------------------------- Dec 10 17:03:41 volumio-4-home systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Dec 10 17:03:41 volumio-4-home systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 17:03:42 volumio-4-home systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 17:03:42 volumio-4-home upmpdcli[1241]: Could not open config: /tmp/upmpdcli.conf Dec 10 17:03:42 volumio-4-home systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 10 17:03:42 volumio-4-home systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 10 17:03:42 volumio-4-home volumio[1125]: info: MYVOLUMIO Environment detected Dec 10 17:03:42 volumio-4-home volumio[1125]: info: Plugin folders cleanup Dec 10 17:03:42 volumio-4-home volumio[1125]: info: Scanning into folder /volumio/app/plugins/ Dec 10 17:03:42 volumio-4-home volumio[1125]: info: Scanning category audio_interface Dec 10 17:03:42 volumio-4-home volumio[1125]: info: Scanning category miscellanea Dec 10 17:03:42 volumio-4-home volumio[1125]: info: Scanning category music_service Dec 10 17:03:42 volumio-4-home volumio[1125]: info: Scanning category plugins.json Dec 10 17:03:42 volumio-4-home volumio[1125]: info: Scanning category system_controller Dec 10 17:03:42 volumio-4-home volumio[1125]: info: Scanning category user_interface Dec 10 17:03:42 volumio-4-home volumio[1125]: info: Scanning into folder /data/plugins/ Dec 10 17:03:42 volumio-4-home volumio[1125]: info: Scanning category system_hardware Dec 10 17:03:42 volumio-4-home volumio[1125]: info: Plugin folders cleanup completed Dec 10 17:03:42 volumio-4-home volumio[1125]: info: ------------------------------------------- Dec 10 17:03:42 volumio-4-home volumio[1125]: info: ----- Core plugins startup ---- Dec 10 17:03:42 volumio-4-home volumio[1125]: info: ------------------------------------------- Dec 10 17:03:42 volumio-4-home volumio[1125]: info: Loading plugins from folder /volumio/app/plugins/ Dec 10 17:03:42 volumio-4-home volumio[1125]: info: Adding plugin upnp to MyMusic Plugins Dec 10 17:03:42 volumio-4-home volumio[1125]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 10 17:03:42 volumio-4-home volumio[1125]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 10 17:03:42 volumio-4-home volumio[1125]: info: Loading plugins from folder /data/plugins/ Dec 10 17:03:42 volumio-4-home volumio[1125]: info: Loading plugin "system"... Dec 10 17:03:42 volumio-4-home volumio[1125]: info: Loading plugin "appearance"... Dec 10 17:03:44 volumio-4-home ntpd[855]: CLOCK: time stepped by 0.448713 Dec 10 17:03:44 volumio-4-home ntpd[855]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 10 17:03:44 volumio-4-home volumio[1125]: info: Loading plugin "network"... Dec 10 17:03:44 volumio-4-home volumio[1125]: info: Refreshing Cached IP Addresses Dec 10 17:03:45 volumio-4-home sudo[1249]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 10 17:03:45 volumio-4-home sudo[1249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 17:03:45 volumio-4-home sudo[1252]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 10 17:03:45 volumio-4-home sudo[1252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 17:03:45 volumio-4-home sudo[1252]: pam_unix(sudo:session): session closed for user root Dec 10 17:03:45 volumio-4-home sudo[1249]: pam_unix(sudo:session): session closed for user root Dec 10 17:03:45 volumio-4-home volumio[1125]: info: Loading plugin "services"... Dec 10 17:03:45 volumio-4-home volumio[1125]: info: Loading plugin "alsa_controller"... Dec 10 17:03:45 volumio-4-home sudo[1260]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 10 17:03:45 volumio-4-home sudo[1260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 17:03:45 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 17:03:45 volumio-4-home volumio[1125]: info: Loading plugin "wizard"... Dec 10 17:03:45 volumio-4-home volumio[1125]: info: Loading plugin "networkfs"... Dec 10 17:03:45 volumio-4-home volumio[1125]: info: Starting Udev Watcher for removable devices Dec 10 17:03:45 volumio-4-home volumio[1125]: info: Ignoring mount for partition: boot Dec 10 17:03:45 volumio-4-home volumio[1125]: info: Ignoring mount for partition: volumio Dec 10 17:03:45 volumio-4-home volumio[1125]: info: Ignoring mount for partition: volumio_data Dec 10 17:03:45 volumio-4-home volumio[1125]: info: Mounting Device TOSHIBA_EXT Dec 10 17:03:45 volumio-4-home sudo[1288]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/TOSHIBA_EXT -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 10 17:03:45 volumio-4-home sudo[1288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 17:03:47 volumio-4-home sudo[1260]: pam_unix(sudo:session): session closed for user root Dec 10 17:03:48 volumio-4-home ntfs-3g[1291]: Version 2022.10.3 integrated FUSE 28 Dec 10 17:03:48 volumio-4-home ntfs-3g[1291]: Mounted /dev/sda1 (Read-Write, label "TOSHIBA EXT", NTFS 3.1) Dec 10 17:03:48 volumio-4-home ntfs-3g[1291]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 10 17:03:48 volumio-4-home ntfs-3g[1291]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Dec 10 17:03:48 volumio-4-home ntfs-3g[1291]: Global ownership and permissions enforced, configuration type 7 Dec 10 17:03:48 volumio-4-home sudo[1288]: pam_unix(sudo:session): session closed for user root Dec 10 17:03:48 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 10 17:03:48 volumio-4-home volumio[1125]: info: Loading plugin "volumio_command_line_client"... Dec 10 17:03:48 volumio-4-home volumio[1125]: info: Loading plugin "upnp"... Dec 10 17:03:48 volumio-4-home volumio[1125]: info: [1765382628476] Starting Upmpd Daemon Dec 10 17:03:48 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 10 17:03:48 volumio-4-home volumio[1125]: info: Loading plugin "my_music"... Dec 10 17:03:48 volumio-4-home volumio[1125]: info: Loading plugin "mpd"... Dec 10 17:03:49 volumio-4-home volumio[1125]: info: Loading plugin "upnp_browser"... Dec 10 17:03:49 volumio-4-home volumio-remote-updater[680]: [2025-12-10 17:03:49] [connect] Successful connection Dec 10 17:03:51 volumio-4-home volumio[1125]: info: Starting UPNP Browser Dec 10 17:03:51 volumio-4-home volumio[1125]: info: Loading plugin "alarm-clock"... Dec 10 17:03:52 volumio-4-home volumio[1125]: info: Loading plugin "airplay_emulation"... Dec 10 17:03:52 volumio-4-home volumio[1125]: info: Starting Shairport Sync Dec 10 17:03:52 volumio-4-home volumio[1125]: info: Loading plugin "last_100"... Dec 10 17:03:52 volumio-4-home volumio[1125]: info: Loading plugin "webradio"... Dec 10 17:03:52 volumio-4-home volumio[1125]: info: Loading plugin "i2s_dacs"... Dec 10 17:03:52 volumio-4-home volumio[1125]: info: Loading plugin "volumiodiscovery"... Dec 10 17:03:52 volumio-4-home volumio[1125]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 10 17:03:52 volumio-4-home volumio[1125]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 10 17:03:52 volumio-4-home volumio[1125]: *** WARNING *** For more information see Dec 10 17:03:52 volumio-4-home volumio[1125]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 10 17:03:52 volumio-4-home volumio[1125]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 10 17:03:52 volumio-4-home volumio[1125]: *** WARNING *** For more information see Dec 10 17:03:52 volumio-4-home node[1125]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 10 17:03:52 volumio-4-home node[1125]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 10 17:03:52 volumio-4-home node[1125]: *** WARNING *** For more information see Dec 10 17:03:52 volumio-4-home node[1125]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 10 17:03:52 volumio-4-home node[1125]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 10 17:03:52 volumio-4-home node[1125]: *** WARNING *** For more information see Dec 10 17:03:52 volumio-4-home volumio[1125]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 10 17:03:52 volumio-4-home volumio[1125]: info: Discovery: Started advertising with name: Volumio 4 home Dec 10 17:03:52 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 10 17:03:52 volumio-4-home volumio[1125]: info: Loading plugin "outputs"... Dec 10 17:03:52 volumio-4-home volumio[1125]: info: Loading plugin "albumart"... Dec 10 17:03:52 volumio-4-home volumio[1125]: info: Plugin example_plugin is not enabled Dec 10 17:03:52 volumio-4-home volumio[1125]: info: Loading plugin "inputs"... Dec 10 17:03:52 volumio-4-home volumio[1125]: info: Loading plugin "updater_comm"... Dec 10 17:03:52 volumio-4-home volumio[1125]: info: Plugin mpdemulation is not enabled Dec 10 17:03:52 volumio-4-home volumio[1125]: info: Loading plugin "rest_api"... Dec 10 17:03:52 volumio-4-home volumio[1125]: info: Loading plugin "websocket"... Dec 10 17:03:52 volumio-4-home volumio[1125]: info: Starting Socket.io Server version 1.7.4 Dec 10 17:03:52 volumio-4-home volumio[1125]: info: Loading plugin "audiophonicsonoff"... Dec 10 17:03:54 volumio-4-home volumio[1125]: info: Applying required configuration parameters for plugin audiophonicsonoff Dec 10 17:03:54 volumio-4-home volumio[1125]: info: Audiophonics on/off initiated Dec 10 17:03:54 volumio-4-home volumio[1125]: info: Loading i18n strings for locale fr Dec 10 17:03:54 volumio-4-home volumio[1125]: Updating browse sources language Dec 10 17:03:54 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 17:03:54 volumio-4-home systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 10 17:03:54 volumio-4-home volumio[1296]: Forking 3 albumart workers Dec 10 17:03:55 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 17:03:55 volumio-4-home volumio[1125]: info: CoreCommandRouter::initPlayerControls Dec 10 17:03:55 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:03:55 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:03:55 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:03:55 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:03:55 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:03:55 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:03:55 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:03:55 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:03:55 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 17:03:55 volumio-4-home volumio[1125]: Express server listening on port 3000 Dec 10 17:03:55 volumio-4-home volumio[1125]: [Metrics] WebUI: 15s 585.70ms Dec 10 17:03:55 volumio-4-home volumio[1125]: info: CoreStateMachine::resetVolumioState Dec 10 17:03:55 volumio-4-home volumio[1125]: info: CoreStateMachine::getcurrentVolume Dec 10 17:03:55 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 17:03:56 volumio-4-home volumio[1125]: info: Volumio Network Manager: Network status updated: 1 Dec 10 17:03:57 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 1 Dec 10 17:03:57 volumio-4-home volumio[1125]: Unable to parse: Dec 10 17:03:57 volumio-4-home volumio[1125]: Simple mixer control 'Analogue Playback Boost',0 Dec 10 17:03:57 volumio-4-home volumio[1125]: Capabilities: volume Dec 10 17:03:57 volumio-4-home volumio[1125]: Playback channels: Front Left - Front Right Dec 10 17:03:57 volumio-4-home volumio[1125]: Capture channels: Front Left - Front Right Dec 10 17:03:57 volumio-4-home volumio[1125]: Limits: 0 - 1 Dec 10 17:03:57 volumio-4-home volumio[1125]: Front Left: 0 [0%] [0.00dB] Dec 10 17:03:57 volumio-4-home volumio[1125]: Front Right: 0 [0%] [0.00dB] Dec 10 17:03:57 volumio-4-home volumio[1125]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 10 17:03:57 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 2 Dec 10 17:03:57 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 17:03:57 volumio-4-home systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 10 17:03:57 volumio-4-home systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 17:03:57 volumio-4-home volumio[1125]: info: Reloading queue from file Dec 10 17:03:57 volumio-4-home systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 17:03:57 volumio-4-home upmpdcli[1357]: Could not open config: /tmp/upmpdcli.conf Dec 10 17:03:57 volumio-4-home systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 10 17:03:57 volumio-4-home systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 10 17:03:58 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 3 Dec 10 17:03:58 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 4 Dec 10 17:03:58 volumio-4-home volumio[1125]: Unable to parse: Dec 10 17:03:58 volumio-4-home volumio[1125]: Simple mixer control 'Analogue Playback Boost',0 Dec 10 17:03:58 volumio-4-home volumio[1125]: Capabilities: volume Dec 10 17:03:58 volumio-4-home volumio[1125]: Playback channels: Front Left - Front Right Dec 10 17:03:58 volumio-4-home volumio[1125]: Capture channels: Front Left - Front Right Dec 10 17:03:58 volumio-4-home volumio[1125]: Limits: 0 - 1 Dec 10 17:03:58 volumio-4-home volumio[1125]: Front Left: 0 [0%] [0.00dB] Dec 10 17:03:58 volumio-4-home volumio[1125]: Front Right: 0 [0%] [0.00dB] Dec 10 17:03:58 volumio-4-home volumio[1125]: info: VolumeController:: Volume=undefined Mute =false Dec 10 17:03:58 volumio-4-home volumio[1125]: info: CoreStateMachine::pushState Dec 10 17:03:58 volumio-4-home volumio[1125]: info: CorePlayQueue::getTrack 0 Dec 10 17:03:58 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 17:03:58 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioPushState Dec 10 17:03:58 volumio-4-home volumio[1125]: info: CoreStateMachine::updateTrackBlock Dec 10 17:03:58 volumio-4-home volumio[1125]: info: CorePlayQueue::getTrackBlock Dec 10 17:03:58 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 17:03:58 volumio-4-home volumio[1125]: info: CoreStateMachine::setRepeat true single undefined Dec 10 17:03:58 volumio-4-home volumio[1125]: info: CoreStateMachine::pushState Dec 10 17:03:58 volumio-4-home volumio[1125]: info: CorePlayQueue::getTrack 0 Dec 10 17:03:58 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 17:03:58 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioPushState Dec 10 17:03:58 volumio-4-home volumio[1125]: info: CoreStateMachine::setRandom null Dec 10 17:03:58 volumio-4-home volumio[1125]: info: CoreStateMachine::pushState Dec 10 17:03:58 volumio-4-home volumio[1125]: info: CorePlayQueue::getTrack 0 Dec 10 17:03:58 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioPushState Dec 10 17:03:58 volumio-4-home volumio[1125]: info: Setting Device type: Raspberry PI Dec 10 17:03:58 volumio-4-home sudo[1367]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 10 17:03:58 volumio-4-home sudo[1366]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 10 17:03:58 volumio-4-home sudo[1366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 17:03:58 volumio-4-home sudo[1367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 17:03:58 volumio-4-home sudo[1366]: pam_unix(sudo:session): session closed for user root Dec 10 17:03:58 volumio-4-home sudo[1367]: pam_unix(sudo:session): session closed for user root Dec 10 17:03:59 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 5 Dec 10 17:03:59 volumio-4-home volumio[1125]: Unable to parse: Dec 10 17:03:59 volumio-4-home volumio[1125]: Simple mixer control 'Analogue Playback Boost',0 Dec 10 17:03:59 volumio-4-home volumio[1125]: Capabilities: volume Dec 10 17:03:59 volumio-4-home volumio[1125]: Playback channels: Front Left - Front Right Dec 10 17:03:59 volumio-4-home volumio[1125]: Capture channels: Front Left - Front Right Dec 10 17:03:59 volumio-4-home volumio[1125]: Limits: 0 - 1 Dec 10 17:03:59 volumio-4-home volumio[1125]: Front Left: 0 [0%] [0.00dB] Dec 10 17:03:59 volumio-4-home volumio[1125]: Front Right: 0 [0%] [0.00dB] Dec 10 17:03:59 volumio-4-home volumio[1125]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 10 17:03:59 volumio-4-home volumio[1125]: info: Completed loading Core Plugins Dec 10 17:03:59 volumio-4-home volumio[1125]: info: Preparing to generate the ALSA configuration file Dec 10 17:03:59 volumio-4-home systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 10 17:03:59 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 6 Dec 10 17:03:59 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 7 Dec 10 17:03:59 volumio-4-home volumio[1125]: Unable to parse: Dec 10 17:03:59 volumio-4-home volumio[1125]: Simple mixer control 'Analogue Playback Boost',0 Dec 10 17:03:59 volumio-4-home volumio[1125]: Capabilities: volume Dec 10 17:03:59 volumio-4-home volumio[1125]: Playback channels: Front Left - Front Right Dec 10 17:03:59 volumio-4-home volumio[1125]: Capture channels: Front Left - Front Right Dec 10 17:03:59 volumio-4-home volumio[1125]: Limits: 0 - 1 Dec 10 17:03:59 volumio-4-home volumio[1125]: Front Left: 0 [0%] [0.00dB] Dec 10 17:03:59 volumio-4-home volumio[1125]: Front Right: 0 [0%] [0.00dB] Dec 10 17:03:59 volumio-4-home volumio[1125]: info: VolumeController:: Volume=undefined Mute =false Dec 10 17:03:59 volumio-4-home volumio[1125]: info: CoreStateMachine::pushState Dec 10 17:03:59 volumio-4-home volumio[1125]: info: CorePlayQueue::getTrack 0 Dec 10 17:03:59 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioPushState Dec 10 17:04:00 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 8 Dec 10 17:04:00 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 9 Dec 10 17:04:00 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 10 Dec 10 17:04:00 volumio-4-home volumio[1125]: info: Discovery: adding 0ba31fa3-76ab-4849-b687-8cf6e8c67e03 Dec 10 17:04:00 volumio-4-home volumio[1125]: info: Discovery: Found device Volumio 4 home Dec 10 17:04:00 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioGetState Dec 10 17:04:00 volumio-4-home volumio[1125]: info: CorePlayQueue::getTrack 0 Dec 10 17:04:00 volumio-4-home volumio[1125]: info: Discovery: this is already registered, 0ba31fa3-76ab-4849-b687-8cf6e8c67e03 Dec 10 17:04:00 volumio-4-home volumio[1125]: info: Discovery: Found device Volumio 4 home Dec 10 17:04:00 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioGetState Dec 10 17:04:00 volumio-4-home volumio[1125]: info: CorePlayQueue::getTrack 0 Dec 10 17:04:00 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 11 Dec 10 17:04:00 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 12 Dec 10 17:04:00 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:00 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 13 Dec 10 17:04:00 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:00 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:00 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:00 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioGetVisibleSources Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioGetState Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CorePlayQueue::getTrack 0 Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 10 17:04:01 volumio-4-home volumio[1125]: info: Received Get System Info Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 10 17:04:01 volumio-4-home volumio[1125]: info: Discovery: Getting this device information Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioGetState Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CorePlayQueue::getTrack 0 Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioGetState Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CorePlayQueue::getTrack 0 Dec 10 17:04:01 volumio-4-home volumio[1125]: info: Listing playlists Dec 10 17:04:01 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:01 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 14 Dec 10 17:04:01 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 15 Dec 10 17:04:01 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 16 Dec 10 17:04:01 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 17 Dec 10 17:04:02 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 18 Dec 10 17:04:02 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 19 Dec 10 17:04:02 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 20 Dec 10 17:04:02 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 21 Dec 10 17:04:02 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 22 Dec 10 17:04:02 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 23 Dec 10 17:04:02 volumio-4-home sudo[1377]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 10 17:04:02 volumio-4-home sudo[1377]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 17:04:02 volumio-4-home volumio[1125]: info: Asound.conf file written Dec 10 17:04:03 volumio-4-home sudo[1381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 10 17:04:03 volumio-4-home sudo[1381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 17:04:03 volumio-4-home sudo[1381]: pam_unix(sudo:session): session closed for user root Dec 10 17:04:03 volumio-4-home volumio[1307]: Starting albumart workers Dec 10 17:04:03 volumio-4-home volumio[1125]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 10 17:04:03 volumio-4-home volumio[1125]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 10 17:04:03 volumio-4-home volumio[1125]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 10 17:04:03 volumio-4-home volumio[1125]: info: Output device has changed, restarting MPD Dec 10 17:04:03 volumio-4-home volumio[1125]: info: Output device has changed, restarting Shairport Sync Dec 10 17:04:03 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:03 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 17:04:03 volumio-4-home sudo[1387]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 17:04:03 volumio-4-home sudo[1387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 17:04:03 volumio-4-home sudo[1387]: pam_unix(sudo:session): session closed for user root Dec 10 17:04:03 volumio-4-home sudo[1389]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 17:04:03 volumio-4-home sudo[1389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 17:04:03 volumio-4-home volumio[1125]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 17:04:03 volumio-4-home volumio[1125]: info: ___________ START PLUGINS ___________ Dec 10 17:04:03 volumio-4-home volumio[1125]: info: ControllerMpd::onStart: Initializing MPD Dec 10 17:04:03 volumio-4-home volumio[1125]: info: Creating MPD Configuration file Dec 10 17:04:03 volumio-4-home volumio[1308]: Starting albumart workers Dec 10 17:04:03 volumio-4-home systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 17:04:03 volumio-4-home systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 17:04:04 volumio-4-home sudo[1401]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 17:04:04 volumio-4-home sudo[1401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 17:04:04 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 17:04:04 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 10 17:04:04 volumio-4-home volumio[1125]: info: [1765382644148] CoreMusicLibrary::Adding element Serveurs Média Dec 10 17:04:04 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 17:04:04 volumio-4-home sudo[1397]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 17:04:04 volumio-4-home sudo[1397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 17:04:04 volumio-4-home sudo[1397]: pam_unix(sudo:session): session closed for user root Dec 10 17:04:04 volumio-4-home systemd[1]: mpd.service: Deactivated successfully. Dec 10 17:04:04 volumio-4-home systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 17:04:04 volumio-4-home sudo[1399]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 17:04:04 volumio-4-home systemd[1]: mpd.socket: Deactivated successfully. Dec 10 17:04:04 volumio-4-home systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 17:04:04 volumio-4-home systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 17:04:04 volumio-4-home sudo[1399]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 17:04:04 volumio-4-home sudo[1399]: pam_unix(sudo:session): session closed for user root Dec 10 17:04:04 volumio-4-home systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 17:04:04 volumio-4-home volumio[1125]: info: UPNP Browser: Client initialized successfully Dec 10 17:04:04 volumio-4-home systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 17:04:04 volumio-4-home sudo[1406]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 17:04:04 volumio-4-home sudo[1406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 17:04:04 volumio-4-home sudo[1407]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 10 17:04:04 volumio-4-home sudo[1406]: pam_unix(sudo:session): session closed for user root Dec 10 17:04:04 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:04 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 17:04:04 volumio-4-home volumio[1309]: Starting albumart workers Dec 10 17:04:04 volumio-4-home volumio-remote-updater[680]: [2025-12-10 17:04:04] [connect] Successful connection Dec 10 17:04:04 volumio-4-home volumio[1125]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 17:04:04 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:04 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 17:04:04 volumio-4-home volumio[1125]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 17:04:04 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 10 17:04:04 volumio-4-home volumio[1125]: info: [1765382644933] CoreMusicLibrary::Adding element Last_100 Dec 10 17:04:04 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 17:04:04 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 10 17:04:04 volumio-4-home volumio[1125]: info: [1765382644940] CoreMusicLibrary::Adding element Webradio Dec 10 17:04:04 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 17:04:04 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 17:04:04 volumio-4-home volumio[1125]: info: Initializing BBC Radios Dec 10 17:04:05 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 17:04:05 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 17:04:05 volumio-4-home volumio[1125]: info: Configuring GPIO pins Dec 10 17:04:05 volumio-4-home volumio[1125]: info: Soft shutdown GPIO 516 binding... OK Dec 10 17:04:05 volumio-4-home volumio[1125]: info: Hardware button GPIO 529 binding... OK Dec 10 17:04:06 volumio-4-home volumio[1125]: info: Boot OK GPIO 534 binding... OK Dec 10 17:04:06 volumio-4-home volumio[1125]: info: Volumio Calling Home Dec 10 17:04:07 volumio-4-home systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 10 17:04:09 volumio-4-home systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 10 17:04:09 volumio-4-home systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 10 17:04:11 volumio-4-home volumio[1125]: info: MPD Permissions set Dec 10 17:04:11 volumio-4-home volumio[1125]: info: MPD Permissions set Dec 10 17:04:11 volumio-4-home volumio[1125]: info: Volumio called home Dec 10 17:04:11 volumio-4-home mpd[1408]: 2025-12-10T17:04:11 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 17:04:12 volumio-4-home systemd[1]: Started mpd.service - Music Player Daemon. Dec 10 17:04:12 volumio-4-home sudo[1389]: pam_unix(sudo:session): session closed for user root Dec 10 17:04:12 volumio-4-home sudo[1401]: pam_unix(sudo:session): session closed for user root Dec 10 17:04:12 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 24 Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 17:04:12 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:12 volumio-4-home systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 10 17:04:12 volumio-4-home systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 17:04:12 volumio-4-home systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 17:04:12 volumio-4-home sudo[1377]: pam_unix(sudo:session): session closed for user root Dec 10 17:04:13 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:13 volumio-4-home volumio[1125]: info: Completed starting Core Plugins Dec 10 17:04:13 volumio-4-home volumio[1125]: info: ------------------------------------------- Dec 10 17:04:13 volumio-4-home volumio[1125]: info: ----- MyVolumio plugins startup ---- Dec 10 17:04:13 volumio-4-home volumio[1125]: info: ------------------------------------------- Dec 10 17:04:13 volumio-4-home volumio[1125]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 10 17:04:13 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.93 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 25 Dec 10 17:04:13 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Dec 10 17:04:13 volumio-4-home volumio[1125]: info: MPD running with PID1408 Dec 10 17:04:13 volumio-4-home volumio[1125]: ,establishing connection Dec 10 17:04:13 volumio-4-home volumio[1125]: info: Upmpdcli Daemon Started Dec 10 17:04:13 volumio-4-home volumio[1125]: error: MPD error: The expression evaluated to a falsy value: Dec 10 17:04:13 volumio-4-home volumio[1125]: assert.ok(self.idling) Dec 10 17:04:13 volumio-4-home volumio[1125]: error: The expression evaluated to a falsy value: Dec 10 17:04:13 volumio-4-home volumio[1125]: assert.ok(self.idling) Dec 10 17:04:13 volumio-4-home volumio[1125]: error: MPD error: The expression evaluated to a falsy value: Dec 10 17:04:13 volumio-4-home volumio[1125]: assert.ok(self.idling) Dec 10 17:04:13 volumio-4-home volumio[1125]: error: The expression evaluated to a falsy value: Dec 10 17:04:13 volumio-4-home volumio[1125]: assert.ok(self.idling) Dec 10 17:04:13 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:13 volumio-4-home volumio[1125]: error: MPD error: The expression evaluated to a falsy value: Dec 10 17:04:13 volumio-4-home volumio[1125]: assert.ok(self.idling) Dec 10 17:04:13 volumio-4-home volumio[1125]: error: The expression evaluated to a falsy value: Dec 10 17:04:13 volumio-4-home volumio[1125]: assert.ok(self.idling) Dec 10 17:04:13 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:13 volumio-4-home volumio[1125]: error: MPD error: The expression evaluated to a falsy value: Dec 10 17:04:13 volumio-4-home volumio[1125]: assert.ok(self.idling) Dec 10 17:04:13 volumio-4-home volumio[1125]: error: The expression evaluated to a falsy value: Dec 10 17:04:13 volumio-4-home volumio[1125]: assert.ok(self.idling) Dec 10 17:04:14 volumio-4-home volumio[1125]: info: Starting Shairport Sync Dec 10 17:04:14 volumio-4-home volumio[1125]: info: Starting Shairport Sync Dec 10 17:04:14 volumio-4-home volumio[1125]: info: Starting Shairport Sync Dec 10 17:04:14 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 10 17:04:14 volumio-4-home sudo[1544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 17:04:14 volumio-4-home sudo[1546]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 17:04:14 volumio-4-home sudo[1544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 17:04:14 volumio-4-home sudo[1548]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 17:04:14 volumio-4-home sudo[1548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 17:04:14 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioGetVisibleSources Dec 10 17:04:14 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 17:04:14 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioGetState Dec 10 17:04:14 volumio-4-home volumio[1125]: info: CorePlayQueue::getTrack 0 Dec 10 17:04:14 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 10 17:04:14 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioGetQueue Dec 10 17:04:14 volumio-4-home volumio[1125]: info: CoreStateMachine::getQueue Dec 10 17:04:14 volumio-4-home volumio[1125]: info: CorePlayQueue::getQueue Dec 10 17:04:14 volumio-4-home sudo[1546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 17:04:14 volumio-4-home systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 17:04:14 volumio-4-home systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 17:04:14 volumio-4-home systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 17:04:14 volumio-4-home systemd[1]: shairport-sync.service: Consumed 1.887s CPU time. Dec 10 17:04:14 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 10 17:04:14 volumio-4-home volumio[1125]: info: Received Get System Info Dec 10 17:04:14 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 10 17:04:14 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 10 17:04:14 volumio-4-home volumio[1125]: info: Discovery: Getting this device information Dec 10 17:04:14 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioGetState Dec 10 17:04:14 volumio-4-home volumio[1125]: info: CorePlayQueue::getTrack 0 Dec 10 17:04:14 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 10 17:04:14 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioGetState Dec 10 17:04:14 volumio-4-home volumio[1125]: info: CorePlayQueue::getTrack 0 Dec 10 17:04:14 volumio-4-home volumio[1125]: info: Listing playlists Dec 10 17:04:14 volumio-4-home systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 17:04:14 volumio-4-home sudo[1544]: pam_unix(sudo:session): session closed for user root Dec 10 17:04:14 volumio-4-home systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 17:04:14 volumio-4-home volumio[1125]: error: updateQueue error: null Dec 10 17:04:14 volumio-4-home systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 17:04:14 volumio-4-home systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 17:04:14 volumio-4-home systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 17:04:14 volumio-4-home sudo[1546]: pam_unix(sudo:session): session closed for user root Dec 10 17:04:14 volumio-4-home sudo[1548]: pam_unix(sudo:session): session closed for user root Dec 10 17:04:14 volumio-4-home volumio[1125]: info: Shairport-Sync Started Dec 10 17:04:14 volumio-4-home volumio[1125]: Error adding Membership: Error: addMembership EINVAL Dec 10 17:04:14 volumio-4-home volumio[1125]: info: Shairport-Sync Started Dec 10 17:04:14 volumio-4-home volumio[1125]: info: Shairport-Sync Started Dec 10 17:04:15 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumioGetState Dec 10 17:04:15 volumio-4-home volumio[1125]: info: CorePlayQueue::getTrack 0 Dec 10 17:04:16 volumio-4-home sudo[1601]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 17:04:16 volumio-4-home sudo[1601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 17:04:16 volumio-4-home sudo[1605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 17:04:16 volumio-4-home sudo[1605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 17:04:16 volumio-4-home systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 10 17:04:16 volumio-4-home mpd_monitor.sh[1603]: MPD Monitor Service: Starting MPD Monitor Service Dec 10 17:04:16 volumio-4-home sudo[1601]: pam_unix(sudo:session): session closed for user root Dec 10 17:04:16 volumio-4-home volumio[1125]: info: Successfully started MPD Monitor Dec 10 17:04:16 volumio-4-home systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 10 17:04:16 volumio-4-home systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 10 17:04:16 volumio-4-home systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 10 17:04:16 volumio-4-home systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 10 17:04:16 volumio-4-home mpd_monitor.sh[1610]: MPD Monitor Service: Starting MPD Monitor Service Dec 10 17:04:16 volumio-4-home sudo[1605]: pam_unix(sudo:session): session closed for user root Dec 10 17:04:16 volumio-4-home volumio[1125]: info: Successfully started MPD Monitor Dec 10 17:04:16 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:19 volumio-4-home volumio-remote-updater[680]: [2025-12-10 17:04:19] [connect] Successful connection Dec 10 17:04:21 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:31 volumio-4-home upmpdcli[1628]: writing RSA key Dec 10 17:04:34 volumio-4-home volumio-remote-updater[680]: [2025-12-10 17:04:34] [connect] Successful connection Dec 10 17:04:35 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:35 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:35 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 17:04:35 volumio-4-home volumio[1125]: verbose: New Socket.io Connection to 192.168.1.15:3000 from 192.168.1.161 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 27 Dec 10 17:04:35 volumio-4-home volumio[1125]: error: MyVolumio Plugin failed to start in a timely fashion Dec 10 17:04:35 volumio-4-home volumio[1125]: [Metrics] CommandRouter: 54s 574.30ms Dec 10 17:04:35 volumio-4-home volumio[1125]: info: CoreCommandRouter::volumiosetStartupVolume Dec 10 17:04:35 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 17:04:35 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 17:04:35 volumio-4-home volumio[1125]: info: CoreCommandRouter::Close All Modals sent Dec 10 17:04:35 volumio-4-home volumio[1125]: info: CoreCommandRouter::Close All Modals sent Dec 10 17:04:35 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 17:04:35 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 17:04:36 volumio-4-home volumio[1125]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 10 17:04:36 volumio-4-home volumio[1125]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 17:04:36 volumio-4-home volumio[1125]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Dec 10 17:04:36 volumio-4-home volumio[1125]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Dec 10 17:04:36 volumio-4-home volumio[1125]: at new NodeError (node:internal/errors:405:5) Dec 10 17:04:36 volumio-4-home volumio[1125]: at assert (node:internal/assert:14:11) Dec 10 17:04:36 volumio-4-home volumio[1125]: at internalConnectMultiple (node:net:1118:3) Dec 10 17:04:36 volumio-4-home volumio[1125]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Dec 10 17:04:36 volumio-4-home volumio[1125]: at listOnTimeout (node:internal/timers:575:11) Dec 10 17:04:36 volumio-4-home volumio[1125]: at process.processTimers (node:internal/timers:514:7) { Dec 10 17:04:36 volumio-4-home volumio[1125]: code: 'ERR_INTERNAL_ASSERTION' Dec 10 17:04:36 volumio-4-home volumio[1125]: } Dec 10 17:04:36 volumio-4-home volumio[1125]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 17:04:41 volumio-4-home sudo[1671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-10 17:03' Dec 10 17:04:41 volumio-4-home sudo[1671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"