-- Logs begin at Thu 2025-10-30 22:51:37 EET, end at Thu 2025-10-30 22:53:21 EET. -- Oct 30 22:52:00 volumio wireless.js[642]: trying... Oct 30 22:52:00 volumio sudo[970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 22:52:00 volumio sudo[970]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:00 volumio sudo[970]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:01 volumio wireless.js[642]: trying... Oct 30 22:52:01 volumio sudo[973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 22:52:01 volumio sudo[973]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:01 volumio sudo[973]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:02 volumio wireless.js[642]: trying... Oct 30 22:52:02 volumio sudo[976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 22:52:02 volumio sudo[976]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:02 volumio sudo[976]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:03 volumio volumio-time-update[653]: volumio-time-update-util: Fetching time from Volumio... Oct 30 22:52:03 volumio volumio-time-update[653]: volumio-time-update-util: Date not found in response Oct 30 22:52:03 volumio volumio-time-update[653]: volumio-time-update-util: Retrying in 5 seconds... Oct 30 22:52:03 volumio wireless.js[642]: trying... Oct 30 22:52:03 volumio sudo[984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 22:52:03 volumio sudo[984]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:03 volumio sudo[984]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:04 volumio wireless.js[642]: trying... Oct 30 22:52:04 volumio sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 22:52:04 volumio sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:04 volumio sudo[987]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:05 volumio wireless.js[642]: trying... Oct 30 22:52:05 volumio sudo[990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 22:52:05 volumio sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:05 volumio sudo[990]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:06 volumio volumio-remote-updater[647]: [2025-10-30 22:52:06] [info] asio async_connect error: system:111 (Connection refused) Oct 30 22:52:06 volumio volumio-remote-updater[647]: [2025-10-30 22:52:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 30 22:52:06 volumio volumio-remote-updater[647]: [2025-10-30 22:52:06] [error] handle_connect error: Underlying Transport Error Oct 30 22:52:06 volumio wireless.js[642]: trying... Oct 30 22:52:06 volumio sudo[993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 22:52:06 volumio sudo[993]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:06 volumio sudo[993]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:07 volumio wireless.js[642]: Overtime, starting plan B Oct 30 22:52:07 volumio systemd[1]: Started Wireless Services. Oct 30 22:52:07 volumio systemd[1]: Started Volumio Backend Module. Oct 30 22:52:07 volumio systemd[1]: Started Start Volumio Kiosk. Oct 30 22:52:08 volumio systemd[1]: Started Volumio Cpu Tweaker. Oct 30 22:52:08 volumio systemd[1]: Started Intel SST and HDA soundcard init service. Oct 30 22:52:08 volumio volumio-cpu-tweak[1000]: Setting RT Priority for mpd Oct 30 22:52:08 volumio volumio-cpu-tweak[1000]: Not enough cores to set MPD affinity Oct 30 22:52:08 volumio volumio-cpu-tweak[1000]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 30 22:52:08 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 30 22:52:08 volumio startx[999]: X.Org X Server 1.20.4 Oct 30 22:52:08 volumio startx[999]: X Protocol Version 11, Revision 0 Oct 30 22:52:08 volumio startx[999]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Oct 30 22:52:08 volumio startx[999]: Current Operating System: Linux volumio 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 Oct 30 22:52:08 volumio startx[999]: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=3dab4857-b198-4b00-9df2-948c9caf7d95 bootpart=UUID=681D-C082 datapart=UUID=0e8aee7b-fd34-42cb-aa20-93a7ec43c0ae hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no initrd=volumio.initrd Oct 30 22:52:08 volumio startx[999]: Build Date: 18 December 2021 09:05:36AM Oct 30 22:52:08 volumio startx[999]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Oct 30 22:52:08 volumio startx[999]: Current version of pixman: 0.36.0 Oct 30 22:52:08 volumio startx[999]: Before reporting problems, check http://wiki.x.org Oct 30 22:52:08 volumio startx[999]: to make sure that you have the latest version. Oct 30 22:52:08 volumio startx[999]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 30 22:52:08 volumio startx[999]: (++) from command line, (!!) notice, (II) informational, Oct 30 22:52:08 volumio startx[999]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 30 22:52:08 volumio startx[999]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Oct 30 22:52:08 2025 Oct 30 22:52:08 volumio startx[999]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 30 22:52:08 volumio startx[999]: (II) [KMS] Kernel modesetting enabled. Oct 30 22:52:08 volumio volumio-time-update[653]: volumio-time-update-util: Fetching time from Volumio... Oct 30 22:52:08 volumio volumio-time-update[653]: volumio-time-update-util: Date not found in response Oct 30 22:52:08 volumio volumio-time-update[653]: volumio-time-update-util: Retrying in 5 seconds... Oct 30 22:52:10 volumio soundcard-init.sh[1005]: amixer: Unable to find simple control 'Surround',0 Oct 30 22:52:10 volumio soundcard-init.sh[1005]: amixer: Unable to find simple control 'Center',0 Oct 30 22:52:10 volumio soundcard-init.sh[1005]: amixer: Unable to find simple control 'LFE',0 Oct 30 22:52:10 volumio soundcard-init.sh[1005]: amixer: Unable to find simple control 'IEC958',16 Oct 30 22:52:10 volumio soundcard-init.sh[1005]: amixer: Unable to find simple control 'Front',0 Oct 30 22:52:10 volumio soundcard-init.sh[1005]: Simple mixer control 'Headphone',0 Oct 30 22:52:10 volumio soundcard-init.sh[1005]: Capabilities: pvolume pswitch Oct 30 22:52:10 volumio soundcard-init.sh[1005]: Playback channels: Front Left - Front Right Oct 30 22:52:10 volumio soundcard-init.sh[1005]: Limits: Playback 0 - 87 Oct 30 22:52:10 volumio soundcard-init.sh[1005]: Mono: Oct 30 22:52:10 volumio soundcard-init.sh[1005]: Front Left: Playback 81 [93%] [-4.50dB] [on] Oct 30 22:52:10 volumio soundcard-init.sh[1005]: Front Right: Playback 81 [93%] [-4.50dB] [on] Oct 30 22:52:10 volumio soundcard-init.sh[1005]: Simple mixer control 'Master',0 Oct 30 22:52:10 volumio soundcard-init.sh[1005]: Capabilities: pvolume pvolume-joined pswitch pswitch-joined Oct 30 22:52:10 volumio soundcard-init.sh[1005]: Playback channels: Mono Oct 30 22:52:10 volumio soundcard-init.sh[1005]: Limits: Playback 0 - 87 Oct 30 22:52:10 volumio soundcard-init.sh[1005]: Mono: Playback 66 [76%] [-15.75dB] [on] Oct 30 22:52:10 volumio systemd[1]: soundcard-init.service: Succeeded. Oct 30 22:52:11 volumio systemd[1]: systemd-fsckd.service: Succeeded. Oct 30 22:52:12 volumio volumio[998]: info: ------------------------------------------- Oct 30 22:52:12 volumio volumio[998]: info: ----- Volumio3 ---- Oct 30 22:52:12 volumio volumio[998]: info: ------------------------------------------- Oct 30 22:52:12 volumio volumio[998]: info: ----- System startup ---- Oct 30 22:52:12 volumio volumio[998]: info: ------------------------------------------- Oct 30 22:52:13 volumio sh[596]: timed out Oct 30 22:52:13 volumio dhcpcd[633]: timed out Oct 30 22:52:13 volumio sh[596]: dhcpcd exited Oct 30 22:52:13 volumio dhcpcd[633]: dhcpcd exited Oct 30 22:52:13 volumio sh[596]: ifup: failed to bring up eth0 Oct 30 22:52:13 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Oct 30 22:52:13 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Oct 30 22:52:13 volumio volumio-time-update[653]: volumio-time-update-util: Fetching time from Volumio... Oct 30 22:52:13 volumio volumio-time-update[653]: volumio-time-update-util: Date not found in response Oct 30 22:52:13 volumio volumio-time-update[653]: volumio-time-update-util: Retrying in 5 seconds... Oct 30 22:52:14 volumio volumio[998]: info: MYVOLUMIO Environment detected Oct 30 22:52:14 volumio volumio[998]: info: Plugin folders cleanup Oct 30 22:52:14 volumio volumio[998]: info: Scanning into folder /volumio/app/plugins/ Oct 30 22:52:14 volumio volumio[998]: info: Scanning category audio_interface Oct 30 22:52:14 volumio volumio[998]: info: Scanning category miscellanea Oct 30 22:52:14 volumio volumio[998]: info: Scanning category music_service Oct 30 22:52:14 volumio volumio[998]: info: Scanning category plugins.json Oct 30 22:52:14 volumio volumio[998]: info: Scanning category system_controller Oct 30 22:52:14 volumio volumio[998]: info: Scanning category user_interface Oct 30 22:52:14 volumio volumio[998]: info: Scanning into folder /data/plugins/ Oct 30 22:52:14 volumio volumio[998]: info: Scanning category audio_interface Oct 30 22:52:14 volumio volumio[998]: info: Scanning category music_service Oct 30 22:52:14 volumio volumio[998]: info: Scanning category system_controller Oct 30 22:52:14 volumio volumio[998]: info: Scanning category user_interface Oct 30 22:52:14 volumio volumio[998]: info: Plugin folders cleanup completed Oct 30 22:52:14 volumio volumio[998]: info: ------------------------------------------- Oct 30 22:52:14 volumio volumio[998]: info: ----- Core plugins startup ---- Oct 30 22:52:14 volumio volumio[998]: info: ------------------------------------------- Oct 30 22:52:14 volumio volumio[998]: info: Loading plugins from folder /volumio/app/plugins/ Oct 30 22:52:14 volumio volumio[998]: info: Adding plugin upnp to MyMusic Plugins Oct 30 22:52:14 volumio volumio[998]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 30 22:52:14 volumio volumio[998]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 30 22:52:14 volumio volumio[998]: info: Loading plugins from folder /data/plugins/ Oct 30 22:52:14 volumio volumio[998]: info: Loading plugin "system"... Oct 30 22:52:14 volumio volumio[998]: info: Loading plugin "appearance"... Oct 30 22:52:16 volumio volumio-remote-updater[647]: [2025-10-30 22:52:16] [connect] Successful connection Oct 30 22:52:17 volumio volumio[998]: info: Loading plugin "network"... Oct 30 22:52:17 volumio volumio[998]: info: Refreshing Cached IP Addresses Oct 30 22:52:17 volumio sudo[1237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 30 22:52:17 volumio sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:18 volumio sudo[1237]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:18 volumio sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 22:52:18 volumio sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:18 volumio sudo[1239]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:18 volumio volumio[998]: info: Loading plugin "services"... Oct 30 22:52:18 volumio sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 30 22:52:18 volumio volumio[998]: info: Loading plugin "alsa_controller"... Oct 30 22:52:18 volumio sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:18 volumio sudo[1248]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:18 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 22:52:18 volumio volumio[998]: info: Loading plugin "wizard"... Oct 30 22:52:18 volumio volumio[998]: info: Loading plugin "networkfs"... Oct 30 22:52:18 volumio volumio[998]: info: Starting Udev Watcher for removable devices Oct 30 22:52:18 volumio volumio[998]: info: Ignoring mount for partition: boot Oct 30 22:52:18 volumio volumio[998]: info: Ignoring mount for partition: volumio Oct 30 22:52:18 volumio volumio[998]: info: Ignoring mount for partition: volumio_data Oct 30 22:52:18 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 22:52:18 volumio volumio[998]: info: Loading plugin "volumio_command_line_client"... Oct 30 22:52:18 volumio volumio[998]: info: Loading plugin "upnp"... Oct 30 22:52:18 volumio volumio[998]: info: [1761857538468] Starting Upmpd Daemon Oct 30 22:52:18 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 22:52:18 volumio volumio[998]: info: Loading plugin "my_music"... Oct 30 22:52:18 volumio volumio[998]: info: Loading plugin "mpd"... Oct 30 22:52:18 volumio volumio-time-update[653]: volumio-time-update-util: Fetching time from Volumio... Oct 30 22:52:18 volumio volumio-time-update[653]: volumio-time-update-util: Date not found in response Oct 30 22:52:18 volumio volumio-time-update[653]: volumio-time-update-util: Retrying in 5 seconds... Oct 30 22:52:19 volumio volumio[998]: info: Loading plugin "upnp_browser"... Oct 30 22:52:19 volumio volumio[998]: info: Loading plugin "alarm-clock"... Oct 30 22:52:19 volumio volumio[998]: info: Loading plugin "airplay_emulation"... Oct 30 22:52:19 volumio volumio[998]: info: Starting Shairport Sync Oct 30 22:52:19 volumio volumio[998]: info: Loading plugin "last_100"... Oct 30 22:52:20 volumio volumio[998]: info: Loading plugin "webradio"... Oct 30 22:52:20 volumio dbus-daemon[664]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.6' (uid=0 pid=1202 comm="/usr/lib/chromium/chromium --show-component-extens") Oct 30 22:52:20 volumio systemd[1]: Starting Bluetooth service... Oct 30 22:52:20 volumio volumio[998]: info: Loading plugin "i2s_dacs"... Oct 30 22:52:20 volumio kernel: cryptd: max_cpu_qlen set to 1000 Oct 30 22:52:20 volumio volumio[998]: info: I2S DAC not set, start Auto-detection Oct 30 22:52:20 volumio volumio[998]: info: Loading plugin "volumiodiscovery"... Oct 30 22:52:20 volumio volumio[998]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 30 22:52:20 volumio node[998]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 30 22:52:20 volumio volumio[998]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 22:52:20 volumio volumio[998]: *** WARNING *** For more information see Oct 30 22:52:20 volumio volumio[998]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 30 22:52:20 volumio volumio[998]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 22:52:20 volumio node[998]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 22:52:20 volumio node[998]: *** WARNING *** For more information see Oct 30 22:52:20 volumio node[998]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 30 22:52:20 volumio node[998]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 22:52:20 volumio volumio[998]: *** WARNING *** For more information see Oct 30 22:52:20 volumio node[998]: *** WARNING *** For more information see Oct 30 22:52:20 volumio volumio[998]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 30 22:52:20 volumio volumio[998]: info: Discovery: Started advertising with name: Volumio Oct 30 22:52:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 22:52:20 volumio volumio[998]: info: Loading plugin "calmradio"... Oct 30 22:52:21 volumio kernel: Bluetooth: Core ver 2.22 Oct 30 22:52:21 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Oct 30 22:52:21 volumio kernel: Bluetooth: HCI device and connection manager initialized Oct 30 22:52:21 volumio kernel: Bluetooth: HCI socket layer initialized Oct 30 22:52:21 volumio kernel: Bluetooth: L2CAP socket layer initialized Oct 30 22:52:21 volumio kernel: Bluetooth: SCO socket layer initialized Oct 30 22:52:21 volumio bluetoothd[1280]: Bluetooth daemon 5.50 Oct 30 22:52:21 volumio bluetoothd[1280]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Oct 30 22:52:21 volumio dbus-daemon[664]: [system] Successfully activated service 'org.bluez' Oct 30 22:52:21 volumio systemd[1]: Started Bluetooth service. Oct 30 22:52:21 volumio bluetoothd[1280]: Starting SDP server Oct 30 22:52:21 volumio bluetoothd[1280]: Excluding (cli) sap Oct 30 22:52:21 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Oct 30 22:52:21 volumio kernel: Bluetooth: BNEP filters: protocol multicast Oct 30 22:52:21 volumio kernel: Bluetooth: BNEP socket layer initialized Oct 30 22:52:21 volumio bluetoothd[1280]: Bluetooth management interface 1.22 initialized Oct 30 22:52:21 volumio dbus-daemon[664]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.9' (uid=0 pid=1280 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Oct 30 22:52:21 volumio systemd[1]: Starting Hostname Service... Oct 30 22:52:21 volumio dbus-daemon[664]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 30 22:52:21 volumio systemd[1]: Started Hostname Service. Oct 30 22:52:23 volumio volumio[998]: info: Loading plugin "soundcloud"... Oct 30 22:52:23 volumio volumio[998]: info: Loading plugin "spop"... Oct 30 22:52:23 volumio volumio-time-update[653]: volumio-time-update-util: Fetching time from Volumio... Oct 30 22:52:24 volumio volumio-time-update[653]: volumio-time-update-util: Date not found in response Oct 30 22:52:24 volumio volumio-time-update[653]: volumio-time-update-util: Retrying in 5 seconds... Oct 30 22:52:25 volumio volumio[998]: info: Loading plugin "youtube2"... Oct 30 22:52:29 volumio volumio-time-update[653]: volumio-time-update-util: Fetching time from Volumio... Oct 30 22:52:29 volumio volumio-time-update[653]: volumio-time-update-util: Date not found in response Oct 30 22:52:29 volumio volumio-time-update[653]: volumio-time-update-util: Retrying in 5 seconds... Oct 30 22:52:30 volumio volumio[998]: info: Loading plugin "ytcr"... Oct 30 22:52:31 volumio volumio-remote-updater[647]: [2025-10-30 22:52:31] [connect] Successful connection Oct 30 22:52:34 volumio volumio-time-update[653]: volumio-time-update-util: Fetching time from Volumio... Oct 30 22:52:34 volumio volumio-time-update[653]: volumio-time-update-util: Date not found in response Oct 30 22:52:34 volumio volumio-time-update[653]: volumio-time-update-util: Retrying in 5 seconds... Oct 30 22:52:36 volumio volumio[998]: info: Loading plugin "autostart"... Oct 30 22:52:36 volumio volumio[998]: info: Applying required configuration parameters for plugin autostart Oct 30 22:52:36 volumio volumio[998]: info: AutoStart - onVolumioStart - read config.json Oct 30 22:52:36 volumio volumio[998]: info: Loading plugin "now_playing"... Oct 30 22:52:38 volumio volumio[998]: info: Loading plugin "outputs"... Oct 30 22:52:38 volumio volumio[998]: info: Loading plugin "albumart"... Oct 30 22:52:38 volumio volumio[998]: info: Plugin example_plugin is not enabled Oct 30 22:52:38 volumio volumio[998]: info: Loading plugin "inputs"... Oct 30 22:52:38 volumio volumio[998]: info: Loading plugin "updater_comm"... Oct 30 22:52:38 volumio volumio[998]: Forking 1 albumart workers Oct 30 22:52:38 volumio volumio[998]: info: Plugin mpdemulation is not enabled Oct 30 22:52:38 volumio volumio[998]: info: Loading plugin "rest_api"... Oct 30 22:52:38 volumio volumio[998]: info: Loading plugin "websocket"... Oct 30 22:52:38 volumio volumio[998]: info: Starting Socket.io Server version 2.3.0 Oct 30 22:52:38 volumio volumio[998]: info: Loading plugin "fusiondsp"... Oct 30 22:52:39 volumio volumio-time-update[653]: volumio-time-update-util: Fetching time from Volumio... Oct 30 22:52:39 volumio volumio-time-update[653]: volumio-time-update-util: Date not found in response Oct 30 22:52:39 volumio volumio-time-update[653]: volumio-time-update-util: Retrying in 5 seconds... Oct 30 22:52:39 volumio volumio[998]: info: Applying required configuration parameters for plugin fusiondsp Oct 30 22:52:39 volumio volumio[998]: info: Loading plugin "personal_radio"... Oct 30 22:52:39 volumio volumio[998]: Starting albumart workers Oct 30 22:52:40 volumio volumio[998]: info: ControllerPersonalRadio::constructor Oct 30 22:52:40 volumio volumio[998]: info: Loading plugin "rad357pl"... Oct 30 22:52:40 volumio volumio[998]: info: Applying required configuration parameters for plugin rad357pl Oct 30 22:52:40 volumio volumio[998]: info: [1761857560862] [rad357pl] API delay: 5 Oct 30 22:52:40 volumio volumio[998]: info: Loading plugin "radio_paradise"... Oct 30 22:52:41 volumio volumio[998]: info: Applying required configuration parameters for plugin radio_paradise Oct 30 22:52:41 volumio volumio[998]: info: [1761857561248] [RadioParadise] API delay: 5 Oct 30 22:52:41 volumio volumio[998]: info: Loading plugin "backup_restore"... Oct 30 22:52:41 volumio volumio[998]: info: Applying required configuration parameters for plugin backup_restore Oct 30 22:52:41 volumio volumio[998]: info: Loading plugin "lastfm"... Oct 30 22:52:42 volumio volumio[998]: info: Plugin peppymeterbasic is not enabled Oct 30 22:52:42 volumio volumio[998]: info: Plugin peppyspectrum is not enabled Oct 30 22:52:42 volumio volumio[998]: info: Plugin randomizer is not enabled Oct 30 22:52:42 volumio volumio[998]: info: Loading i18n strings for locale en Oct 30 22:52:42 volumio volumio[998]: Updating browse sources language Oct 30 22:52:42 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:52:42 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 22:52:42 volumio volumio[998]: info: CoreCommandRouter::initPlayerControls Oct 30 22:52:42 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:42 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:42 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:42 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:42 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:42 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:42 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:42 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:42 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 22:52:42 volumio volumio[998]: Express server listening on port 3000 Oct 30 22:52:42 volumio volumio[998]: [Metrics] WebUI: 32s 98.87ms Oct 30 22:52:42 volumio volumio[998]: info: Setting Device type: x86 Oct 30 22:52:42 volumio volumio[998]: info: CoreStateMachine::resetVolumioState Oct 30 22:52:42 volumio volumio[998]: info: CoreStateMachine::getcurrentVolume Oct 30 22:52:42 volumio volumio[998]: info: CoreCommandRouter::volumioRetrievevolume Oct 30 22:52:42 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 22:52:43 volumio sudo[1470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 30 22:52:43 volumio sudo[1470]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:43 volumio sudo[1470]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:43 volumio sudo[1473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 22:52:43 volumio sudo[1473]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:43 volumio volumio[998]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 30 22:52:43 volumio volumio[998]: info: Completed loading Core Plugins Oct 30 22:52:43 volumio volumio[998]: info: Preparing to generate the ALSA configuration file Oct 30 22:52:43 volumio sudo[1473]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:43 volumio volumio[998]: info: Volumio Network Manager: Network status updated: 0 Oct 30 22:52:43 volumio volumio[998]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Oct 30 22:52:43 volumio volumio[998]: wlan0 Interface doesn't support scanning : Network is down Oct 30 22:52:43 volumio volumio[998]: info: Cannot use regular scanning, forcing with ap-force Oct 30 22:52:43 volumio sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Oct 30 22:52:43 volumio sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:43 volumio sudo[1477]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:43 volumio volumio[998]: command failed: Network is down (-100) Oct 30 22:52:43 volumio volumio[998]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Oct 30 22:52:43 volumio volumio[998]: command failed: Network is down (-100) Oct 30 22:52:43 volumio volumio[998]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 30 22:52:43 volumio volumio[998]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 30 22:52:43 volumio volumio[998]: info: Reading ALSA contributions from plugins. Oct 30 22:52:43 volumio volumio[998]: info: VolumeController:: Volume=100 Mute =false Oct 30 22:52:43 volumio volumio[998]: info: CoreStateMachine::pushState Oct 30 22:52:43 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Oct 30 22:52:43 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 22:52:43 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Oct 30 22:52:43 volumio volumio[998]: info: CoreStateMachine::updateTrackBlock Oct 30 22:52:43 volumio volumio[998]: info: CorePlayQueue::getTrackBlock Oct 30 22:52:43 volumio volumio[998]: info: CoreCommandRouter::volumioRetrievevolume Oct 30 22:52:43 volumio volumio[998]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 30 22:52:43 volumio volumio[998]: info: Reloading queue from file Oct 30 22:52:43 volumio volumio[998]: info: CoreStateMachine::setRepeat false single undefined Oct 30 22:52:43 volumio volumio[998]: info: CoreStateMachine::pushState Oct 30 22:52:43 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Oct 30 22:52:43 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 22:52:43 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Oct 30 22:52:43 volumio volumio[998]: info: CoreStateMachine::setRandom null Oct 30 22:52:43 volumio volumio[998]: info: CoreStateMachine::pushState Oct 30 22:52:43 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Oct 30 22:52:43 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Oct 30 22:52:43 volumio sudo[1515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 30 22:52:43 volumio sudo[1515]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:44 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 30 22:52:44 volumio volumio[998]: info: VolumeController:: Volume=100 Mute =false Oct 30 22:52:44 volumio volumio[998]: info: CoreStateMachine::pushState Oct 30 22:52:44 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Oct 30 22:52:44 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Oct 30 22:52:44 volumio sudo[1515]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:44 volumio volumio[998]: info: Asound.conf file unchanged, so no further update is needed Oct 30 22:52:44 volumio volumio[998]: info: Output device has changed, restarting MPD Oct 30 22:52:44 volumio sudo[1519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 30 22:52:44 volumio sudo[1519]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:44 volumio volumio-time-update[653]: volumio-time-update-util: Fetching time from Volumio... Oct 30 22:52:44 volumio sudo[1519]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:44 volumio volumio[998]: info: Output device has changed, restarting Shairport Sync Oct 30 22:52:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 22:52:44 volumio volumio-time-update[653]: volumio-time-update-util: Date not found in response Oct 30 22:52:44 volumio volumio-time-update[653]: volumio-time-update-util: Retrying in 5 seconds... Oct 30 22:52:44 volumio sudo[1527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 30 22:52:44 volumio sudo[1527]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:44 volumio systemd[1]: Stopping Music Player Daemon... Oct 30 22:52:44 volumio systemd[1]: mpd.service: Succeeded. Oct 30 22:52:44 volumio systemd[1]: Stopped Music Player Daemon. Oct 30 22:52:44 volumio systemd[1]: Starting Music Player Daemon... Oct 30 22:52:44 volumio volumio[998]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 22:52:44 volumio volumio[998]: info: ___________ START PLUGINS ___________ Oct 30 22:52:44 volumio volumio[998]: info: ControllerMpd::onStart: Initializing MPD Oct 30 22:52:44 volumio volumio[998]: info: Creating MPD Configuration file Oct 30 22:52:44 volumio sudo[1535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 30 22:52:44 volumio sudo[1535]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:44 volumio sudo[1533]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 30 22:52:44 volumio sudo[1533]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:44 volumio sudo[1533]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:44 volumio sudo[1535]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 22:52:44 volumio volumio[998]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:52:44 volumio volumio[998]: info: [1761857564745] CoreMusicLibrary::Adding element Media Servers Oct 30 22:52:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:52:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 22:52:44 volumio sudo[1540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 30 22:52:44 volumio sudo[1540]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:44 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 30 22:52:44 volumio systemd[1]: mpd.service: Succeeded. Oct 30 22:52:44 volumio systemd[1]: Stopped Music Player Daemon. Oct 30 22:52:44 volumio systemd[1]: Starting Music Player Daemon... Oct 30 22:52:44 volumio volumio[998]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 22:52:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 22:52:45 volumio sudo[1545]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 30 22:52:45 volumio sudo[1545]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:45 volumio sudo[1545]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:45 volumio volumio[998]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 22:52:45 volumio volumio[998]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:52:45 volumio volumio[998]: info: [1761857565211] CoreMusicLibrary::Adding element Last_100 Oct 30 22:52:45 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:52:45 volumio volumio[998]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:52:45 volumio volumio[998]: info: [1761857565220] CoreMusicLibrary::Adding element Webradio Oct 30 22:52:45 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:52:45 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 22:52:45 volumio volumio[998]: info: Initializing BBC Radios Oct 30 22:52:45 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 22:52:45 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 22:52:45 volumio volumio[998]: info: Adding Calm Radio to Browse Sources Oct 30 22:52:45 volumio volumio[998]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:52:45 volumio volumio[998]: info: [1761857565610] CoreMusicLibrary::Adding element Calm Radio Oct 30 22:52:45 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:52:45 volumio volumio[998]: Cannot find translation for source Calm Radio Oct 30 22:52:45 volumio volumio[998]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:52:45 volumio volumio[998]: info: [1761857565641] CoreMusicLibrary::Adding element SoundCloud Oct 30 22:52:45 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:52:45 volumio volumio[998]: Cannot find translation for source Calm Radio Oct 30 22:52:45 volumio volumio[998]: Cannot find translation for source SoundCloud Oct 30 22:52:45 volumio volumio[998]: info: Creating Spotify config file Oct 30 22:52:45 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:45 volumio volumio[998]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:52:45 volumio volumio[998]: info: [1761857565856] CoreMusicLibrary::Adding element YouTube2 Oct 30 22:52:45 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:52:45 volumio volumio[998]: Cannot find translation for source Calm Radio Oct 30 22:52:45 volumio volumio[998]: Cannot find translation for source SoundCloud Oct 30 22:52:45 volumio volumio[998]: Cannot find translation for source YouTube2 Oct 30 22:52:45 volumio volumio[998]: info: AutoStart - onStart Oct 30 22:52:45 volumio volumio[998]: info: [now-playing] ConfigUpdater: config is up to date. Oct 30 22:52:45 volumio volumio[998]: info: Loading i18n strings for locale en Oct 30 22:52:46 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 30 22:52:46 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:46 volumio volumio[998]: info: FusionDsp - mixtype--------------------- Hardware Oct 30 22:52:46 volumio volumio[998]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:52:46 volumio volumio[998]: info: [1761857566056] CoreMusicLibrary::Adding element Personal Radio Oct 30 22:52:46 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source Calm Radio Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source SoundCloud Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source YouTube2 Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source Personal Radio Oct 30 22:52:46 volumio volumio[998]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:52:46 volumio volumio[998]: info: [1761857566077] CoreMusicLibrary::Adding element RADIO 357 Oct 30 22:52:46 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source Calm Radio Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source SoundCloud Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source YouTube2 Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source Personal Radio Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source RADIO 357 Oct 30 22:52:46 volumio volumio[998]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:52:46 volumio volumio[998]: info: [1761857566101] CoreMusicLibrary::Adding element Radio Paradise Oct 30 22:52:46 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source Calm Radio Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source SoundCloud Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source YouTube2 Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source Personal Radio Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source RADIO 357 Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source Radio Paradise Oct 30 22:52:46 volumio volumio[998]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:52:46 volumio volumio[998]: info: [1761857566113] CoreMusicLibrary::Adding element LastFM Oct 30 22:52:46 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source Calm Radio Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source SoundCloud Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source YouTube2 Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source Personal Radio Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source RADIO 357 Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source Radio Paradise Oct 30 22:52:46 volumio volumio[998]: Cannot find translation for source LastFM Oct 30 22:52:46 volumio volumio[998]: info: [LastFM] scrobbler initiated! Oct 30 22:52:46 volumio volumio[998]: info: [LastFM] extended logging: false Oct 30 22:52:46 volumio volumio[998]: info: [LastFM] try scrobble stream/radio plays: true Oct 30 22:52:46 volumio volumio[998]: info: [LastFM] Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Oct 30 22:52:46 volumio volumio[998]: info: [LastFM] Left init routine Oct 30 22:52:46 volumio volumio[998]: info: [LastFM] Socket already connected: true Oct 30 22:52:46 volumio volumio[998]: info: Volumio Calling Home Oct 30 22:52:46 volumio volumio[998]: info: Preparing to generate the ALSA configuration file Oct 30 22:52:46 volumio volumio[998]: info: [LastFM] finished init with error: Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Oct 30 22:52:46 volumio volumio-remote-updater[647]: [2025-10-30 22:52:46] [connect] Successful connection Oct 30 22:52:46 volumio volumio[998]: info: [now-playing] App is listening on port 4004. Oct 30 22:52:46 volumio volumio[998]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Oct 30 22:52:46 volumio volumio[998]: info: Upmpdcli Daemon Started Oct 30 22:52:46 volumio volumio[998]: Unhandled rejection Error: No sockets available, cannot start. Oct 30 22:52:46 volumio volumio[998]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Oct 30 22:52:46 volumio volumio[998]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Oct 30 22:52:46 volumio volumio[998]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Oct 30 22:52:46 volumio volumio[998]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Oct 30 22:52:46 volumio volumio[998]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Oct 30 22:52:46 volumio volumio[998]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Oct 30 22:52:46 volumio volumio[998]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Oct 30 22:52:46 volumio volumio[998]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Oct 30 22:52:46 volumio volumio[998]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Oct 30 22:52:46 volumio volumio[998]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Oct 30 22:52:46 volumio volumio[998]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Oct 30 22:52:46 volumio volumio[998]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 30 22:52:46 volumio volumio[998]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 30 22:52:46 volumio volumio[998]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Oct 30 22:52:46 volumio volumio[998]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Oct 30 22:52:46 volumio volumio[998]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 30 22:52:46 volumio volumio[998]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Oct 30 22:52:46 volumio volumio[998]: info: ControllerPersonalRadio:fetchRadioUrl Error: FetchError: request to https://raw.githubusercontent.com/ChrisPanda/volumio-kradio-key/master/radiokey.json failed, reason: getaddrinfo EBUSY raw.githubusercontent.com Oct 30 22:52:46 volumio volumio[998]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 30 22:52:46 volumio volumio[998]: info: Reading ALSA contributions from plugins. Oct 30 22:52:46 volumio volumio[998]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (1jm7gbg6jgus79v098ldgd2p9p): Oct 30 22:52:46 volumio volumio[998]: (ConnectionError) (YouTube) Connection error in getting lounge token Oct 30 22:52:46 volumio volumio[998]: Error info: { Oct 30 22:52:46 volumio volumio[998]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Oct 30 22:52:46 volumio volumio[998]: } Oct 30 22:52:46 volumio volumio[998]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Oct 30 22:52:46 volumio volumio[998]: Stack trace: Oct 30 22:52:46 volumio volumio[998]: ConnectionError: (YouTube) Connection error in getting lounge token Oct 30 22:52:46 volumio volumio[998]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Oct 30 22:52:46 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:46 volumio volumio[998]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Oct 30 22:52:46 volumio volumio[998]: at async Promise.all (index 0) Oct 30 22:52:46 volumio volumio[998]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Oct 30 22:52:46 volumio volumio[998]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 30 22:52:46 volumio volumio[998]: Going to generate fresh screen Id and try again... Oct 30 22:52:46 volumio volumio[998]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (44an2ck2ket60dd6dni7pbtqsd): Oct 30 22:52:46 volumio volumio[998]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Oct 30 22:52:46 volumio volumio[998]: Error info: { Oct 30 22:52:46 volumio volumio[998]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Oct 30 22:52:46 volumio volumio[998]: } Oct 30 22:52:46 volumio volumio[998]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Oct 30 22:52:46 volumio volumio[998]: Stack trace: Oct 30 22:52:46 volumio volumio[998]: ConnectionError: (YouTube Music) Connection error in getting lounge token Oct 30 22:52:46 volumio volumio[998]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Oct 30 22:52:46 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:46 volumio volumio[998]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Oct 30 22:52:46 volumio volumio[998]: at async Promise.all (index 1) Oct 30 22:52:46 volumio volumio[998]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Oct 30 22:52:46 volumio volumio[998]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 30 22:52:46 volumio volumio[998]: Going to generate fresh screen Id and try again... Oct 30 22:52:46 volumio volumio-remote-updater[647]: [2025-10-30 22:52:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1761857566 101 Oct 30 22:52:46 volumio volumio[998]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Oct 30 22:52:46 volumio volumio[998]: info: MPD Permissions set Oct 30 22:52:46 volumio volumio[998]: info: MPD Permissions set Oct 30 22:52:47 volumio volumio[998]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Oct 30 22:52:47 volumio volumio[998]: LoungeScreenDisconnected { Oct 30 22:52:47 volumio volumio[998]: AID: null, Oct 30 22:52:47 volumio volumio[998]: name: 'loungeScreenDisconnected', Oct 30 22:52:47 volumio volumio[998]: payload: {} Oct 30 22:52:47 volumio volumio[998]: } Oct 30 22:52:47 volumio volumio[998]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 30 22:52:47 volumio volumio[998]: Stack trace: Oct 30 22:52:47 volumio volumio[998]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 30 22:52:47 volumio volumio[998]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 30 22:52:47 volumio volumio[998]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 30 22:52:47 volumio volumio[998]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 30 22:52:47 volumio volumio[998]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Oct 30 22:52:47 volumio volumio[998]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Oct 30 22:52:47 volumio volumio[998]: at new Promise () Oct 30 22:52:47 volumio volumio[998]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Oct 30 22:52:47 volumio volumio[998]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Oct 30 22:52:47 volumio volumio[998]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: at async Promise.all (index 0) Oct 30 22:52:47 volumio volumio[998]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Oct 30 22:52:47 volumio volumio[998]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 30 22:52:47 volumio volumio[998]: error: [yt-cast-receiver] Failed to start receiver: Oct 30 22:52:47 volumio volumio[998]: (AppError) Failed to start YouTubeApp Oct 30 22:52:47 volumio volumio[998]: --->(SessionError) (YouTube) Failed to establish session Oct 30 22:52:47 volumio volumio[998]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Oct 30 22:52:47 volumio volumio[998]: Error info: { Oct 30 22:52:47 volumio volumio[998]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Oct 30 22:52:47 volumio volumio[998]: } Oct 30 22:52:47 volumio volumio[998]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Oct 30 22:52:47 volumio volumio[998]: Stack trace: Oct 30 22:52:47 volumio volumio[998]: AppError: Failed to start YouTubeApp Oct 30 22:52:47 volumio volumio[998]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 30 22:52:47 volumio volumio[998]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Oct 30 22:52:47 volumio volumio[998]: LoungeScreenDisconnected { Oct 30 22:52:47 volumio volumio[998]: AID: null, Oct 30 22:52:47 volumio volumio[998]: name: 'loungeScreenDisconnected', Oct 30 22:52:47 volumio volumio[998]: payload: {} Oct 30 22:52:47 volumio volumio[998]: } Oct 30 22:52:47 volumio volumio[998]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 30 22:52:47 volumio volumio[998]: Stack trace: Oct 30 22:52:47 volumio volumio[998]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 30 22:52:47 volumio volumio[998]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 30 22:52:47 volumio volumio[998]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 30 22:52:47 volumio volumio[998]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 30 22:52:47 volumio volumio[998]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Oct 30 22:52:47 volumio volumio[998]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Oct 30 22:52:47 volumio volumio[998]: at new Promise () Oct 30 22:52:47 volumio volumio[998]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Oct 30 22:52:47 volumio volumio[998]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Oct 30 22:52:47 volumio volumio[998]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 Oct 30 22:52:47 volumio volumio[998]: at Array.forEach () Oct 30 22:52:47 volumio volumio[998]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 30 22:52:47 volumio volumio[998]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Oct 30 22:52:47 volumio volumio[998]: LoungeScreenDisconnected { Oct 30 22:52:47 volumio volumio[998]: AID: null, Oct 30 22:52:47 volumio volumio[998]: name: 'loungeScreenDisconnected', Oct 30 22:52:47 volumio volumio[998]: payload: {} Oct 30 22:52:47 volumio volumio[998]: } Oct 30 22:52:47 volumio volumio[998]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 30 22:52:47 volumio volumio[998]: Stack trace: Oct 30 22:52:47 volumio volumio[998]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 30 22:52:47 volumio volumio[998]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 30 22:52:47 volumio volumio[998]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 30 22:52:47 volumio volumio[998]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 30 22:52:47 volumio volumio[998]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: error: [ytcr] Failed to start plugin: Oct 30 22:52:47 volumio volumio[998]: (AppError) Failed to start YouTubeApp Oct 30 22:52:47 volumio volumio[998]: --->(SessionError) (YouTube) Failed to establish session Oct 30 22:52:47 volumio volumio[998]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Oct 30 22:52:47 volumio volumio[998]: Error info: { Oct 30 22:52:47 volumio volumio[998]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Oct 30 22:52:47 volumio volumio[998]: } Oct 30 22:52:47 volumio volumio[998]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Oct 30 22:52:47 volumio volumio[998]: Stack trace: Oct 30 22:52:47 volumio volumio[998]: AppError: Failed to start YouTubeApp Oct 30 22:52:47 volumio volumio[998]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 30 22:52:47 volumio volumio[998]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Oct 30 22:52:47 volumio volumio[998]: LoungeScreenDisconnected { Oct 30 22:52:47 volumio volumio[998]: AID: null, Oct 30 22:52:47 volumio volumio[998]: name: 'loungeScreenDisconnected', Oct 30 22:52:47 volumio volumio[998]: payload: {} Oct 30 22:52:47 volumio volumio[998]: } Oct 30 22:52:47 volumio volumio[998]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 30 22:52:47 volumio volumio[998]: Stack trace: Oct 30 22:52:47 volumio volumio[998]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 30 22:52:47 volumio volumio[998]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 30 22:52:47 volumio volumio[998]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 30 22:52:47 volumio volumio[998]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 30 22:52:47 volumio volumio[998]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Oct 30 22:52:47 volumio volumio[998]: info: Spotify config file written Oct 30 22:52:47 volumio volumio[998]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Oct 30 22:52:47 volumio volumio[998]: LoungeScreenDisconnected { Oct 30 22:52:47 volumio volumio[998]: AID: null, Oct 30 22:52:47 volumio volumio[998]: name: 'loungeScreenDisconnected', Oct 30 22:52:47 volumio volumio[998]: payload: {} Oct 30 22:52:47 volumio volumio[998]: } Oct 30 22:52:47 volumio volumio[998]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 30 22:52:47 volumio volumio[998]: Stack trace: Oct 30 22:52:47 volumio volumio[998]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 30 22:52:47 volumio volumio[998]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 30 22:52:47 volumio volumio[998]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 30 22:52:47 volumio volumio[998]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 30 22:52:47 volumio volumio[998]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Oct 30 22:52:47 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Oct 30 22:52:47 volumio volumio[998]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: (Use `node --trace-warnings ...` to show where the warning was created) Oct 30 22:52:47 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 8) Oct 30 22:52:47 volumio volumio[998]: (node:998) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Oct 30 22:52:47 volumio mpd[1548]: Oct 30 22:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 30 22:52:47 volumio systemd[1]: Started Music Player Daemon. Oct 30 22:52:47 volumio sudo[1540]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:47 volumio sudo[1565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 30 22:52:47 volumio volumio[998]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Oct 30 22:52:47 volumio volumio[998]: LoungeScreenDisconnected { Oct 30 22:52:47 volumio volumio[998]: AID: null, Oct 30 22:52:47 volumio volumio[998]: name: 'loungeScreenDisconnected', Oct 30 22:52:47 volumio volumio[998]: payload: {} Oct 30 22:52:47 volumio volumio[998]: } Oct 30 22:52:47 volumio volumio[998]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 30 22:52:47 volumio volumio[998]: Stack trace: Oct 30 22:52:47 volumio volumio[998]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 30 22:52:47 volumio volumio[998]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 30 22:52:47 volumio volumio[998]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 30 22:52:47 volumio volumio[998]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 30 22:52:47 volumio volumio[998]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio sudo[1527]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:47 volumio sudo[1565]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:47 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Oct 30 22:52:47 volumio volumio[998]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 10) Oct 30 22:52:47 volumio volumio[998]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Oct 30 22:52:47 volumio volumio[998]: LoungeScreenDisconnected { Oct 30 22:52:47 volumio volumio[998]: AID: null, Oct 30 22:52:47 volumio volumio[998]: name: 'loungeScreenDisconnected', Oct 30 22:52:47 volumio volumio[998]: payload: {} Oct 30 22:52:47 volumio volumio[998]: } Oct 30 22:52:47 volumio volumio[998]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 30 22:52:47 volumio volumio[998]: Stack trace: Oct 30 22:52:47 volumio volumio[998]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 30 22:52:47 volumio volumio[998]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 30 22:52:47 volumio volumio[998]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 30 22:52:47 volumio volumio[998]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 30 22:52:47 volumio volumio[998]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Oct 30 22:52:47 volumio volumio[998]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 12) Oct 30 22:52:47 volumio volumio[998]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Oct 30 22:52:47 volumio volumio[998]: LoungeScreenDisconnected { Oct 30 22:52:47 volumio volumio[998]: AID: null, Oct 30 22:52:47 volumio volumio[998]: name: 'loungeScreenDisconnected', Oct 30 22:52:47 volumio volumio[998]: payload: {} Oct 30 22:52:47 volumio volumio[998]: } Oct 30 22:52:47 volumio volumio[998]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 30 22:52:47 volumio volumio[998]: Stack trace: Oct 30 22:52:47 volumio volumio[998]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 30 22:52:47 volumio volumio[998]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 30 22:52:47 volumio volumio[998]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 30 22:52:47 volumio volumio[998]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 30 22:52:47 volumio volumio[998]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Oct 30 22:52:47 volumio volumio[998]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 14) Oct 30 22:52:47 volumio systemd[1]: Started go-librespot Daemon. Oct 30 22:52:47 volumio go-librespot[1569]: go-librespot daemon starting... Oct 30 22:52:47 volumio volumio[998]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 30 22:52:47 volumio sudo[1565]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 30 22:52:47 volumio go-librespot[1569]: time="2025-10-30T22:52:47+02:00" level=info msg="running go-librespot 0.2.0" Oct 30 22:52:47 volumio go-librespot[1569]: time="2025-10-30T22:52:47+02:00" level=debug msg="app state loaded" Oct 30 22:52:47 volumio go-librespot[1569]: time="2025-10-30T22:52:47+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 22:52:47 volumio go-librespot[1569]: time="2025-10-30T22:52:47+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 30 22:52:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 22:52:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 30 22:52:47 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:47 volumio volumio[998]: info: No need to fix Spotify hosts Oct 30 22:52:47 volumio volumio[998]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Oct 30 22:52:47 volumio volumio[998]: LoungeScreenDisconnected { Oct 30 22:52:47 volumio volumio[998]: AID: null, Oct 30 22:52:47 volumio volumio[998]: name: 'loungeScreenDisconnected', Oct 30 22:52:47 volumio volumio[998]: payload: {} Oct 30 22:52:47 volumio volumio[998]: } Oct 30 22:52:47 volumio volumio[998]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 30 22:52:47 volumio volumio[998]: Stack trace: Oct 30 22:52:47 volumio volumio[998]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 30 22:52:47 volumio volumio[998]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 30 22:52:47 volumio volumio[998]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 30 22:52:47 volumio volumio[998]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 30 22:52:47 volumio volumio[998]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Oct 30 22:52:47 volumio volumio[998]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 16) Oct 30 22:52:47 volumio volumio[998]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Oct 30 22:52:47 volumio volumio[998]: LoungeScreenDisconnected { Oct 30 22:52:47 volumio volumio[998]: AID: null, Oct 30 22:52:47 volumio volumio[998]: name: 'loungeScreenDisconnected', Oct 30 22:52:47 volumio volumio[998]: payload: {} Oct 30 22:52:47 volumio volumio[998]: } Oct 30 22:52:47 volumio volumio[998]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 30 22:52:47 volumio volumio[998]: Stack trace: Oct 30 22:52:47 volumio volumio[998]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 30 22:52:47 volumio volumio[998]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 30 22:52:47 volumio volumio[998]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 30 22:52:47 volumio volumio[998]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 30 22:52:47 volumio volumio[998]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Oct 30 22:52:47 volumio volumio[998]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 18) Oct 30 22:52:47 volumio volumio[998]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Oct 30 22:52:47 volumio volumio[998]: LoungeScreenDisconnected { Oct 30 22:52:47 volumio volumio[998]: AID: null, Oct 30 22:52:47 volumio volumio[998]: name: 'loungeScreenDisconnected', Oct 30 22:52:47 volumio volumio[998]: payload: {} Oct 30 22:52:47 volumio volumio[998]: } Oct 30 22:52:47 volumio volumio[998]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 30 22:52:47 volumio volumio[998]: Stack trace: Oct 30 22:52:47 volumio volumio[998]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 30 22:52:47 volumio volumio[998]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 30 22:52:47 volumio volumio[998]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 30 22:52:47 volumio volumio[998]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 30 22:52:47 volumio volumio[998]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Oct 30 22:52:47 volumio volumio[998]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 20) Oct 30 22:52:47 volumio volumio[998]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Oct 30 22:52:47 volumio volumio[998]: LoungeScreenDisconnected { Oct 30 22:52:47 volumio volumio[998]: AID: null, Oct 30 22:52:47 volumio volumio[998]: name: 'loungeScreenDisconnected', Oct 30 22:52:47 volumio volumio[998]: payload: {} Oct 30 22:52:47 volumio volumio[998]: } Oct 30 22:52:47 volumio volumio[998]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 30 22:52:47 volumio volumio[998]: Stack trace: Oct 30 22:52:47 volumio volumio[998]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 30 22:52:47 volumio volumio[998]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 30 22:52:47 volumio volumio[998]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 30 22:52:47 volumio volumio[998]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 30 22:52:47 volumio volumio[998]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 30 22:52:47 volumio volumio[998]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Oct 30 22:52:47 volumio volumio[998]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Oct 30 22:52:47 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:47 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 22) Oct 30 22:52:47 volumio volumio[998]: Upnp client error: Error: This socket has been ended by the other party Oct 30 22:52:47 volumio volumio[998]: info: Completed starting Core Plugins Oct 30 22:52:47 volumio volumio[998]: info: ------------------------------------------- Oct 30 22:52:47 volumio volumio[998]: info: ----- MyVolumio plugins startup ---- Oct 30 22:52:47 volumio volumio[998]: info: ------------------------------------------- Oct 30 22:52:47 volumio volumio[998]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 30 22:52:48 volumio volumio[998]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Oct 30 22:52:48 volumio volumio[998]: LoungeScreenDisconnected { Oct 30 22:52:48 volumio volumio[998]: AID: null, Oct 30 22:52:48 volumio volumio[998]: name: 'loungeScreenDisconnected', Oct 30 22:52:48 volumio volumio[998]: payload: {} Oct 30 22:52:48 volumio volumio[998]: } Oct 30 22:52:48 volumio volumio[998]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 30 22:52:48 volumio volumio[998]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 30 22:52:48 volumio volumio[998]: Stack trace: Oct 30 22:52:48 volumio volumio[998]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 30 22:52:48 volumio volumio[998]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 30 22:52:48 volumio volumio[998]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 30 22:52:48 volumio volumio[998]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 30 22:52:48 volumio volumio[998]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 30 22:52:48 volumio volumio[998]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 30 22:52:48 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:48 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Oct 30 22:52:48 volumio volumio[998]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Oct 30 22:52:48 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:48 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 24) Oct 30 22:52:48 volumio volumio[998]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Oct 30 22:52:48 volumio volumio[998]: LoungeScreenDisconnected { Oct 30 22:52:48 volumio volumio[998]: AID: null, Oct 30 22:52:48 volumio volumio[998]: name: 'loungeScreenDisconnected', Oct 30 22:52:48 volumio volumio[998]: payload: {} Oct 30 22:52:48 volumio volumio[998]: } Oct 30 22:52:48 volumio volumio[998]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 30 22:52:48 volumio volumio[998]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 30 22:52:48 volumio volumio[998]: Stack trace: Oct 30 22:52:48 volumio volumio[998]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 30 22:52:48 volumio volumio[998]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 30 22:52:48 volumio volumio[998]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 30 22:52:48 volumio volumio[998]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 30 22:52:48 volumio volumio[998]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 30 22:52:48 volumio volumio[998]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 30 22:52:48 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:48 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Oct 30 22:52:48 volumio volumio[998]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Oct 30 22:52:48 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:48 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 26) Oct 30 22:52:48 volumio volumio[998]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Oct 30 22:52:48 volumio volumio[998]: LoungeScreenDisconnected { Oct 30 22:52:48 volumio volumio[998]: AID: null, Oct 30 22:52:48 volumio volumio[998]: name: 'loungeScreenDisconnected', Oct 30 22:52:48 volumio volumio[998]: payload: {} Oct 30 22:52:48 volumio volumio[998]: } Oct 30 22:52:48 volumio volumio[998]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 30 22:52:48 volumio volumio[998]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 30 22:52:48 volumio volumio[998]: Stack trace: Oct 30 22:52:48 volumio volumio[998]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 30 22:52:48 volumio volumio[998]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 30 22:52:48 volumio volumio[998]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 30 22:52:48 volumio volumio[998]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 30 22:52:48 volumio volumio[998]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 30 22:52:48 volumio volumio[998]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 30 22:52:48 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:48 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Oct 30 22:52:48 volumio volumio[998]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Oct 30 22:52:48 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:48 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 28) Oct 30 22:52:48 volumio volumio[998]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Oct 30 22:52:48 volumio volumio[998]: LoungeScreenDisconnected { Oct 30 22:52:48 volumio volumio[998]: AID: null, Oct 30 22:52:48 volumio volumio[998]: name: 'loungeScreenDisconnected', Oct 30 22:52:48 volumio volumio[998]: payload: {} Oct 30 22:52:48 volumio volumio[998]: } Oct 30 22:52:48 volumio volumio[998]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 30 22:52:48 volumio volumio[998]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 30 22:52:48 volumio volumio[998]: Stack trace: Oct 30 22:52:48 volumio volumio[998]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 30 22:52:48 volumio volumio[998]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 30 22:52:48 volumio volumio[998]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 30 22:52:48 volumio volumio[998]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 30 22:52:48 volumio volumio[998]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 30 22:52:48 volumio volumio[998]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 30 22:52:48 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:48 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Oct 30 22:52:48 volumio volumio[998]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Oct 30 22:52:48 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 30 22:52:48 volumio volumio[998]: (node:998) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 30) Oct 30 22:52:48 volumio volumio[998]: info: camilladsp service started and running in background, instance 1 Oct 30 22:52:48 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:52:48 volumio volumio[998]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 30 22:52:48 volumio volumio[998]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 30 22:52:48 volumio volumio[998]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 30 22:52:48 volumio volumio[998]: info: FusionDsp loaded Oct 30 22:52:48 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 22:52:48 volumio sudo[1599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 30 22:52:48 volumio volumio[998]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 30 22:52:48 volumio sudo[1599]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:48 volumio volumio[998]: info: Adding Signal Path Element [object Object] Oct 30 22:52:48 volumio volumio[998]: info: Adding fusiondspeq DSP Signal Path Element Oct 30 22:52:48 volumio volumio[998]: info: FusionDsp - ---- installed callbackRead Oct 30 22:52:48 volumio volumio[998]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 22:52:48 volumio volumio[998]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 30 22:52:48 volumio volumio[998]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Oct 30 22:52:48 volumio volumio[998]: at onErrorNT (internal/child_process.js:472:16) Oct 30 22:52:48 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 30 22:52:48 volumio volumio[998]: at runNextTicks (internal/process/task_queues.js:64:3) Oct 30 22:52:48 volumio volumio[998]: at listOnTimeout (internal/timers.js:526:9) Oct 30 22:52:48 volumio volumio[998]: at processTimers (internal/timers.js:500:7) { Oct 30 22:52:48 volumio volumio[998]: errno: -2, Oct 30 22:52:48 volumio volumio[998]: code: 'ENOENT', Oct 30 22:52:48 volumio volumio[998]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 30 22:52:48 volumio volumio[998]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 30 22:52:48 volumio volumio[998]: spawnargs: [ Oct 30 22:52:48 volumio volumio[998]: '-p', Oct 30 22:52:48 volumio volumio[998]: 9876, Oct 30 22:52:48 volumio volumio[998]: '-o', Oct 30 22:52:48 volumio volumio[998]: '/tmp/camilladsp.log', Oct 30 22:52:48 volumio volumio[998]: '-l', Oct 30 22:52:48 volumio volumio[998]: 'warn', Oct 30 22:52:48 volumio volumio[998]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 30 22:52:48 volumio volumio[998]: ] Oct 30 22:52:48 volumio volumio[998]: } Oct 30 22:52:48 volumio volumio[998]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 22:52:48 volumio systemd[1]: Started FusionDsp Daemon. Oct 30 22:52:48 volumio sudo[1599]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:48 volumio volumio[1602]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Oct 30 22:52:48 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Oct 30 22:52:48 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 30 22:52:48 volumio sudo[1625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-30 22:51 Oct 30 22:52:48 volumio sudo[1625]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:49 volumio sudo[1625]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:49 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 30 22:52:49 volumio volumio-remote-updater[647]: [2025-10-30 22:52:49] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 30 22:52:49 volumio volumio-remote-updater[647]: [2025-10-30 22:52:49] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 30 22:52:49 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 30 22:52:49 volumio volumio-time-update[653]: volumio-time-update-util: Fetching time from Volumio... Oct 30 22:52:49 volumio systemd[1]: Started dynamicswap service. Oct 30 22:52:49 volumio volumio-time-update[653]: volumio-time-update-util: Date not found in response Oct 30 22:52:49 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 30 22:52:49 volumio volumio-time-update[653]: volumio-time-update-util: Retrying in 5 seconds... Oct 30 22:52:49 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 30 22:52:49 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Oct 30 22:52:49 volumio systemd[1]: Stopped Volumio Backend Module. Oct 30 22:52:49 volumio systemd[1]: Started Volumio Backend Module. Oct 30 22:52:49 volumio systemd[1]: Started dynamicswap service. Oct 30 22:52:49 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 30 22:52:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 30 22:52:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Oct 30 22:52:50 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 30 22:52:50 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1. Oct 30 22:52:50 volumio systemd[1]: Stopped FusionDsp Daemon. Oct 30 22:52:50 volumio systemd[1]: Started FusionDsp Daemon. Oct 30 22:52:50 volumio systemd[1]: Stopped go-librespot Daemon. Oct 30 22:52:50 volumio systemd[1]: Started go-librespot Daemon. Oct 30 22:52:50 volumio go-librespot[1670]: go-librespot daemon starting... Oct 30 22:52:50 volumio go-librespot[1670]: time="2025-10-30T22:52:50+02:00" level=info msg="running go-librespot 0.2.0" Oct 30 22:52:50 volumio go-librespot[1670]: time="2025-10-30T22:52:50+02:00" level=debug msg="app state loaded" Oct 30 22:52:50 volumio go-librespot[1670]: time="2025-10-30T22:52:50+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 22:52:50 volumio go-librespot[1670]: time="2025-10-30T22:52:50+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 30 22:52:50 volumio volumio[1669]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Oct 30 22:52:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 22:52:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 22:52:50 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Oct 30 22:52:50 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 30 22:52:51 volumio ntpd[876]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 30 22:52:51 volumio volumio[1652]: info: ------------------------------------------- Oct 30 22:52:51 volumio volumio[1652]: info: ----- Volumio3 ---- Oct 30 22:52:51 volumio volumio[1652]: info: ------------------------------------------- Oct 30 22:52:51 volumio volumio[1652]: info: ----- System startup ---- Oct 30 22:52:51 volumio volumio[1652]: info: ------------------------------------------- Oct 30 22:52:51 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Oct 30 22:52:52 volumio volumio[1652]: info: MYVOLUMIO Environment detected Oct 30 22:52:52 volumio volumio[1652]: info: Plugin folders cleanup Oct 30 22:52:52 volumio volumio[1652]: info: Scanning into folder /volumio/app/plugins/ Oct 30 22:52:52 volumio volumio[1652]: info: Scanning category audio_interface Oct 30 22:52:52 volumio volumio[1652]: info: Scanning category miscellanea Oct 30 22:52:52 volumio volumio[1652]: info: Scanning category music_service Oct 30 22:52:52 volumio volumio[1652]: info: Scanning category plugins.json Oct 30 22:52:52 volumio volumio[1652]: info: Scanning category system_controller Oct 30 22:52:52 volumio volumio[1652]: info: Scanning category user_interface Oct 30 22:52:52 volumio volumio[1652]: info: Scanning into folder /data/plugins/ Oct 30 22:52:52 volumio volumio[1652]: info: Scanning category audio_interface Oct 30 22:52:52 volumio volumio[1652]: info: Scanning category music_service Oct 30 22:52:52 volumio volumio[1652]: info: Scanning category system_controller Oct 30 22:52:52 volumio volumio[1652]: info: Scanning category user_interface Oct 30 22:52:52 volumio volumio[1652]: info: Plugin folders cleanup completed Oct 30 22:52:52 volumio volumio[1652]: info: ------------------------------------------- Oct 30 22:52:52 volumio volumio[1652]: info: ----- Core plugins startup ---- Oct 30 22:52:52 volumio volumio[1652]: info: ------------------------------------------- Oct 30 22:52:52 volumio volumio[1652]: info: Loading plugins from folder /volumio/app/plugins/ Oct 30 22:52:52 volumio volumio[1652]: info: Adding plugin upnp to MyMusic Plugins Oct 30 22:52:52 volumio volumio[1652]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 30 22:52:52 volumio volumio[1652]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 30 22:52:52 volumio volumio[1652]: info: Loading plugins from folder /data/plugins/ Oct 30 22:52:52 volumio volumio[1652]: info: Loading plugin "system"... Oct 30 22:52:52 volumio volumio[1652]: info: Loading plugin "appearance"... Oct 30 22:52:52 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 30 22:52:52 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 2. Oct 30 22:52:52 volumio systemd[1]: Stopped FusionDsp Daemon. Oct 30 22:52:52 volumio systemd[1]: Started FusionDsp Daemon. Oct 30 22:52:53 volumio volumio[1691]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Oct 30 22:52:53 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Oct 30 22:52:53 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 30 22:52:53 volumio ntpd[876]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 30 22:52:53 volumio ntpd[876]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 30 22:52:53 volumio volumio[1652]: info: Loading plugin "network"... Oct 30 22:52:53 volumio volumio[1652]: info: Refreshing Cached IP Addresses Oct 30 22:52:53 volumio sudo[1693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 30 22:52:53 volumio sudo[1693]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:53 volumio sudo[1693]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:53 volumio sudo[1695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 22:52:53 volumio sudo[1695]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:53 volumio volumio[1652]: info: Loading plugin "services"... Oct 30 22:52:53 volumio volumio[1652]: info: Loading plugin "alsa_controller"... Oct 30 22:52:53 volumio sudo[1695]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:53 volumio sudo[1705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 30 22:52:53 volumio sudo[1705]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:52:53 volumio sudo[1705]: pam_unix(sudo:session): session closed for user root Oct 30 22:52:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 30 22:52:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Oct 30 22:52:53 volumio systemd[1]: Stopped go-librespot Daemon. Oct 30 22:52:53 volumio systemd[1]: Started go-librespot Daemon. Oct 30 22:52:53 volumio go-librespot[1718]: go-librespot daemon starting... Oct 30 22:52:54 volumio go-librespot[1718]: time="2025-10-30T22:52:54+02:00" level=info msg="running go-librespot 0.2.0" Oct 30 22:52:54 volumio go-librespot[1718]: time="2025-10-30T22:52:54+02:00" level=debug msg="app state loaded" Oct 30 22:52:54 volumio go-librespot[1718]: time="2025-10-30T22:52:54+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 22:52:54 volumio go-librespot[1718]: time="2025-10-30T22:52:54+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 30 22:52:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 22:52:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 22:52:54 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 22:52:54 volumio volumio[1652]: info: Loading plugin "wizard"... Oct 30 22:52:54 volumio volumio[1652]: info: Loading plugin "networkfs"... Oct 30 22:52:54 volumio volumio[1652]: info: Starting Udev Watcher for removable devices Oct 30 22:52:54 volumio volumio[1652]: info: Ignoring mount for partition: boot Oct 30 22:52:54 volumio volumio[1652]: info: Ignoring mount for partition: volumio Oct 30 22:52:54 volumio volumio[1652]: info: Ignoring mount for partition: volumio_data Oct 30 22:52:54 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 22:52:54 volumio volumio[1652]: info: Loading plugin "volumio_command_line_client"... Oct 30 22:52:54 volumio volumio[1652]: info: Loading plugin "upnp"... Oct 30 22:52:54 volumio volumio[1652]: info: [1761857574157] Starting Upmpd Daemon Oct 30 22:52:54 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 22:52:54 volumio volumio[1652]: info: Loading plugin "my_music"... Oct 30 22:52:54 volumio volumio[1652]: info: Loading plugin "mpd"... Oct 30 22:52:54 volumio volumio-remote-updater[647]: [2025-10-30 22:52:54] [connect] Successful connection Oct 30 22:52:54 volumio volumio-time-update[653]: volumio-time-update-util: Fetching time from Volumio... Oct 30 22:52:54 volumio volumio-time-update[653]: volumio-time-update-util: Date not found in response Oct 30 22:52:54 volumio volumio-time-update[653]: volumio-time-update-util: Retrying in 5 seconds... Oct 30 22:52:54 volumio volumio[1652]: info: Loading plugin "upnp_browser"... Oct 30 22:52:54 volumio wpa_supplicant[911]: rfkill: WLAN unblocked Oct 30 22:52:54 volumio kernel: ACPI Warning: Time parameter 255 us > 100 us violating ACPI spec, please fix the firmware. (20230628/exsystem-141) Oct 30 22:52:54 volumio kernel: ACPI Warning: Time parameter 255 us > 100 us violating ACPI spec, please fix the firmware. (20230628/exsystem-141) Oct 30 22:52:54 volumio kernel: ACPI Warning: Time parameter 255 us > 100 us violating ACPI spec, please fix the firmware. (20230628/exsystem-141) Oct 30 22:52:54 volumio kernel: ACPI Warning: Time parameter 255 us > 100 us violating ACPI spec, please fix the firmware. (20230628/exsystem-141) Oct 30 22:52:54 volumio kernel: ACPI Warning: Time parameter 255 us > 100 us violating ACPI spec, please fix the firmware. (20230628/exsystem-141) Oct 30 22:52:54 volumio kernel: ACPI Warning: Time parameter 255 us > 100 us violating ACPI spec, please fix the firmware. (20230628/exsystem-141) Oct 30 22:52:54 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Oct 30 22:52:55 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Oct 30 22:52:55 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 30 22:52:55 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 3. Oct 30 22:52:55 volumio systemd[1]: Stopped FusionDsp Daemon. Oct 30 22:52:55 volumio systemd[1]: Started FusionDsp Daemon. Oct 30 22:52:55 volumio volumio[1652]: info: Loading plugin "alarm-clock"... Oct 30 22:52:55 volumio volumio[1799]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Oct 30 22:52:55 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Oct 30 22:52:55 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 30 22:52:55 volumio volumio[1652]: info: Loading plugin "airplay_emulation"... Oct 30 22:52:55 volumio volumio[1652]: info: Starting Shairport Sync Oct 30 22:52:55 volumio volumio[1652]: info: Loading plugin "last_100"... Oct 30 22:52:55 volumio volumio[1652]: info: Loading plugin "webradio"... Oct 30 22:52:55 volumio volumio[1652]: info: Loading plugin "i2s_dacs"... Oct 30 22:52:55 volumio volumio[1652]: info: I2S DAC not set, start Auto-detection Oct 30 22:52:55 volumio volumio[1652]: info: Loading plugin "volumiodiscovery"... Oct 30 22:52:55 volumio volumio[1652]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 30 22:52:55 volumio node[1652]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 30 22:52:55 volumio volumio[1652]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 22:52:55 volumio node[1652]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 22:52:55 volumio volumio[1652]: *** WARNING *** For more information see Oct 30 22:52:55 volumio volumio[1652]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 30 22:52:55 volumio volumio[1652]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 22:52:55 volumio volumio[1652]: *** WARNING *** For more information see Oct 30 22:52:55 volumio node[1652]: *** WARNING *** For more information see Oct 30 22:52:55 volumio node[1652]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 30 22:52:55 volumio node[1652]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 22:52:55 volumio node[1652]: *** WARNING *** For more information see Oct 30 22:52:55 volumio volumio[1652]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 30 22:52:55 volumio volumio[1652]: info: Discovery: Started advertising with name: Volumio Oct 30 22:52:55 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 22:52:55 volumio volumio[1652]: info: Loading plugin "calmradio"... Oct 30 22:52:55 volumio wpa_supplicant[911]: wlan0: SME: Trying to authenticate with cc:d8:43:b1:7a:e6 (SSID='vapona_2.4' freq=2452 MHz) Oct 30 22:52:55 volumio kernel: wlan0: authenticate with cc:d8:43:b1:7a:e6 Oct 30 22:52:55 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Oct 30 22:52:55 volumio kernel: wlan0: send auth to cc:d8:43:b1:7a:e6 (try 1/3) Oct 30 22:52:55 volumio wpa_supplicant[911]: wlan0: Trying to associate with cc:d8:43:b1:7a:e6 (SSID='vapona_2.4' freq=2452 MHz) Oct 30 22:52:55 volumio kernel: wlan0: authenticated Oct 30 22:52:55 volumio kernel: wlan0: associate with cc:d8:43:b1:7a:e6 (try 1/3) Oct 30 22:52:55 volumio kernel: wlan0: RX AssocResp from cc:d8:43:b1:7a:e6 (capab=0x1431 status=0 aid=10) Oct 30 22:52:55 volumio kernel: wlan0: associated Oct 30 22:52:55 volumio wpa_supplicant[911]: wlan0: Associated with cc:d8:43:b1:7a:e6 Oct 30 22:52:55 volumio wpa_supplicant[911]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 30 22:52:56 volumio systemd-udevd[1780]: Process '/sbin/crda' failed with exit code 255. Oct 30 22:52:56 volumio kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by cc:d8:43:b1:7a:e6 Oct 30 22:52:56 volumio wpa_supplicant[911]: wlan0: WPA: Key negotiation completed with cc:d8:43:b1:7a:e6 [PTK=CCMP GTK=CCMP] Oct 30 22:52:56 volumio wpa_supplicant[911]: wlan0: CTRL-EVENT-CONNECTED - Connection to cc:d8:43:b1:7a:e6 completed [id=0 id_str=] Oct 30 22:52:56 volumio dhcpcd[726]: wlan0: carrier acquired Oct 30 22:52:56 volumio dhcpcd[726]: wlan0: IAID e5:2d:0b:9e Oct 30 22:52:56 volumio dhcpcd[726]: wlan0: adding address fe80::9639:e5ff:fe2d:b9e Oct 30 22:52:56 volumio dhcpcd[726]: ipv6_addaddr1: Permission denied Oct 30 22:52:56 volumio ntpd[876]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 30 22:52:56 volumio dhcpcd[726]: wlan0: soliciting an IPv6 router Oct 30 22:52:56 volumio volumio[1652]: info: Loading plugin "soundcloud"... Oct 30 22:52:57 volumio dhcpcd[726]: wlan0: rebinding lease of 192.168.0.35 Oct 30 22:52:57 volumio dhcpcd[726]: wlan0: probing address 192.168.0.35/24 Oct 30 22:52:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 30 22:52:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Oct 30 22:52:57 volumio systemd[1]: Stopped go-librespot Daemon. Oct 30 22:52:57 volumio systemd[1]: Started go-librespot Daemon. Oct 30 22:52:57 volumio go-librespot[1932]: go-librespot daemon starting... Oct 30 22:52:57 volumio go-librespot[1932]: time="2025-10-30T22:52:57+02:00" level=info msg="running go-librespot 0.2.0" Oct 30 22:52:57 volumio go-librespot[1932]: time="2025-10-30T22:52:57+02:00" level=debug msg="app state loaded" Oct 30 22:52:57 volumio go-librespot[1932]: time="2025-10-30T22:52:57+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 22:52:57 volumio go-librespot[1932]: time="2025-10-30T22:52:57+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 30 22:52:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 22:52:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 22:52:57 volumio volumio[1652]: info: Loading plugin "spop"... Oct 30 22:52:57 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 30 22:52:57 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 4. Oct 30 22:52:57 volumio systemd[1]: Stopped FusionDsp Daemon. Oct 30 22:52:57 volumio systemd[1]: Started FusionDsp Daemon. Oct 30 22:52:57 volumio volumio[1938]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Oct 30 22:52:57 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Oct 30 22:52:57 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 30 22:52:58 volumio volumio[1652]: info: Loading plugin "youtube2"... Oct 30 22:52:59 volumio wpa_supplicant[911]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Oct 30 22:52:59 volumio volumio-time-update[653]: volumio-time-update-util: Fetching time from Volumio... Oct 30 22:52:59 volumio volumio-time-update[653]: volumio-time-update-util: Date not found in response Oct 30 22:52:59 volumio volumio-time-update[653]: volumio-time-update-util: Retrying in 5 seconds... Oct 30 22:52:59 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 30 22:52:59 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 5. Oct 30 22:52:59 volumio systemd[1]: Stopped FusionDsp Daemon. Oct 30 22:52:59 volumio systemd[1]: Started FusionDsp Daemon. Oct 30 22:52:59 volumio volumio[1944]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Oct 30 22:52:59 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Oct 30 22:52:59 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 30 22:53:00 volumio systemd[1]: systemd-rfkill.service: Succeeded. Oct 30 22:53:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 30 22:53:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Oct 30 22:53:00 volumio systemd[1]: Stopped go-librespot Daemon. Oct 30 22:53:00 volumio systemd[1]: Started go-librespot Daemon. Oct 30 22:53:00 volumio go-librespot[1945]: go-librespot daemon starting... Oct 30 22:53:00 volumio go-librespot[1945]: time="2025-10-30T22:53:00+02:00" level=info msg="running go-librespot 0.2.0" Oct 30 22:53:00 volumio go-librespot[1945]: time="2025-10-30T22:53:00+02:00" level=debug msg="app state loaded" Oct 30 22:53:00 volumio go-librespot[1945]: time="2025-10-30T22:53:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 22:53:00 volumio go-librespot[1945]: time="2025-10-30T22:53:00+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 30 22:53:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 22:53:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 22:53:01 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 30 22:53:01 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 6. Oct 30 22:53:01 volumio systemd[1]: Stopped FusionDsp Daemon. Oct 30 22:53:01 volumio volumio[1652]: info: Loading plugin "ytcr"... Oct 30 22:53:01 volumio systemd[1]: Started FusionDsp Daemon. Oct 30 22:53:02 volumio volumio[1951]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Oct 30 22:53:02 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Oct 30 22:53:02 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 30 22:53:02 volumio dhcpcd[726]: wlan0: leased 192.168.0.35 for 43200 seconds Oct 30 22:53:02 volumio avahi-daemon[644]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.35. Oct 30 22:53:02 volumio avahi-daemon[644]: New relevant interface wlan0.IPv4 for mDNS. Oct 30 22:53:02 volumio avahi-daemon[644]: Registering new address record for 192.168.0.35 on wlan0.IPv4. Oct 30 22:53:02 volumio dhcpcd[726]: wlan0: adding route to 192.168.0.0/24 Oct 30 22:53:02 volumio dhcpcd[726]: wlan0: adding default route via 192.168.0.1 Oct 30 22:53:02 volumio systemd[1]: Stopping Network Time Service... Oct 30 22:53:02 volumio ntpd[876]: ntpd exiting on signal 15 (Terminated) Oct 30 22:53:02 volumio systemd[1]: ntp.service: Succeeded. Oct 30 22:53:02 volumio systemd[1]: Stopped Network Time Service. Oct 30 22:53:02 volumio systemd[1]: Starting Network Time Service... Oct 30 22:53:02 volumio ntpd[1984]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 30 22:53:02 volumio ntpd[1984]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 30 22:53:02 volumio systemd[1]: Started Network Time Service. Oct 30 22:53:02 volumio ntpd[1990]: proto: precision = 0.378 usec (-21) Oct 30 22:53:02 volumio ntpd[1990]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 30 22:53:02 volumio ntpd[1990]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 30 22:53:02 volumio ntpd[1990]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1038 days ago Oct 30 22:53:02 volumio ntpd[1990]: Listen and drop on 0 v6wildcard [::]:123 Oct 30 22:53:02 volumio ntpd[1990]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 30 22:53:02 volumio ntpd[1990]: Listen normally on 2 lo 127.0.0.1:123 Oct 30 22:53:02 volumio ntpd[1990]: Listen normally on 3 wlan0 192.168.0.35:123 Oct 30 22:53:02 volumio ntpd[1990]: Listening on routing socket on fd #20 for interface updates Oct 30 22:53:02 volumio ntpd[1990]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 30 22:53:02 volumio ntpd[1990]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 30 22:53:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 30 22:53:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Oct 30 22:53:03 volumio systemd[1]: Stopped go-librespot Daemon. Oct 30 22:53:03 volumio systemd[1]: Started go-librespot Daemon. Oct 30 22:53:03 volumio go-librespot[1992]: go-librespot daemon starting... Oct 30 22:53:03 volumio go-librespot[1992]: time="2025-10-30T22:53:03+02:00" level=info msg="running go-librespot 0.2.0" Oct 30 22:53:03 volumio go-librespot[1992]: time="2025-10-30T22:53:03+02:00" level=debug msg="app state loaded" Oct 30 22:53:03 volumio go-librespot[1992]: time="2025-10-30T22:53:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 22:53:04 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 30 22:53:04 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 7. Oct 30 22:53:04 volumio systemd[1]: Stopped FusionDsp Daemon. Oct 30 22:53:04 volumio systemd[1]: Started FusionDsp Daemon. Oct 30 22:53:04 volumio volumio[2012]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Oct 30 22:53:04 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Oct 30 22:53:04 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 30 22:53:04 volumio volumio-time-update[653]: volumio-time-update-util: Fetching time from Volumio... Oct 30 22:53:06 volumio nmbd[856]: [2025/10/30 22:53:06.288038, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 30 22:53:06 volumio nmbd[856]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 30 22:53:06 volumio systemd[1]: Started Samba NMB Daemon. Oct 30 22:53:06 volumio systemd[1]: Starting Samba Winbind Daemon... Oct 30 22:53:06 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 30 22:53:06 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 8. Oct 30 22:53:06 volumio systemd[1]: Stopped FusionDsp Daemon. Oct 30 22:53:06 volumio systemd[1]: Started FusionDsp Daemon. Oct 30 22:53:06 volumio volumio[2035]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Oct 30 22:53:06 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Oct 30 22:53:06 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 30 22:53:06 volumio volumio-remote-updater[647]: [2025-10-30 22:53:06] [connect] Successful connection Oct 30 22:53:06 volumio winbindd[2033]: [2025/10/30 22:53:06.817984, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 30 22:53:06 volumio winbindd[2033]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 30 22:53:06 volumio winbindd[2033]: [2025/10/30 22:53:06.825710, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 30 22:53:06 volumio systemd[1]: Started Samba Winbind Daemon. Oct 30 22:53:06 volumio winbindd[2033]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 30 22:53:06 volumio systemd[1]: Starting Samba SMB Daemon... Oct 30 22:53:07 volumio smbd[2039]: [2025/10/30 22:53:07.645885, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 30 22:53:07 volumio smbd[2039]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 30 22:53:07 volumio systemd[1]: Started Samba SMB Daemon. Oct 30 22:53:07 volumio volumio[1652]: info: Loading plugin "autostart"... Oct 30 22:53:08 volumio volumio[1652]: info: Applying required configuration parameters for plugin autostart Oct 30 22:53:08 volumio volumio[1652]: info: AutoStart - onVolumioStart - read config.json Oct 30 22:53:08 volumio volumio[1652]: info: Loading plugin "now_playing"... Oct 30 22:53:08 volumio ntpd[1990]: Soliciting pool server 162.159.200.123 Oct 30 22:53:08 volumio ntpd[1990]: Soliciting pool server 193.93.164.195 Oct 30 22:53:08 volumio ntpd[1990]: Soliciting pool server 194.177.210.54 Oct 30 22:53:08 volumio ntpd[1990]: Soliciting pool server 147.52.190.11 Oct 30 22:53:08 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 30 22:53:08 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 9. Oct 30 22:53:08 volumio systemd[1]: Stopped FusionDsp Daemon. Oct 30 22:53:08 volumio systemd[1]: Started FusionDsp Daemon. Oct 30 22:53:08 volumio volumio[2087]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Oct 30 22:53:08 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Oct 30 22:53:08 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 30 22:53:09 volumio go-librespot[1992]: time="2025-10-30T22:53:09+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 22:53:09 volumio go-librespot[1992]: time="2025-10-30T22:53:09+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 30 22:53:09 volumio go-librespot[1992]: time="2025-10-30T22:53:09+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 30 22:53:09 volumio go-librespot[1992]: time="2025-10-30T22:53:09+02:00" level=info msg="zeroconf server listening on port 44685" Oct 30 22:53:09 volumio volumio[1652]: info: Loading plugin "outputs"... Oct 30 22:53:09 volumio volumio[1652]: info: Loading plugin "albumart"... Oct 30 22:53:09 volumio ntpd[1990]: Soliciting pool server 83.212.109.72 Oct 30 22:53:09 volumio ntpd[1990]: Soliciting pool server 193.239.214.227 Oct 30 22:53:09 volumio ntpd[1990]: Soliciting pool server 83.212.108.245 Oct 30 22:53:09 volumio ntpd[1990]: Soliciting pool server 193.239.214.227 Oct 30 22:53:09 volumio volumio[1652]: info: Plugin example_plugin is not enabled Oct 30 22:53:09 volumio volumio[1652]: info: Loading plugin "inputs"... Oct 30 22:53:09 volumio volumio[1652]: info: Loading plugin "updater_comm"... Oct 30 22:53:09 volumio volumio-time-update[653]: volumio-time-update-util: Setting system time to: 2025-10-30 22:53:09 Oct 30 22:53:09 volumio sudo[2100]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-10-30 22:53:09 Oct 30 22:53:09 volumio sudo[2100]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:53:09 volumio dbus-daemon[664]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.15' (uid=0 pid=2101 comm="timedatectl set-time 2025-10-30 22:53:09 ") Oct 30 22:53:09 volumio systemd[1]: Starting Time & Date Service... Oct 30 22:53:09 volumio volumio[1652]: Forking 1 albumart workers Oct 30 22:53:09 volumio volumio[1652]: info: Plugin mpdemulation is not enabled Oct 30 22:53:09 volumio volumio[1652]: info: Loading plugin "rest_api"... Oct 30 22:53:10 volumio volumio[1652]: info: Loading plugin "websocket"... Oct 30 22:53:10 volumio volumio[1652]: info: Starting Socket.io Server version 2.3.0 Oct 30 22:53:10 volumio volumio[1652]: info: Loading plugin "fusiondsp"... Oct 30 22:53:10 volumio dbus-daemon[664]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 30 22:53:10 volumio systemd[1]: Started Time & Date Service. Oct 30 22:53:09 volumio systemd-timedated[2102]: Changed local time to Thu Oct 30 22:53:09 2025 Oct 30 22:53:09 volumio systemd[1]: Started Volumio Time Update Utility. Oct 30 22:53:09 volumio volumio-time-update[653]: volumio-time-update-util: System time updated successfully. Oct 30 22:53:09 volumio sudo[2100]: pam_unix(sudo:session): session closed for user root Oct 30 22:53:09 volumio systemd[1]: Reached target Multi-User System. Oct 30 22:53:09 volumio systemd[1]: Reached target Graphical Interface. Oct 30 22:53:09 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 30 22:53:09 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 30 22:53:09 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 30 22:53:09 volumio systemd[1]: Startup finished in 29.298s (kernel) + 1min 34.248s (userspace) = 2min 3.546s. Oct 30 22:53:09 volumio ntpd[1990]: Soliciting pool server 195.167.30.249 Oct 30 22:53:09 volumio ntpd[1990]: Soliciting pool server 193.93.164.194 Oct 30 22:53:09 volumio ntpd[1990]: Soliciting pool server 193.239.214.226 Oct 30 22:53:09 volumio volumio[1652]: info: Applying required configuration parameters for plugin fusiondsp Oct 30 22:53:09 volumio volumio[1652]: info: Loading plugin "personal_radio"... Oct 30 22:53:09 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 30 22:53:09 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 10. Oct 30 22:53:09 volumio systemd[1]: Stopped FusionDsp Daemon. Oct 30 22:53:09 volumio systemd[1]: Started FusionDsp Daemon. Oct 30 22:53:09 volumio volumio[2122]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Oct 30 22:53:09 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Oct 30 22:53:09 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 30 22:53:09 volumio volumio[1652]: Starting albumart workers Oct 30 22:53:10 volumio ntpd[1990]: Soliciting pool server 62.1.105.163 Oct 30 22:53:10 volumio ntpd[1990]: Soliciting pool server 81.2.191.34 Oct 30 22:53:10 volumio volumio[1652]: info: ControllerPersonalRadio::constructor Oct 30 22:53:10 volumio volumio[1652]: info: Loading plugin "rad357pl"... Oct 30 22:53:10 volumio volumio[1652]: info: Applying required configuration parameters for plugin rad357pl Oct 30 22:53:10 volumio volumio[1652]: info: [1761857590643] [rad357pl] API delay: 5 Oct 30 22:53:10 volumio volumio[1652]: info: Loading plugin "radio_paradise"... Oct 30 22:53:10 volumio volumio[1652]: info: Applying required configuration parameters for plugin radio_paradise Oct 30 22:53:10 volumio volumio[1652]: info: [1761857590899] [RadioParadise] API delay: 5 Oct 30 22:53:10 volumio volumio[1652]: info: Loading plugin "backup_restore"... Oct 30 22:53:11 volumio volumio[1652]: info: Applying required configuration parameters for plugin backup_restore Oct 30 22:53:11 volumio volumio[1652]: info: Loading plugin "lastfm"... Oct 30 22:53:11 volumio ntpd[1990]: Soliciting pool server 2a06:5780:82:c0c::c0c Oct 30 22:53:11 volumio volumio[1652]: info: Plugin peppymeterbasic is not enabled Oct 30 22:53:11 volumio volumio[1652]: info: Plugin peppyspectrum is not enabled Oct 30 22:53:11 volumio volumio[1652]: info: Plugin randomizer is not enabled Oct 30 22:53:11 volumio volumio[1652]: info: Loading i18n strings for locale en Oct 30 22:53:11 volumio volumio[1652]: Updating browse sources language Oct 30 22:53:11 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:53:11 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 22:53:11 volumio volumio[1652]: info: CoreCommandRouter::initPlayerControls Oct 30 22:53:11 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:11 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:11 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:11 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:11 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:11 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:11 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:11 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:11 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 22:53:11 volumio volumio[1652]: Express server listening on port 3000 Oct 30 22:53:11 volumio volumio[1652]: [Metrics] WebUI: 22s 302.79ms Oct 30 22:53:11 volumio volumio[1652]: info: Setting Device type: x86 Oct 30 22:53:11 volumio volumio[1652]: info: CoreStateMachine::resetVolumioState Oct 30 22:53:11 volumio volumio[1652]: info: CoreStateMachine::getcurrentVolume Oct 30 22:53:11 volumio volumio[1652]: info: CoreCommandRouter::volumioRetrievevolume Oct 30 22:53:12 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 22:53:12 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 30 22:53:12 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 11. Oct 30 22:53:12 volumio systemd[1]: Stopped FusionDsp Daemon. Oct 30 22:53:12 volumio systemd[1]: Started FusionDsp Daemon. Oct 30 22:53:12 volumio sudo[2134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 30 22:53:12 volumio sudo[2134]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:53:12 volumio sudo[2134]: pam_unix(sudo:session): session closed for user root Oct 30 22:53:12 volumio volumio[2133]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Oct 30 22:53:12 volumio sudo[2137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 22:53:12 volumio sudo[2137]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:53:12 volumio volumio[1652]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 30 22:53:12 volumio volumio[1652]: info: Completed loading Core Plugins Oct 30 22:53:12 volumio volumio[1652]: info: Preparing to generate the ALSA configuration file Oct 30 22:53:12 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Oct 30 22:53:12 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 30 22:53:12 volumio sudo[2137]: pam_unix(sudo:session): session closed for user root Oct 30 22:53:12 volumio volumio[1652]: info: Volumio Network Manager: Network status updated: 0 Oct 30 22:53:12 volumio volumio[1652]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Oct 30 22:53:12 volumio volumio[1652]: wlan0 Interface doesn't support scanning : Network is down Oct 30 22:53:12 volumio volumio[1652]: info: Cannot use regular scanning, forcing with ap-force Oct 30 22:53:12 volumio sudo[2141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Oct 30 22:53:12 volumio sudo[2141]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:53:13 volumio go-librespot[1992]: time="2025-10-30T22:53:13+02:00" level=debug msg="obtained new client token: AABMQMIMku4s7dPaBX5811SPt0Gu6/6HMFWLg8l+3vLtSSJG0OueWx4M9Qjg5QTgs2TRM10jcVlhZ/PxvxhT/22tM2ShuZeVkvr86aZc6owC6I7QY3ts1lgIvAlXXyz6851lpV+DH76rthKNwhVyWKrbd5fCK+gyPAHfTPL0saZkEIpeRLRJ+a1DlE3ByF2FpG+t0kzAacnKx6FNp/QpD6G9c6ruirkOsxGd28lZNZ403c1UU1GhimVh" Oct 30 22:53:13 volumio go-librespot[1992]: time="2025-10-30T22:53:13+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 22:53:13 volumio go-librespot[1992]: time="2025-10-30T22:53:13+02:00" level=debug msg="completed keyexchange" Oct 30 22:53:13 volumio go-librespot[1992]: time="2025-10-30T22:53:13+02:00" level=debug msg="completed challenge" Oct 30 22:53:13 volumio go-librespot[1992]: time="2025-10-30T22:53:13+02:00" level=info msg="authenticated AP as j36mz8yvkp876xmgxznp1tr1l" Oct 30 22:53:14 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 30 22:53:14 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 12. Oct 30 22:53:14 volumio systemd[1]: Stopped FusionDsp Daemon. Oct 30 22:53:14 volumio systemd[1]: Started FusionDsp Daemon. Oct 30 22:53:14 volumio volumio[2157]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Oct 30 22:53:14 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Oct 30 22:53:14 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 30 22:53:14 volumio wpa_supplicant[911]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN Oct 30 22:53:15 volumio wpa_supplicant[911]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN Oct 30 22:53:16 volumio ntpd[1990]: receive: Unexpected origin timestamp 0xecae4ebb.522eabe7 does not match aorg 0000000000.00000000 from server@81.2.191.34 xmt 0xecae4ebc.1b840e60 Oct 30 22:53:16 volumio ntpd[1990]: receive: Unexpected origin timestamp 0xecae4ebb.524ae390 does not match aorg 0000000000.00000000 from server@193.239.214.227 xmt 0xecae4ebc.1abc85ca Oct 30 22:53:16 volumio ntpd[1990]: receive: Unexpected origin timestamp 0xecae4ebb.524125f5 does not match aorg 0000000000.00000000 from server@193.93.164.194 xmt 0xecae4ebc.27e34917 Oct 30 22:53:16 volumio sudo[2141]: pam_unix(sudo:session): session closed for user root Oct 30 22:53:16 volumio volumio[1652]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 30 22:53:16 volumio volumio[1652]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 30 22:53:16 volumio volumio[1652]: info: Reading ALSA contributions from plugins. Oct 30 22:53:16 volumio volumio[1652]: info: Volumio Network Manager: Network status updated: 2 Oct 30 22:53:16 volumio volumio[1652]: info: VolumeController:: Volume=100 Mute =false Oct 30 22:53:16 volumio volumio[1652]: info: CoreStateMachine::pushState Oct 30 22:53:16 volumio volumio[1652]: info: CorePlayQueue::getTrack 0 Oct 30 22:53:16 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 22:53:16 volumio volumio[1652]: info: CoreCommandRouter::volumioPushState Oct 30 22:53:16 volumio volumio[1652]: info: CoreStateMachine::updateTrackBlock Oct 30 22:53:16 volumio volumio[1652]: info: CorePlayQueue::getTrackBlock Oct 30 22:53:16 volumio volumio[1652]: info: CoreCommandRouter::volumioRetrievevolume Oct 30 22:53:16 volumio volumio[1652]: Upnp client error: Error: This socket has been ended by the other party Oct 30 22:53:16 volumio volumio[1652]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 30 22:53:16 volumio volumio[1652]: info: Reloading queue from file Oct 30 22:53:16 volumio volumio[1652]: info: CoreStateMachine::setRepeat false single undefined Oct 30 22:53:16 volumio volumio[1652]: info: CoreStateMachine::pushState Oct 30 22:53:16 volumio volumio[1652]: info: CorePlayQueue::getTrack 0 Oct 30 22:53:16 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 22:53:16 volumio volumio[1652]: info: CoreCommandRouter::volumioPushState Oct 30 22:53:16 volumio volumio[1652]: info: CoreStateMachine::setRandom null Oct 30 22:53:16 volumio volumio[1652]: info: CoreStateMachine::pushState Oct 30 22:53:16 volumio volumio[1652]: info: CorePlayQueue::getTrack 0 Oct 30 22:53:16 volumio volumio[1652]: info: CoreCommandRouter::volumioPushState Oct 30 22:53:16 volumio volumio[1652]: info: VolumeController:: Volume=100 Mute =false Oct 30 22:53:16 volumio volumio[1652]: info: CoreStateMachine::pushState Oct 30 22:53:16 volumio volumio[1652]: info: CorePlayQueue::getTrack 0 Oct 30 22:53:16 volumio volumio[1652]: info: CoreCommandRouter::volumioPushState Oct 30 22:53:17 volumio sudo[2166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 30 22:53:17 volumio sudo[2166]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:53:17 volumio sudo[2166]: pam_unix(sudo:session): session closed for user root Oct 30 22:53:17 volumio volumio[1652]: info: Discovery: adding 82bbd6fe-5df9-4b2d-bf76-2277392f0e79 Oct 30 22:53:17 volumio volumio[1652]: info: Discovery: Found device Volumio Oct 30 22:53:17 volumio volumio[1652]: info: CoreCommandRouter::volumioGetState Oct 30 22:53:17 volumio volumio[1652]: info: CorePlayQueue::getTrack 0 Oct 30 22:53:17 volumio volumio[1652]: info: Upmpdcli Daemon Started Oct 30 22:53:17 volumio volumio[1652]: info: Asound.conf file unchanged, so no further update is needed Oct 30 22:53:17 volumio volumio[1652]: info: Output device has changed, restarting MPD Oct 30 22:53:17 volumio sudo[2169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 30 22:53:17 volumio sudo[2169]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:53:17 volumio sudo[2169]: pam_unix(sudo:session): session closed for user root Oct 30 22:53:17 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 30 22:53:17 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 13. Oct 30 22:53:17 volumio systemd[1]: Stopped FusionDsp Daemon. Oct 30 22:53:17 volumio systemd[1]: Started FusionDsp Daemon. Oct 30 22:53:17 volumio volumio[1652]: info: Output device has changed, restarting Shairport Sync Oct 30 22:53:17 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:17 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 22:53:17 volumio sudo[2173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 30 22:53:17 volumio sudo[2173]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:53:17 volumio systemd[1]: Stopping Music Player Daemon... Oct 30 22:53:17 volumio volumio[2172]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Oct 30 22:53:17 volumio systemd[1]: mpd.service: Succeeded. Oct 30 22:53:17 volumio systemd[1]: Stopped Music Player Daemon. Oct 30 22:53:17 volumio systemd[1]: Starting Music Player Daemon... Oct 30 22:53:17 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Oct 30 22:53:17 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 30 22:53:17 volumio volumio[1652]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 22:53:17 volumio volumio[1652]: info: ___________ START PLUGINS ___________ Oct 30 22:53:17 volumio volumio[1652]: info: ControllerMpd::onStart: Initializing MPD Oct 30 22:53:17 volumio volumio[1652]: info: Creating MPD Configuration file Oct 30 22:53:17 volumio sudo[2178]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 30 22:53:17 volumio sudo[2178]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:53:17 volumio sudo[2178]: pam_unix(sudo:session): session closed for user root Oct 30 22:53:17 volumio sudo[2180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 30 22:53:17 volumio sudo[2180]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:53:17 volumio sudo[2180]: pam_unix(sudo:session): session closed for user root Oct 30 22:53:17 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 22:53:17 volumio volumio[1652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:53:17 volumio volumio[1652]: info: [1761857597701] CoreMusicLibrary::Adding element Media Servers Oct 30 22:53:17 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:53:17 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:17 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 22:53:17 volumio sudo[2185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 30 22:53:17 volumio sudo[2185]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:53:17 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 30 22:53:17 volumio systemd[1]: mpd.service: Succeeded. Oct 30 22:53:17 volumio systemd[1]: Stopped Music Player Daemon. Oct 30 22:53:17 volumio systemd[1]: Starting Music Player Daemon... Oct 30 22:53:17 volumio volumio[1652]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 22:53:17 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:17 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 22:53:17 volumio sudo[2190]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 30 22:53:17 volumio volumio[1652]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 22:53:17 volumio sudo[2190]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:53:17 volumio volumio[1652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:53:17 volumio volumio[1652]: info: [1761857597962] CoreMusicLibrary::Adding element Last_100 Oct 30 22:53:17 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:53:17 volumio volumio[1652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:53:17 volumio volumio[1652]: info: [1761857597968] CoreMusicLibrary::Adding element Webradio Oct 30 22:53:17 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:53:17 volumio sudo[2190]: pam_unix(sudo:session): session closed for user root Oct 30 22:53:17 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 22:53:17 volumio volumio[1652]: info: Initializing BBC Radios Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 22:53:18 volumio volumio[1652]: info: Adding Calm Radio to Browse Sources Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:53:18 volumio volumio[1652]: info: [1761857598157] CoreMusicLibrary::Adding element Calm Radio Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source Calm Radio Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:53:18 volumio volumio[1652]: info: [1761857598171] CoreMusicLibrary::Adding element SoundCloud Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source Calm Radio Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source SoundCloud Oct 30 22:53:18 volumio volumio[1652]: info: Creating Spotify config file Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:53:18 volumio volumio[1652]: info: [1761857598281] CoreMusicLibrary::Adding element YouTube2 Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source Calm Radio Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source SoundCloud Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source YouTube2 Oct 30 22:53:18 volumio volumio[1652]: info: AutoStart - onStart Oct 30 22:53:18 volumio volumio[1652]: info: [now-playing] ConfigUpdater: config is up to date. Oct 30 22:53:18 volumio volumio[1652]: info: Loading i18n strings for locale en Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:18 volumio volumio[1652]: info: FusionDsp - mixtype--------------------- Hardware Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:53:18 volumio volumio[1652]: info: [1761857598447] CoreMusicLibrary::Adding element Personal Radio Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source Calm Radio Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source SoundCloud Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source YouTube2 Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source Personal Radio Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:53:18 volumio volumio[1652]: info: [1761857598456] CoreMusicLibrary::Adding element RADIO 357 Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source Calm Radio Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source SoundCloud Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source YouTube2 Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source Personal Radio Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source RADIO 357 Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:53:18 volumio volumio[1652]: info: [1761857598463] CoreMusicLibrary::Adding element Radio Paradise Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source Calm Radio Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source SoundCloud Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source YouTube2 Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source Personal Radio Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source RADIO 357 Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source Radio Paradise Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 22:53:18 volumio volumio[1652]: info: [1761857598468] CoreMusicLibrary::Adding element LastFM Oct 30 22:53:18 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source Calm Radio Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source SoundCloud Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source YouTube2 Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source Personal Radio Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source RADIO 357 Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source Radio Paradise Oct 30 22:53:18 volumio volumio[1652]: Cannot find translation for source LastFM Oct 30 22:53:18 volumio volumio[1652]: info: [LastFM] scrobbler initiated! Oct 30 22:53:18 volumio volumio[1652]: info: [LastFM] extended logging: false Oct 30 22:53:18 volumio volumio[1652]: info: [LastFM] try scrobble stream/radio plays: true Oct 30 22:53:18 volumio volumio[1652]: info: [LastFM] Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Oct 30 22:53:18 volumio volumio[1652]: info: [LastFM] Left init routine Oct 30 22:53:18 volumio volumio[1652]: info: [LastFM] Socket already connected: true Oct 30 22:53:18 volumio volumio[1652]: info: Volumio Calling Home Oct 30 22:53:18 volumio volumio[1652]: info: Preparing to generate the ALSA configuration file Oct 30 22:53:18 volumio volumio[1652]: info: [LastFM] finished init with error: Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Oct 30 22:53:18 volumio volumio[1652]: info: [now-playing] App is listening on port 4004. Oct 30 22:53:18 volumio volumio[1652]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Oct 30 22:53:18 volumio volumio[1652]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 30 22:53:18 volumio volumio[1652]: info: Reading ALSA contributions from plugins. Oct 30 22:53:18 volumio volumio[1652]: info: MPD Permissions set Oct 30 22:53:18 volumio volumio[1652]: info: MPD Permissions set Oct 30 22:53:19 volumio volumio[1652]: info: Spotify config file written Oct 30 22:53:19 volumio volumio[1652]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 30 22:53:19 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:19 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:19 volumio sudo[2210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 30 22:53:19 volumio sudo[2210]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:53:19 volumio systemd[1]: Stopping go-librespot Daemon... Oct 30 22:53:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Oct 30 22:53:19 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Oct 30 22:53:19 volumio systemd[1]: Stopped go-librespot Daemon. Oct 30 22:53:19 volumio systemd[1]: Started go-librespot Daemon. Oct 30 22:53:19 volumio go-librespot[2214]: go-librespot daemon starting... Oct 30 22:53:19 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 22:53:19 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 30 22:53:19 volumio sudo[2210]: pam_unix(sudo:session): session closed for user root Oct 30 22:53:19 volumio go-librespot[2214]: time="2025-10-30T22:53:19+02:00" level=info msg="running go-librespot 0.2.0" Oct 30 22:53:19 volumio go-librespot[2214]: time="2025-10-30T22:53:19+02:00" level=debug msg="app state loaded" Oct 30 22:53:19 volumio go-librespot[2214]: time="2025-10-30T22:53:19+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 22:53:19 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:19 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:19 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:19 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:19 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 30 22:53:19 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 14. Oct 30 22:53:19 volumio systemd[1]: Stopped FusionDsp Daemon. Oct 30 22:53:19 volumio systemd[1]: Started FusionDsp Daemon. Oct 30 22:53:19 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 22:53:19 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 30 22:53:19 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:19 volumio volumio[1652]: info: No need to fix Spotify hosts Oct 30 22:53:19 volumio volumio[2229]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Oct 30 22:53:19 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Oct 30 22:53:19 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 30 22:53:19 volumio volumio[1652]: info: Starting Shairport Sync Oct 30 22:53:20 volumio volumio[1652]: info: Starting Shairport Sync Oct 30 22:53:20 volumio sudo[2233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 30 22:53:20 volumio sudo[2233]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:53:20 volumio volumio[1652]: info: Starting Shairport Sync Oct 30 22:53:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 30 22:53:20 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 30 22:53:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 30 22:53:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 30 22:53:20 volumio sudo[2233]: pam_unix(sudo:session): session closed for user root Oct 30 22:53:20 volumio mpd[2195]: Oct 30 22:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 30 22:53:20 volumio systemd[1]: Started Music Player Daemon. Oct 30 22:53:20 volumio sudo[2236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 30 22:53:20 volumio sudo[2236]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:53:20 volumio sudo[2185]: pam_unix(sudo:session): session closed for user root Oct 30 22:53:20 volumio sudo[2173]: pam_unix(sudo:session): session closed for user root Oct 30 22:53:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 30 22:53:20 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 30 22:53:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 30 22:53:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 30 22:53:20 volumio sudo[2243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 30 22:53:20 volumio sudo[2236]: pam_unix(sudo:session): session closed for user root Oct 30 22:53:20 volumio sudo[2243]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:53:20 volumio volumio[1652]: info: CoreCommandRouter::volumioGetState Oct 30 22:53:20 volumio volumio[1652]: info: CorePlayQueue::getTrack 0 Oct 30 22:53:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 30 22:53:20 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 30 22:53:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 30 22:53:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 30 22:53:20 volumio sudo[2243]: pam_unix(sudo:session): session closed for user root Oct 30 22:53:20 volumio volumio[1652]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 30 22:53:20 volumio volumio[1652]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Oct 30 22:53:20 volumio volumio[1652]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 30 22:53:20 volumio volumio[1652]: info: camilladsp service started and running in background, instance 1 Oct 30 22:53:20 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 22:53:20 volumio volumio[1652]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 30 22:53:20 volumio volumio[1652]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 30 22:53:20 volumio volumio[1652]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 30 22:53:20 volumio volumio[1652]: info: FusionDsp loaded Oct 30 22:53:20 volumio volumio[1652]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 22:53:20 volumio volumio[1652]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 30 22:53:20 volumio sudo[2260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 30 22:53:20 volumio sudo[2260]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:53:20 volumio volumio[1652]: info: Adding Signal Path Element [object Object] Oct 30 22:53:20 volumio volumio[1652]: info: Adding fusiondspeq DSP Signal Path Element Oct 30 22:53:20 volumio volumio[1652]: info: FusionDsp - ---- installed callbackRead Oct 30 22:53:20 volumio volumio[1652]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 22:53:20 volumio volumio[1652]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 30 22:53:20 volumio volumio[1652]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Oct 30 22:53:20 volumio volumio[1652]: at onErrorNT (internal/child_process.js:472:16) Oct 30 22:53:20 volumio volumio[1652]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 30 22:53:20 volumio volumio[1652]: at runNextTicks (internal/process/task_queues.js:64:3) Oct 30 22:53:20 volumio volumio[1652]: at listOnTimeout (internal/timers.js:526:9) Oct 30 22:53:20 volumio volumio[1652]: at processTimers (internal/timers.js:500:7) { Oct 30 22:53:20 volumio volumio[1652]: errno: -2, Oct 30 22:53:20 volumio volumio[1652]: code: 'ENOENT', Oct 30 22:53:20 volumio volumio[1652]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 30 22:53:20 volumio volumio[1652]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 30 22:53:20 volumio volumio[1652]: spawnargs: [ Oct 30 22:53:20 volumio volumio[1652]: '-p', Oct 30 22:53:20 volumio volumio[1652]: 9876, Oct 30 22:53:20 volumio volumio[1652]: '-o', Oct 30 22:53:20 volumio volumio[1652]: '/tmp/camilladsp.log', Oct 30 22:53:20 volumio volumio[1652]: '-l', Oct 30 22:53:20 volumio volumio[1652]: 'warn', Oct 30 22:53:20 volumio volumio[1652]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 30 22:53:20 volumio volumio[1652]: ] Oct 30 22:53:20 volumio volumio[1652]: } Oct 30 22:53:20 volumio volumio[1652]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 22:53:21 volumio sudo[2271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-30 22:52 Oct 30 22:53:21 volumio sudo[2271]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 22:53:21 volumio volumio-remote-updater[647]: [2025-10-30 22:53:21] [connect] Successful connection 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"