-- Logs begin at Mon 2025-09-01 17:12:46 UTC, end at Mon 2025-09-01 17:15:43 UTC. -- Sep 01 17:15:02 volumio ntpd[1177]: receive: Unexpected origin timestamp 0xec6052a6.b8166779 does not match aorg 0000000000.00000000 from server@51.250.110.169 xmt 0xec605316.5ebe9f3e Sep 01 17:15:02 volumio ntpd[1177]: receive: Unexpected origin timestamp 0xec6052a6.b81a9a08 does not match aorg 0000000000.00000000 from server@45.141.102.99 xmt 0xec605316.5eb21e9b Sep 01 17:15:02 volumio ntpd[1177]: receive: Unexpected origin timestamp 0xec6052a6.b820aa7a does not match aorg 0000000000.00000000 from server@91.206.16.3 xmt 0xec605316.6314581e Sep 01 17:15:03 volumio volumio-remote-updater[658]: [2025-09-01 17:15:03] [info] asio async_connect error: system:111 (Connection refused) Sep 01 17:15:03 volumio volumio-remote-updater[658]: [2025-09-01 17:15:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 01 17:15:03 volumio volumio-remote-updater[658]: [2025-09-01 17:15:03] [error] handle_connect error: Underlying Transport Error Sep 01 17:15:09 volumio systemd[1]: systemd-fsckd.service: Succeeded. Sep 01 17:15:10 volumio sh[602]: timed out Sep 01 17:15:10 volumio sh[602]: dhcpcd exited Sep 01 17:15:10 volumio dhcpcd[642]: timed out Sep 01 17:15:10 volumio dhcpcd[642]: dhcpcd exited Sep 01 17:15:10 volumio sh[602]: ifup: failed to bring up eth0 Sep 01 17:15:10 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Sep 01 17:15:10 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Sep 01 17:15:13 volumio volumio-remote-updater[658]: [2025-09-01 17:15:13] [info] asio async_connect error: system:111 (Connection refused) Sep 01 17:15:13 volumio volumio-remote-updater[658]: [2025-09-01 17:15:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 01 17:15:13 volumio volumio-remote-updater[658]: [2025-09-01 17:15:13] [error] handle_connect error: Underlying Transport Error Sep 01 17:15:14 volumio mpd_monitor.sh[663]: MPD Monitor Service: MPD Appears to be inactive, restarting Sep 01 17:15:14 volumio systemd[1]: Stopping Music Player Daemon... Sep 01 17:15:23 volumio volumio-remote-updater[658]: [2025-09-01 17:15:23] [info] asio async_connect error: system:111 (Connection refused) Sep 01 17:15:23 volumio volumio-remote-updater[658]: [2025-09-01 17:15:23] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 01 17:15:23 volumio volumio-remote-updater[658]: [2025-09-01 17:15:23] [error] handle_connect error: Underlying Transport Error Sep 01 17:15:33 volumio volumio-remote-updater[658]: [2025-09-01 17:15:33] [info] asio async_connect error: system:111 (Connection refused) Sep 01 17:15:33 volumio volumio-remote-updater[658]: [2025-09-01 17:15:33] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 01 17:15:33 volumio volumio-remote-updater[658]: [2025-09-01 17:15:33] [error] handle_connect error: Underlying Transport Error Sep 01 17:15:42 volumio kernel: ata1.00: exception Emask 0x0 SAct 0xfc03e SErr 0x50000 action 0x6 frozen Sep 01 17:15:42 volumio kernel: ata1: SError: { PHYRdyChg CommWake } Sep 01 17:15:42 volumio kernel: ata1.00: failed command: WRITE FPDMA QUEUED Sep 01 17:15:42 volumio kernel: ata1.00: cmd 61/08:08:08:e0:37/00:00:07:00:00/40 tag 1 ncq dma 4096 out res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) Sep 01 17:15:42 volumio kernel: ata1.00: status: { DRDY } Sep 01 17:15:42 volumio kernel: ata1.00: failed command: READ FPDMA QUEUED Sep 01 17:15:42 volumio kernel: ata1.00: cmd 60/08:10:68:39:64/00:00:00:00:00/40 tag 2 ncq dma 4096 in res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) Sep 01 17:15:42 volumio kernel: ata1.00: status: { DRDY } Sep 01 17:15:42 volumio kernel: ata1.00: failed command: WRITE FPDMA QUEUED Sep 01 17:15:42 volumio kernel: ata1.00: cmd 61/08:18:e0:7f:32/00:00:07:00:00/40 tag 3 ncq dma 4096 out res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) Sep 01 17:15:42 volumio kernel: ata1.00: status: { DRDY } Sep 01 17:15:42 volumio kernel: ata1.00: failed command: WRITE FPDMA QUEUED Sep 01 17:15:42 volumio kernel: ata1.00: cmd 61/08:20:88:6e:05/00:00:00:00:00/40 tag 4 ncq dma 4096 out res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) Sep 01 17:15:42 volumio kernel: ata1.00: status: { DRDY } Sep 01 17:15:42 volumio kernel: ata1.00: failed command: WRITE FPDMA QUEUED Sep 01 17:15:42 volumio kernel: ata1.00: cmd 61/08:28:68:41:35/00:00:07:00:00/40 tag 5 ncq dma 4096 out res 40/00:01:00:00:00/00:00:00:00:00/e0 Emask 0x4 (timeout) Sep 01 17:15:42 volumio kernel: ata1.00: status: { DRDY } Sep 01 17:15:42 volumio kernel: ata1.00: failed command: WRITE FPDMA QUEUED Sep 01 17:15:42 volumio kernel: ata1.00: cmd 61/08:70:e0:d0:37/00:00:07:00:00/40 tag 14 ncq dma 4096 out res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) Sep 01 17:15:42 volumio kernel: ata1.00: status: { DRDY } Sep 01 17:15:42 volumio kernel: ata1.00: failed command: READ FPDMA QUEUED Sep 01 17:15:42 volumio kernel: ata1.00: cmd 60/08:78:08:46:32/00:00:07:00:00/40 tag 15 ncq dma 4096 in res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) Sep 01 17:15:42 volumio kernel: ata1.00: status: { DRDY } Sep 01 17:15:42 volumio kernel: ata1.00: failed command: READ FPDMA QUEUED Sep 01 17:15:42 volumio kernel: ata1.00: cmd 60/d0:80:b8:74:70/00:00:00:00:00/40 tag 16 ncq dma 106496 in res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) Sep 01 17:15:42 volumio kernel: ata1.00: status: { DRDY } Sep 01 17:15:42 volumio kernel: ata1.00: failed command: WRITE FPDMA QUEUED Sep 01 17:15:42 volumio kernel: ata1.00: cmd 61/08:88:20:38:ba/00:00:04:00:00/40 tag 17 ncq dma 4096 out res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) Sep 01 17:15:42 volumio kernel: ata1.00: status: { DRDY } Sep 01 17:15:42 volumio kernel: ata1.00: failed command: WRITE FPDMA QUEUED Sep 01 17:15:42 volumio kernel: ata1.00: cmd 61/68:90:28:38:ba/00:00:04:00:00/40 tag 18 ncq dma 53248 out res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) Sep 01 17:15:42 volumio kernel: ata1.00: status: { DRDY } Sep 01 17:15:42 volumio kernel: ata1.00: failed command: READ FPDMA QUEUED Sep 01 17:15:42 volumio kernel: ata1.00: cmd 60/08:98:f8:43:32/00:00:07:00:00/40 tag 19 ncq dma 4096 in res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) Sep 01 17:15:42 volumio kernel: ata1.00: status: { DRDY } Sep 01 17:15:42 volumio kernel: ata1: hard resetting link Sep 01 17:15:43 volumio kernel: ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) Sep 01 17:15:43 volumio kernel: ata1.00: configured for UDMA/133 Sep 01 17:15:43 volumio kernel: ata1.00: device reported invalid CHS sector 0 Sep 01 17:15:43 volumio kernel: ata1.00: device reported invalid CHS sector 0 Sep 01 17:15:43 volumio kernel: ata1.00: device reported invalid CHS sector 0 Sep 01 17:15:43 volumio kernel: ata1.00: device reported invalid CHS sector 0 Sep 01 17:15:43 volumio kernel: ata1.00: device reported invalid CHS sector 0 Sep 01 17:15:43 volumio kernel: ata1.00: device reported invalid CHS sector 0 Sep 01 17:15:43 volumio kernel: ata1.00: device reported invalid CHS sector 0 Sep 01 17:15:43 volumio kernel: ata1.00: device reported invalid CHS sector 0 Sep 01 17:15:43 volumio kernel: ata1.00: device reported invalid CHS sector 0 Sep 01 17:15:43 volumio kernel: ata1.00: device reported invalid CHS sector 0 Sep 01 17:15:43 volumio kernel: sd 0:0:0:0: [sda] tag#2 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_OK cmd_age=46s Sep 01 17:15:43 volumio kernel: sd 0:0:0:0: [sda] tag#2 Sense Key : Illegal Request [current] Sep 01 17:15:43 volumio kernel: sd 0:0:0:0: [sda] tag#2 Add. Sense: Unaligned write command Sep 01 17:15:43 volumio kernel: sd 0:0:0:0: [sda] tag#2 CDB: Read(10) 28 00 00 64 39 68 00 00 08 00 Sep 01 17:15:43 volumio kernel: I/O error, dev sda, sector 6568296 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Sep 01 17:15:43 volumio kernel: sd 0:0:0:0: [sda] tag#16 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_OK cmd_age=50s Sep 01 17:15:43 volumio kernel: sd 0:0:0:0: [sda] tag#16 Sense Key : Illegal Request [current] Sep 01 17:15:43 volumio kernel: sd 0:0:0:0: [sda] tag#16 Add. Sense: Unaligned write command Sep 01 17:15:43 volumio kernel: sd 0:0:0:0: [sda] tag#16 CDB: Read(10) 28 00 00 70 74 b8 00 00 d0 00 Sep 01 17:15:43 volumio kernel: I/O error, dev sda, sector 7369912 op 0x0:(READ) flags 0x80700 phys_seg 26 prio class 2 Sep 01 17:15:43 volumio kernel: ata1: EH complete Sep 01 17:15:43 volumio dbus-daemon[683]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.5' (uid=0 pid=1185 comm="timedatectl set-time 2025-09-01 17:14:56 ") Sep 01 17:15:43 volumio iwconfig[1208]: Error for wireless request "Set Power Management" (8B2C) : Sep 01 17:15:43 volumio systemd[1]: Starting Time & Date Service... Sep 01 17:15:43 volumio iwconfig[1208]: SET failed on device wlan0 ; Operation not supported. Sep 01 17:15:43 volumio systemd[1]: Started Wireless Services. Sep 01 17:15:43 volumio systemd[1]: Started Volumio Backend Module. Sep 01 17:15:43 volumio systemd[1]: Started Intel SST and HDA soundcard init service. Sep 01 17:15:43 volumio systemd[1]: Started Volumio Cpu Tweaker. Sep 01 17:15:43 volumio systemd[1]: mpd.service: Succeeded. Sep 01 17:15:43 volumio systemd[1]: Stopped Music Player Daemon. Sep 01 17:15:43 volumio systemd[1]: Starting Music Player Daemon... Sep 01 17:15:43 volumio volumio-cpu-tweak[1212]: Setting RT Priority for mpd Sep 01 17:15:43 volumio volumio-cpu-tweak[1212]: pid 35's current scheduling policy: SCHED_OTHER Sep 01 17:15:43 volumio volumio-cpu-tweak[1212]: pid 35's current scheduling priority: 0 Sep 01 17:15:43 volumio volumio-cpu-tweak[1212]: Not enough cores to set MPD affinity Sep 01 17:15:43 volumio volumio-cpu-tweak[1212]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Sep 01 17:15:43 volumio dbus-daemon[683]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 01 17:15:43 volumio systemd[1]: Started Time & Date Service. Sep 01 17:15:43 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Sep 01 17:15:43 volumio sudo[1219]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 17:15:43 volumio sudo[1219]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 17:14:56 volumio systemd-timedated[1209]: Changed local time to Mon Sep 1 17:14:56 2025 Sep 01 17:14:56 volumio sudo[1219]: pam_unix(sudo:session): session closed for user root Sep 01 17:14:56 volumio sudo[1184]: pam_unix(sudo:session): session closed for user root Sep 01 17:14:56 volumio volumio-time-update[661]: volumio-time-update-util: System time updated successfully. Sep 01 17:14:56 volumio systemd[1]: Started Volumio Time Update Utility. Sep 01 17:14:56 volumio systemd[1]: Reached target Multi-User System. Sep 01 17:14:56 volumio systemd[1]: Reached target Graphical Interface. Sep 01 17:14:56 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 01 17:14:56 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 01 17:14:56 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 01 17:14:56 volumio systemd[1]: soundcard-init.service: Succeeded. Sep 01 17:14:57 volumio mpd[1240]: Sep 01 17:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 17:14:57 volumio systemd[1]: Started Music Player Daemon. Sep 01 17:14:57 volumio mpd_monitor.sh[663]: MPD Monitor Service: MPD restarted due to no mpc output. Sep 01 17:14:57 volumio systemd[1]: Startup finished in 39.334s (kernel) + 1min 7.697s (userspace) = 1min 47.031s. Sep 01 17:14:57 volumio volumio[1210]: info: ------------------------------------------- Sep 01 17:14:57 volumio volumio[1210]: info: ----- Volumio3 ---- Sep 01 17:14:57 volumio volumio[1210]: info: ------------------------------------------- Sep 01 17:14:57 volumio volumio[1210]: info: ----- System startup ---- Sep 01 17:14:57 volumio volumio[1210]: info: ------------------------------------------- Sep 01 17:14:58 volumio volumio[1210]: info: MYVOLUMIO Environment detected Sep 01 17:14:58 volumio volumio[1210]: info: Plugin folders cleanup Sep 01 17:14:58 volumio volumio[1210]: info: Scanning into folder /volumio/app/plugins/ Sep 01 17:14:58 volumio volumio[1210]: info: Scanning category audio_interface Sep 01 17:14:58 volumio volumio[1210]: info: Scanning category miscellanea Sep 01 17:14:58 volumio volumio[1210]: info: Scanning category music_service Sep 01 17:14:58 volumio volumio[1210]: info: Scanning category plugins.json Sep 01 17:14:58 volumio volumio[1210]: info: Scanning category system_controller Sep 01 17:14:58 volumio volumio[1210]: info: Scanning category user_interface Sep 01 17:14:58 volumio volumio[1210]: info: Scanning into folder /data/plugins/ Sep 01 17:14:58 volumio volumio[1210]: info: Scanning category audio_interface Sep 01 17:14:58 volumio volumio[1210]: info: Plugin folders cleanup completed Sep 01 17:14:58 volumio volumio[1210]: info: ------------------------------------------- Sep 01 17:14:58 volumio volumio[1210]: info: ----- Core plugins startup ---- Sep 01 17:14:58 volumio volumio[1210]: info: ------------------------------------------- Sep 01 17:14:58 volumio volumio[1210]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 17:14:58 volumio volumio[1210]: info: Adding plugin upnp to MyMusic Plugins Sep 01 17:14:58 volumio volumio[1210]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 17:14:58 volumio volumio[1210]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 17:14:58 volumio volumio[1210]: info: Loading plugins from folder /data/plugins/ Sep 01 17:14:58 volumio volumio[1210]: info: Loading plugin "system"... Sep 01 17:14:58 volumio volumio[1210]: info: Loading plugin "appearance"... Sep 01 17:14:59 volumio volumio[1210]: info: Loading plugin "network"... Sep 01 17:14:59 volumio volumio[1210]: info: Refreshing Cached IP Addresses Sep 01 17:14:59 volumio sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 17:14:59 volumio sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 17:14:59 volumio sudo[1337]: pam_unix(sudo:session): session closed for user root Sep 01 17:14:59 volumio sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 17:14:59 volumio sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 17:14:59 volumio sudo[1339]: pam_unix(sudo:session): session closed for user root Sep 01 17:14:59 volumio volumio[1210]: info: Loading plugin "services"... Sep 01 17:14:59 volumio volumio[1210]: info: Loading plugin "alsa_controller"... Sep 01 17:14:59 volumio sudo[1352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 01 17:14:59 volumio sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 17:14:59 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 17:14:59 volumio volumio[1210]: info: Loading plugin "wizard"... Sep 01 17:14:59 volumio volumio[1210]: info: Loading plugin "networkfs"... Sep 01 17:14:59 volumio volumio[1210]: info: Starting Udev Watcher for removable devices Sep 01 17:14:59 volumio volumio[1210]: info: Ignoring mount for partition: boot Sep 01 17:14:59 volumio volumio[1210]: info: Ignoring mount for partition: volumio Sep 01 17:14:59 volumio volumio[1210]: info: Ignoring mount for partition: volumio_data Sep 01 17:14:59 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 17:14:59 volumio volumio[1210]: info: Loading plugin "volumio_command_line_client"... Sep 01 17:14:59 volumio volumio[1210]: info: Plugin upnp is not enabled Sep 01 17:14:59 volumio volumio[1210]: info: Loading plugin "my_music"... Sep 01 17:14:59 volumio volumio[1210]: info: Loading plugin "mpd"... Sep 01 17:14:59 volumio volumio[1210]: info: Plugin upnp_browser is not enabled Sep 01 17:14:59 volumio volumio[1210]: info: Loading plugin "alarm-clock"... Sep 01 17:14:59 volumio volumio[1210]: info: Plugin airplay_emulation is not enabled Sep 01 17:14:59 volumio volumio[1210]: info: Loading plugin "last_100"... Sep 01 17:14:59 volumio volumio[1210]: info: Loading plugin "webradio"... Sep 01 17:14:59 volumio volumio[1210]: info: Loading plugin "i2s_dacs"... Sep 01 17:14:59 volumio volumio[1210]: info: I2S DAC not set, start Auto-detection Sep 01 17:14:59 volumio volumio[1210]: info: Loading plugin "volumiodiscovery"... Sep 01 17:14:59 volumio volumio[1210]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 17:14:59 volumio volumio[1210]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 17:14:59 volumio node[1210]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 17:14:59 volumio volumio[1210]: *** WARNING *** For more information see Sep 01 17:14:59 volumio volumio[1210]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 17:14:59 volumio volumio[1210]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 17:14:59 volumio node[1210]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 17:14:59 volumio volumio[1210]: *** WARNING *** For more information see Sep 01 17:14:59 volumio node[1210]: *** WARNING *** For more information see Sep 01 17:14:59 volumio node[1210]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 17:14:59 volumio node[1210]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 17:14:59 volumio node[1210]: *** WARNING *** For more information see Sep 01 17:14:59 volumio volumio[1210]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 17:14:59 volumio volumio[1210]: info: Discovery: Started advertising with name: Volumio Sep 01 17:14:59 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 17:14:59 volumio volumio[1210]: info: Loading plugin "outputs"... Sep 01 17:14:59 volumio volumio[1210]: info: Loading plugin "albumart"... Sep 01 17:14:59 volumio volumio[1210]: info: Plugin example_plugin is not enabled Sep 01 17:14:59 volumio volumio[1210]: info: Loading plugin "inputs"... Sep 01 17:14:59 volumio volumio[1210]: info: Loading plugin "updater_comm"... Sep 01 17:15:00 volumio volumio[1210]: Forking 1 albumart workers Sep 01 17:15:00 volumio volumio[1210]: info: Plugin mpdemulation is not enabled Sep 01 17:15:00 volumio volumio[1210]: info: Loading plugin "rest_api"... Sep 01 17:15:00 volumio volumio[1210]: info: Loading plugin "websocket"... Sep 01 17:15:00 volumio volumio[1210]: info: Starting Socket.io Server version 2.3.0 Sep 01 17:15:00 volumio volumio[1210]: info: Loading plugin "fusiondsp"... Sep 01 17:15:00 volumio volumio[1210]: info: Applying required configuration parameters for plugin fusiondsp Sep 01 17:15:00 volumio volumio[1210]: info: Loading i18n strings for locale en Sep 01 17:15:00 volumio volumio[1210]: Updating browse sources language Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 17:15:00 volumio volumio[1210]: Starting albumart workers Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::initPlayerControls Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 17:15:00 volumio volumio[1210]: Express server listening on port 3000 Sep 01 17:15:00 volumio volumio[1210]: [Metrics] WebUI: 3s 540.37ms Sep 01 17:15:00 volumio volumio[1210]: info: Setting Device type: x86 Sep 01 17:15:00 volumio volumio[1210]: info: CoreStateMachine::resetVolumioState Sep 01 17:15:00 volumio volumio[1210]: info: CoreStateMachine::getcurrentVolume Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 17:15:00 volumio volumio[1210]: info: CoreStateMachine::pushState Sep 01 17:15:00 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 17:15:00 volumio volumio[1210]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 01 17:15:00 volumio volumio[1210]: info: Completed loading Core Plugins Sep 01 17:15:00 volumio volumio[1210]: info: Preparing to generate the ALSA configuration file Sep 01 17:15:00 volumio volumio[1210]: info: Volumio Network Manager: Network status updated: 2 Sep 01 17:15:00 volumio volumio[1210]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Sep 01 17:15:00 volumio volumio[1210]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 01 17:15:00 volumio volumio[1210]: info: Reading ALSA contributions from plugins. Sep 01 17:15:00 volumio volumio[1210]: info: Reloading queue from file Sep 01 17:15:00 volumio volumio[1210]: info: CoreStateMachine::setRepeat null single undefined Sep 01 17:15:00 volumio volumio[1210]: info: CoreStateMachine::pushState Sep 01 17:15:00 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Sep 01 17:15:00 volumio volumio[1210]: info: CoreStateMachine::setRandom null Sep 01 17:15:00 volumio volumio[1210]: info: CoreStateMachine::pushState Sep 01 17:15:00 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Sep 01 17:15:00 volumio volumio[1210]: info: Asound.conf file unchanged, so no further update is needed Sep 01 17:15:00 volumio volumio[1210]: info: Output device has changed, restarting MPD Sep 01 17:15:00 volumio sudo[1392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 17:15:00 volumio sudo[1392]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 17:15:00 volumio volumio[1210]: info: ___________ START PLUGINS ___________ Sep 01 17:15:00 volumio sudo[1392]: pam_unix(sudo:session): session closed for user root Sep 01 17:15:00 volumio sudo[1395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 17:15:00 volumio volumio[1210]: info: ControllerMpd::onStart: Initializing MPD Sep 01 17:15:00 volumio volumio[1210]: info: Creating MPD Configuration file Sep 01 17:15:00 volumio sudo[1395]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 17:15:00 volumio systemd[1]: Stopping Music Player Daemon... Sep 01 17:15:00 volumio sudo[1398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 17:15:00 volumio sudo[1398]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 17:15:00 volumio volumio[1210]: info: [1756746900961] CoreMusicLibrary::Adding element Last_100 Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 17:15:00 volumio volumio[1210]: info: [1756746900968] CoreMusicLibrary::Adding element Webradio Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 17:15:00 volumio sudo[1398]: pam_unix(sudo:session): session closed for user root Sep 01 17:15:00 volumio systemd[1]: mpd.service: Succeeded. Sep 01 17:15:00 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 17:15:00 volumio systemd[1]: Stopped Music Player Daemon. Sep 01 17:15:00 volumio volumio[1210]: info: Initializing BBC Radios Sep 01 17:15:00 volumio sudo[1401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 17:15:00 volumio sudo[1401]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 17:15:00 volumio systemd[1]: Starting Music Player Daemon... Sep 01 17:15:01 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 01 17:15:01 volumio systemd[1]: mpd.service: Succeeded. Sep 01 17:15:01 volumio systemd[1]: Stopped Music Player Daemon. Sep 01 17:15:01 volumio systemd[1]: Starting Music Player Daemon... Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 17:15:01 volumio volumio[1210]: info: Loading i18n strings for locale en Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 17:15:01 volumio volumio[1210]: info: FusionDsp - mixtype--------------------- None Sep 01 17:15:01 volumio volumio[1210]: info: Volumio Calling Home Sep 01 17:15:01 volumio sudo[1407]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 17:15:01 volumio sudo[1407]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 17:15:01 volumio sudo[1407]: pam_unix(sudo:session): session closed for user root Sep 01 17:15:01 volumio volumio[1210]: info: Preparing to generate the ALSA configuration file Sep 01 17:15:01 volumio volumio[1210]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 01 17:15:01 volumio volumio[1210]: info: Reading ALSA contributions from plugins. Sep 01 17:15:01 volumio volumio[1210]: info: MPD Permissions set Sep 01 17:15:01 volumio volumio[1210]: info: MPD Permissions set Sep 01 17:15:01 volumio volumio[1210]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 17:15:01 volumio volumio[1210]: info: Asound.conf file unchanged, so no further update is needed Sep 01 17:15:01 volumio volumio[1210]: info: Output device has changed, restarting MPD Sep 01 17:15:01 volumio sudo[1433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 17:15:01 volumio sudo[1433]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 17:15:01 volumio sudo[1433]: pam_unix(sudo:session): session closed for user root Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 01 17:15:01 volumio sudo[1435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 17:15:01 volumio sudo[1435]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 17:15:01 volumio volumio[1210]: info: MPD Permissions set Sep 01 17:15:01 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 01 17:15:01 volumio systemd[1]: mpd.service: Succeeded. Sep 01 17:15:01 volumio systemd[1]: Stopped Music Player Daemon. Sep 01 17:15:01 volumio systemd[1]: Starting Music Player Daemon... Sep 01 17:15:01 volumio sudo[1444]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 17:15:01 volumio sudo[1444]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 17:15:01 volumio sudo[1444]: pam_unix(sudo:session): session closed for user root Sep 01 17:15:01 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Sep 01 17:15:01 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Sep 01 17:15:01 volumio volumio[1210]: info: Volumio called home Sep 01 17:15:02 volumio mpd[1446]: Sep 01 17:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 17:15:02 volumio systemd[1]: Started Music Player Daemon. Sep 01 17:15:02 volumio sudo[1435]: pam_unix(sudo:session): session closed for user root Sep 01 17:15:02 volumio sudo[1401]: pam_unix(sudo:session): session closed for user root Sep 01 17:15:02 volumio sudo[1395]: pam_unix(sudo:session): session closed for user root Sep 01 17:15:02 volumio volumio[1210]: info: Completed starting Core Plugins Sep 01 17:15:02 volumio volumio[1210]: info: ------------------------------------------- Sep 01 17:15:02 volumio volumio[1210]: info: ----- MyVolumio plugins startup ---- Sep 01 17:15:02 volumio volumio[1210]: info: ------------------------------------------- Sep 01 17:15:02 volumio volumio[1210]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 17:15:02 volumio volumio[1210]: error: MPD error: The expression evaluated to a falsy value: Sep 01 17:15:02 volumio volumio[1210]: assert.ok(self.idling) Sep 01 17:15:02 volumio volumio[1210]: error: The expression evaluated to a falsy value: Sep 01 17:15:02 volumio volumio[1210]: assert.ok(self.idling) Sep 01 17:15:02 volumio volumio[1210]: error: MPD error: The expression evaluated to a falsy value: Sep 01 17:15:02 volumio volumio[1210]: assert.ok(self.idling) Sep 01 17:15:02 volumio volumio[1210]: error: The expression evaluated to a falsy value: Sep 01 17:15:02 volumio volumio[1210]: assert.ok(self.idling) Sep 01 17:15:02 volumio volumio[1210]: info: MPD running with PID1446 Sep 01 17:15:02 volumio volumio[1210]: ,establishing connection Sep 01 17:15:02 volumio volumio[1210]: error: updateQueue error: null Sep 01 17:15:02 volumio volumio[1210]: error: updateQueue error: null Sep 01 17:15:03 volumio volumio[1210]: info: camilladsp service started and running in background, instance 1 Sep 01 17:15:03 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 17:15:03 volumio volumio[1210]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 01 17:15:03 volumio volumio[1210]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 01 17:15:03 volumio volumio[1210]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 01 17:15:03 volumio volumio[1210]: info: FusionDsp loaded Sep 01 17:15:03 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 17:15:03 volumio sudo[1461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 01 17:15:03 volumio sudo[1461]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 17:15:03 volumio volumio[1210]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 01 17:15:03 volumio volumio[1210]: info: Adding Signal Path Element [object Object] Sep 01 17:15:03 volumio volumio[1210]: info: Adding fusiondspeq DSP Signal Path Element Sep 01 17:15:03 volumio volumio[1210]: info: FusionDsp - ---- installed callbackRead Sep 01 17:15:03 volumio volumio[1210]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 17:15:03 volumio volumio[1210]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 01 17:15:03 volumio volumio[1210]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Sep 01 17:15:03 volumio volumio[1210]: at onErrorNT (internal/child_process.js:472:16) Sep 01 17:15:03 volumio volumio[1210]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Sep 01 17:15:03 volumio volumio[1210]: errno: -2, Sep 01 17:15:03 volumio volumio[1210]: code: 'ENOENT', Sep 01 17:15:03 volumio volumio[1210]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 01 17:15:03 volumio volumio[1210]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 01 17:15:03 volumio volumio[1210]: spawnargs: [ Sep 01 17:15:03 volumio volumio[1210]: '-p', Sep 01 17:15:03 volumio volumio[1210]: 9876, Sep 01 17:15:03 volumio volumio[1210]: '-o', Sep 01 17:15:03 volumio volumio[1210]: '/tmp/camilladsp.log', Sep 01 17:15:03 volumio volumio[1210]: '-l', Sep 01 17:15:03 volumio volumio[1210]: 'warn', Sep 01 17:15:03 volumio volumio[1210]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 01 17:15:03 volumio volumio[1210]: ] Sep 01 17:15:03 volumio volumio[1210]: } Sep 01 17:15:03 volumio volumio[1210]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 17:15:03 volumio systemd[1]: Started FusionDsp Daemon. Sep 01 17:15:03 volumio sudo[1461]: pam_unix(sudo:session): session closed for user root Sep 01 17:15:03 volumio sudo[1473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-01 17:14 Sep 01 17:15:03 volumio sudo[1473]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"