May 26 05:54:00 volumiodell volumio[1140]: info: ------------------------------------------- May 26 05:54:00 volumiodell volumio[1140]: info: ----- Volumio3 ---- May 26 05:54:00 volumiodell volumio[1140]: info: ------------------------------------------- May 26 05:54:00 volumiodell volumio[1140]: info: ----- System startup ---- May 26 05:54:00 volumiodell volumio[1140]: info: ------------------------------------------- May 26 05:54:00 volumiodell dhcpcd[811]: eth0: carrier acquired May 26 05:54:00 volumiodell kernel: r8169 0000:04:00.0 eth0: Link is Up - 1Gbps/Full - flow control off May 26 05:54:00 volumiodell dhcpcd[811]: eth0: IAID f3:e2:f6:31 May 26 05:54:00 volumiodell dhcpcd[811]: eth0: probing address 172.30.1.70/24 May 26 05:54:00 volumiodell ifplugd(eth0)[1100]: Link beat detected. May 26 05:54:00 volumiodell ifplugd(eth0)[1100]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 26 05:54:00 volumiodell ntpd[1087]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 May 26 05:54:00 volumiodell ntpd[1087]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 May 26 05:54:00 volumiodell ntpd[1087]: DNS: dns_check: DNS error: -11, System error May 26 05:54:00 volumiodell ntpd[1087]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 May 26 05:54:00 volumiodell ifplugd(eth0)[1100]: client: ifup: interface eth0 already configured May 26 05:54:01 volumiodell ifplugd(eth0)[1100]: Program executed successfully. May 26 05:54:01 volumiodell volumio[1140]: info: MYVOLUMIO Environment detected May 26 05:54:01 volumiodell volumio[1140]: info: Plugin folders cleanup May 26 05:54:01 volumiodell volumio[1140]: info: Scanning into folder /volumio/app/plugins/ May 26 05:54:01 volumiodell volumio[1140]: info: Scanning category audio_interface May 26 05:54:01 volumiodell volumio[1140]: info: Scanning category miscellanea May 26 05:54:01 volumiodell volumio[1140]: info: Scanning category music_service May 26 05:54:01 volumiodell volumio[1140]: info: Scanning category plugins.json May 26 05:54:01 volumiodell volumio[1140]: info: Scanning category system_controller May 26 05:54:01 volumiodell volumio[1140]: info: Scanning category user_interface May 26 05:54:01 volumiodell volumio[1140]: info: Scanning into folder /data/plugins/ May 26 05:54:01 volumiodell volumio[1140]: info: Scanning category audio_interface May 26 05:54:01 volumiodell volumio[1140]: info: Scanning category music_service May 26 05:54:01 volumiodell volumio[1140]: info: Scanning category system_controller May 26 05:54:01 volumiodell volumio[1140]: info: Scanning category system_hardware May 26 05:54:01 volumiodell volumio[1140]: info: Scanning category user_interface May 26 05:54:01 volumiodell volumio[1140]: info: Plugin folders cleanup completed May 26 05:54:01 volumiodell volumio[1140]: info: ------------------------------------------- May 26 05:54:01 volumiodell volumio[1140]: info: ----- Core plugins startup ---- May 26 05:54:01 volumiodell volumio[1140]: info: ------------------------------------------- May 26 05:54:01 volumiodell volumio[1140]: info: Loading plugins from folder /volumio/app/plugins/ May 26 05:54:01 volumiodell volumio[1140]: info: Adding plugin upnp to MyMusic Plugins May 26 05:54:01 volumiodell volumio[1140]: info: Adding plugin airplay_emulation to MyMusic Plugins May 26 05:54:01 volumiodell volumio[1140]: info: Adding plugin upnp_browser to MyMusic Plugins May 26 05:54:01 volumiodell volumio[1140]: info: Loading plugins from folder /data/plugins/ May 26 05:54:01 volumiodell volumio[1140]: info: Loading plugin "system"... May 26 05:54:01 volumiodell volumio[1140]: info: Loading plugin "appearance"... May 26 05:54:01 volumiodell dhcpcd[811]: eth0: soliciting an IPv6 router May 26 05:54:01 volumiodell ntpd[1087]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 May 26 05:54:01 volumiodell ntpd[1087]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 May 26 05:54:01 volumiodell ntpd[1087]: DNS: dns_check: DNS error: -11, System error May 26 05:54:01 volumiodell ntpd[1087]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 May 26 05:54:02 volumiodell volumio-remote-updater[792]: [2026-05-26 05:54:02] [connect] Successful connection May 26 05:54:02 volumiodell volumio[1140]: info: Loading plugin "network"... May 26 05:54:02 volumiodell volumio[1140]: info: Refreshing Cached IP Addresses May 26 05:54:02 volumiodell volumio[1140]: info: Loading plugin "services"... May 26 05:54:02 volumiodell volumio[1140]: info: Loading plugin "volumio5onboarding"... May 26 05:54:02 volumiodell volumio[1140]: info: Loading plugin "alsa_controller"... May 26 05:54:02 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 05:54:02 volumiodell volumio[1140]: info: Loading plugin "wizard"... May 26 05:54:02 volumiodell volumio[1140]: info: Loading plugin "networkfs"... May 26 05:54:02 volumiodell sudo[1379]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 05:54:02 volumiodell sudo[1389]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 26 05:54:02 volumiodell sudo[1381]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 05:54:02 volumiodell sudo[1389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:02 volumiodell sudo[1379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:02 volumiodell sudo[1381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:02 volumiodell sudo[1379]: pam_unix(sudo:session): session closed for user root May 26 05:54:02 volumiodell sudo[1381]: pam_unix(sudo:session): session closed for user root May 26 05:54:02 volumiodell sudo[1389]: pam_unix(sudo:session): session closed for user root May 26 05:54:02 volumiodell volumio[1140]: info: Starting Udev Watcher for removable devices May 26 05:54:02 volumiodell volumio[1140]: info: Mounting Device SOUND2T May 26 05:54:02 volumiodell sudo[1422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/SOUND2T -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 26 05:54:02 volumiodell sudo[1422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:03 volumiodell ntfs-3g[1458]: Version 2022.10.3 integrated FUSE 28 May 26 05:54:03 volumiodell ntfs-3g[1458]: Mounted /dev/sda1 (Read-Write, label "SOUND2T", NTFS 3.1) May 26 05:54:03 volumiodell ntfs-3g[1458]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 May 26 05:54:03 volumiodell ntfs-3g[1458]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 May 26 05:54:03 volumiodell ntfs-3g[1458]: Global ownership and permissions enforced, configuration type 7 May 26 05:54:03 volumiodell sudo[1422]: pam_unix(sudo:session): session closed for user root May 26 05:54:03 volumiodell volumio[1140]: info: Ignoring mount for partition: boot May 26 05:54:03 volumiodell volumio[1140]: info: Ignoring mount for partition: volumio May 26 05:54:03 volumiodell volumio[1140]: info: Ignoring mount for partition: volumio_data May 26 05:54:03 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 26 05:54:03 volumiodell volumio[1140]: info: Loading plugin "volumio_command_line_client"... May 26 05:54:03 volumiodell volumio[1140]: info: Plugin upnp is not enabled May 26 05:54:03 volumiodell volumio[1140]: info: Loading plugin "my_music"... May 26 05:54:03 volumiodell volumio[1140]: info: Loading plugin "mpd"... May 26 05:54:04 volumiodell bash[1070]: setdatetime-helper: all HTTPS Date fallbacks failed May 26 05:54:04 volumiodell systemd[1]: setdatetime-helper.service: Deactivated successfully. May 26 05:54:04 volumiodell systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 26 05:54:04 volumiodell volumio[1140]: info: Loading plugin "upnp_browser"... May 26 05:54:05 volumiodell volumio[1140]: info: Starting UPNP Browser May 26 05:54:05 volumiodell volumio[1140]: info: Loading plugin "alarm-clock"... May 26 05:54:05 volumiodell volumio[1140]: info: Loading plugin "airplay_emulation"... May 26 05:54:05 volumiodell volumio[1140]: info: Starting Shairport Sync May 26 05:54:05 volumiodell volumio[1140]: info: Loading plugin "last_100"... May 26 05:54:05 volumiodell dbus-daemon[780]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.7' (uid=0 pid=1362 comm="/usr/lib/chromium/chromium --show-component-extens") May 26 05:54:05 volumiodell volumio[1140]: info: Loading plugin "webradio"... May 26 05:54:05 volumiodell systemd[1]: Starting bluetooth.service - Bluetooth service... May 26 05:54:05 volumiodell volumio[1140]: info: Loading plugin "i2s_dacs"... May 26 05:54:05 volumiodell volumio[1140]: info: I2S DAC not set, start Auto-detection May 26 05:54:05 volumiodell volumio[1140]: info: Loading plugin "volumiodiscovery"... May 26 05:54:05 volumiodell kernel: Bluetooth: Core ver 2.22 May 26 05:54:05 volumiodell kernel: NET: Registered PF_BLUETOOTH protocol family May 26 05:54:05 volumiodell kernel: Bluetooth: HCI device and connection manager initialized May 26 05:54:05 volumiodell kernel: Bluetooth: HCI socket layer initialized May 26 05:54:05 volumiodell kernel: Bluetooth: L2CAP socket layer initialized May 26 05:54:05 volumiodell kernel: Bluetooth: SCO socket layer initialized May 26 05:54:05 volumiodell bluetoothd[1481]: Bluetooth daemon 5.83 May 26 05:54:05 volumiodell dbus-daemon[780]: [system] Successfully activated service 'org.bluez' May 26 05:54:05 volumiodell systemd[1]: Started bluetooth.service - Bluetooth service. May 26 05:54:05 volumiodell node[1140]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 26 05:54:05 volumiodell volumio[1140]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 26 05:54:05 volumiodell volumio[1140]: *** WARNING *** Please fix your application to use the native API of Avahi! May 26 05:54:05 volumiodell volumio[1140]: *** WARNING *** For more information see May 26 05:54:05 volumiodell volumio[1140]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 26 05:54:05 volumiodell volumio[1140]: *** WARNING *** Please fix your application to use the native API of Avahi! May 26 05:54:05 volumiodell volumio[1140]: *** WARNING *** For more information see May 26 05:54:05 volumiodell node[1140]: *** WARNING *** Please fix your application to use the native API of Avahi! May 26 05:54:05 volumiodell node[1140]: *** WARNING *** For more information see May 26 05:54:05 volumiodell node[1140]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 26 05:54:05 volumiodell node[1140]: *** WARNING *** Please fix your application to use the native API of Avahi! May 26 05:54:05 volumiodell node[1140]: *** WARNING *** For more information see May 26 05:54:05 volumiodell bluetoothd[1481]: Starting SDP server May 26 05:54:05 volumiodell bluetoothd[1481]: Excluding (cli) sap May 26 05:54:05 volumiodell bluetoothd[1481]: Excluding (cli) scanparam May 26 05:54:05 volumiodell bluetoothd[1481]: Excluding (cli) midi May 26 05:54:05 volumiodell bluetoothd[1481]: Excluding (cli) battery May 26 05:54:05 volumiodell volumio[1140]: info: Applying required configuration parameters for plugin volumiodiscovery May 26 05:54:05 volumiodell volumio[1140]: info: Discovery: Started advertising with name: VolumioDell May 26 05:54:05 volumiodell kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 26 05:54:05 volumiodell kernel: Bluetooth: BNEP filters: protocol multicast May 26 05:54:05 volumiodell kernel: Bluetooth: BNEP socket layer initialized May 26 05:54:05 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 26 05:54:05 volumiodell volumio[1140]: info: Loading plugin "rp2"... May 26 05:54:05 volumiodell bluetoothd[1481]: Bluetooth management interface 1.23 initialized May 26 05:54:05 volumiodell dbus-daemon[780]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.8' (uid=0 pid=1481 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") May 26 05:54:05 volumiodell systemd[1]: Starting systemd-hostnamed.service - Hostname Service... May 26 05:54:05 volumiodell dhcpcd[811]: eth0: using static address 172.30.1.70/24 May 26 05:54:05 volumiodell avahi-daemon[779]: Joining mDNS multicast group on interface eth0.IPv4 with address 172.30.1.70. May 26 05:54:05 volumiodell avahi-daemon[779]: New relevant interface eth0.IPv4 for mDNS. May 26 05:54:05 volumiodell avahi-daemon[779]: Registering new address record for 172.30.1.70 on eth0.IPv4. May 26 05:54:05 volumiodell dhcpcd[811]: eth0: adding route to 172.30.1.0/24 May 26 05:54:05 volumiodell dhcpcd[811]: eth0: adding default route via 172.30.1.254 May 26 05:54:05 volumiodell systemd[1]: welcome.service: Deactivated successfully. May 26 05:54:05 volumiodell systemd[1]: Stopped welcome.service - Show a welcome message on console. May 26 05:54:05 volumiodell systemd[1]: Stopping welcome.service - Show a welcome message on console... May 26 05:54:05 volumiodell systemd[1]: Starting welcome.service - Show a welcome message on console... May 26 05:54:05 volumiodell welcome[1554]: Resolved ip:[1] 172.30.1.70 May 26 05:54:05 volumiodell dbus-daemon[780]: [system] Successfully activated service 'org.freedesktop.hostname1' May 26 05:54:05 volumiodell systemd[1]: Started systemd-hostnamed.service - Hostname Service. May 26 05:54:05 volumiodell systemd[1]: Finished welcome.service - Show a welcome message on console. May 26 05:54:05 volumiodell systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 26 05:54:06 volumiodell systemd[1]: Started nmbd.service - Samba NMB Daemon. May 26 05:54:06 volumiodell systemd[1]: Starting winbind.service - Samba Winbind Daemon... May 26 05:54:06 volumiodell winbindd[1582]: [2026/05/26 05:54:06.394002, 0] ../../source3/winbindd/winbindd.c:1440(main) May 26 05:54:06 volumiodell winbindd[1582]: winbindd version 4.17.12-Debian started. May 26 05:54:06 volumiodell winbindd[1582]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 26 05:54:06 volumiodell winbindd[1582]: [2026/05/26 05:54:06.402559, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) May 26 05:54:06 volumiodell winbindd[1582]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 26 05:54:06 volumiodell systemd[1]: Started winbind.service - Samba Winbind Daemon. May 26 05:54:06 volumiodell systemd[1]: Starting smbd.service - Samba SMB Daemon... May 26 05:54:06 volumiodell smbd[1602]: [2026/05/26 05:54:06.763371, 0] ../../source3/smbd/server.c:1741(main) May 26 05:54:06 volumiodell smbd[1602]: smbd version 4.17.12-Debian started. May 26 05:54:06 volumiodell smbd[1602]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 26 05:54:06 volumiodell ntpd[1087]: IO: Listen normally on 3 eth0 172.30.1.70:123 May 26 05:54:06 volumiodell ntpd[1087]: IO: new interface(s) found: waking up resolver May 26 05:54:06 volumiodell ntpd[1087]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 May 26 05:54:07 volumiodell ntpd[1087]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 May 26 05:54:07 volumiodell ntpd[1087]: DNS: Pool taking: 1.230.135.74 May 26 05:54:07 volumiodell ntpd[1087]: DNS: Pool taking: 211.108.117.211 May 26 05:54:07 volumiodell ntpd[1087]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 May 26 05:54:07 volumiodell volumio[1140]: info: Loading plugin "spop"... May 26 05:54:07 volumiodell systemd[1]: Started smbd.service - Samba SMB Daemon. May 26 05:54:07 volumiodell systemd[1]: Reached target multi-user.target - Multi-User System. May 26 05:54:07 volumiodell systemd[1]: Reached target graphical.target - Graphical Interface. May 26 05:54:07 volumiodell systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... May 26 05:54:07 volumiodell systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. May 26 05:54:07 volumiodell systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. May 26 05:54:07 volumiodell systemd[1]: Startup finished in 13.976s (firmware) + 3.331s (loader) + 36.486s (kernel) + 13.168s (userspace) = 1min 6.962s. May 26 05:54:07 volumiodell ntpd[1087]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 May 26 05:54:08 volumiodell ntpd[1087]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 May 26 05:54:08 volumiodell ntpd[1087]: DNS: Pool skipping: 1.230.135.74 May 26 05:54:08 volumiodell ntpd[1087]: DNS: Pool taking: 158.247.202.103 May 26 05:54:08 volumiodell ntpd[1087]: DNS: Pool taking: 2001:678:8::123 May 26 05:54:08 volumiodell ntpd[1087]: DNS: Pool taking: 2401:c080:1c00:24a1:5400:5ff:fe04:720 May 26 05:54:08 volumiodell ntpd[1087]: DNS: Pool taking: 240b:400d:3:3300:aeda:71da:9779:d4f1 May 26 05:54:08 volumiodell ntpd[1087]: DNS: Pool taking: 240b:400d:3:3300:aeda:71da:9779:d8f1 May 26 05:54:08 volumiodell ntpd[1087]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 May 26 05:54:08 volumiodell volumio[1140]: info: Loading plugin "youtube2"... May 26 05:54:08 volumiodell volumio[1140]: info: Loading plugin "autostart"... May 26 05:54:08 volumiodell volumio[1140]: info: Applying required configuration parameters for plugin autostart May 26 05:54:08 volumiodell volumio[1140]: info: AutoStart - onVolumioStart - read config.json May 26 05:54:08 volumiodell volumio[1140]: info: Loading plugin "outputs"... May 26 05:54:08 volumiodell volumio[1140]: info: Loading plugin "albumart"... May 26 05:54:08 volumiodell volumio[1140]: info: Plugin example_plugin is not enabled May 26 05:54:08 volumiodell volumio[1140]: info: Loading plugin "inputs"... May 26 05:54:08 volumiodell volumio[1140]: info: Loading plugin "updater_comm"... May 26 05:54:08 volumiodell volumio[1698]: Forking 3 albumart workers May 26 05:54:08 volumiodell volumio[1140]: info: Plugin mpdemulation is not enabled May 26 05:54:08 volumiodell volumio[1140]: info: Loading plugin "rest_api"... May 26 05:54:08 volumiodell volumio[1140]: info: Loading plugin "websocket"... May 26 05:54:08 volumiodell volumio[1140]: info: Starting Socket.io Server version 1.7.4 May 26 05:54:08 volumiodell volumio[1140]: info: Loading plugin "fusiondsp"... May 26 05:54:08 volumiodell ntpd[1087]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 May 26 05:54:09 volumiodell ntpd[1087]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 May 26 05:54:09 volumiodell ntpd[1087]: DNS: Pool skipping: 158.247.202.103 May 26 05:54:09 volumiodell ntpd[1087]: DNS: Pool taking: 175.195.167.194 May 26 05:54:09 volumiodell ntpd[1087]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 May 26 05:54:09 volumiodell volumio[1140]: info: Applying required configuration parameters for plugin fusiondsp May 26 05:54:09 volumiodell volumio[1709]: Starting albumart workers May 26 05:54:09 volumiodell volumio[1708]: Starting albumart workers May 26 05:54:09 volumiodell volumio[1710]: Starting albumart workers May 26 05:54:09 volumiodell volumio[1140]: info: Loading plugin "motherearthradio"... May 26 05:54:09 volumiodell volumio[1140]: info: Loading plugin "personal_radio"... May 26 05:54:09 volumiodell volumio[1140]: info: ControllerPersonalRadio::constructor May 26 05:54:09 volumiodell volumio[1140]: info: Loading plugin "radio_paradise"... May 26 05:54:09 volumiodell volumio[1140]: info: Applying required configuration parameters for plugin radio_paradise May 26 05:54:09 volumiodell volumio[1140]: info: [1779742449844] [RadioParadise] API delay: 5 May 26 05:54:09 volumiodell volumio[1140]: info: Loading plugin "backup_restore"... May 26 05:54:09 volumiodell ntpd[1087]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 26 05:54:09 volumiodell volumio[1140]: info: Applying required configuration parameters for plugin backup_restore May 26 05:54:09 volumiodell volumio[1140]: info: Loading plugin "Bluetoothremote"... May 26 05:54:10 volumiodell volumio[1140]: info: Applying required configuration parameters for plugin Bluetoothremote May 26 05:54:10 volumiodell volumio[1140]: info: Loading plugin "display_configuration"... May 26 05:54:10 volumiodell ntpd[1087]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 26 05:54:10 volumiodell ntpd[1087]: DNS: Pool taking: 121.174.142.81 May 26 05:54:10 volumiodell ntpd[1087]: DNS: Pool taking: 121.134.215.104 May 26 05:54:10 volumiodell ntpd[1087]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 May 26 05:54:10 volumiodell volumio[1140]: info: Loading i18n strings for locale ko May 26 05:54:10 volumiodell volumio[1140]: Updating browse sources language May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::initPlayerControls May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 05:54:10 volumiodell volumio[1140]: Express server listening on port 3000 May 26 05:54:10 volumiodell volumio[1140]: [Metrics] WebUI: 10s 284.62ms May 26 05:54:10 volumiodell volumio[1140]: info: Setting Device type: x86 May 26 05:54:10 volumiodell volumio[1140]: info: CoreStateMachine::resetVolumioState May 26 05:54:10 volumiodell volumio[1140]: info: CoreStateMachine::getcurrentVolume May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::volumioRetrievevolume May 26 05:54:10 volumiodell volumio[1140]: info: CoreStateMachine::pushState May 26 05:54:10 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::volumioPushState May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:10 volumiodell volumio[1140]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 26 05:54:10 volumiodell volumio[1140]: info: Completed loading Core Plugins May 26 05:54:10 volumiodell volumio[1140]: info: Preparing to generate the ALSA configuration file May 26 05:54:10 volumiodell volumio[1140]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 26 05:54:10 volumiodell volumio[1140]: info: Reading ALSA contributions from plugins. May 26 05:54:10 volumiodell volumio[1140]: info: Volumio Network Manager: Network status updated: 0 May 26 05:54:10 volumiodell volumio[1140]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 26 05:54:10 volumiodell volumio[1140]: wlan0 Interface doesn't support scanning. May 26 05:54:10 volumiodell volumio[1140]: info: Cannot use regular scanning, forcing with ap-force May 26 05:54:10 volumiodell sudo[1762]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 26 05:54:10 volumiodell sudo[1762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:10 volumiodell sudo[1762]: pam_unix(sudo:session): session closed for user root May 26 05:54:10 volumiodell volumio[1140]: command failed: No such device (-19) May 26 05:54:10 volumiodell volumio[1140]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 26 05:54:10 volumiodell volumio[1140]: command failed: No such device (-19) May 26 05:54:10 volumiodell volumio[1140]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 26 05:54:10 volumiodell volumio[1140]: info: Reloading queue from file May 26 05:54:10 volumiodell volumio[1140]: info: CoreStateMachine::setRepeat null single undefined May 26 05:54:10 volumiodell volumio[1140]: info: CoreStateMachine::pushState May 26 05:54:10 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::volumioPushState May 26 05:54:10 volumiodell volumio[1140]: info: CoreStateMachine::setRandom true May 26 05:54:10 volumiodell volumio[1140]: info: CoreStateMachine::pushState May 26 05:54:10 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::volumioPushState May 26 05:54:10 volumiodell volumio[1140]: info: Asound.conf file unchanged, so no further update is needed May 26 05:54:10 volumiodell volumio[1140]: info: Output device has changed, restarting MPD May 26 05:54:10 volumiodell volumio[1140]: info: Output device has changed, restarting Shairport Sync May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:10 volumiodell sudo[1776]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 05:54:10 volumiodell sudo[1776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:10 volumiodell sudo[1776]: pam_unix(sudo:session): session closed for user root May 26 05:54:10 volumiodell sudo[1778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 05:54:10 volumiodell sudo[1778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:10 volumiodell volumio[1140]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 05:54:10 volumiodell volumio[1140]: info: ___________ START PLUGINS ___________ May 26 05:54:10 volumiodell volumio[1140]: info: ControllerMpd::onStart: Initializing MPD May 26 05:54:10 volumiodell volumio[1140]: info: Creating MPD Configuration file May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 05:54:10 volumiodell volumio[1140]: info: [1779742450773] CoreMusicLibrary::Adding element 미디어 서버 May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 05:54:10 volumiodell sudo[1786]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service May 26 05:54:10 volumiodell sudo[1786]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:10 volumiodell sudo[1791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 05:54:10 volumiodell sudo[1791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:10 volumiodell volumio[1140]: info: UPNP Browser: Client initialized successfully May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:10 volumiodell sudo[1791]: pam_unix(sudo:session): session closed for user root May 26 05:54:10 volumiodell sudo[1792]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 05:54:10 volumiodell sudo[1792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:10 volumiodell systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 05:54:10 volumiodell volumio[1140]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:10 volumiodell systemd[1]: Starting mpd.service - Music Player Daemon... May 26 05:54:10 volumiodell volumio[1140]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 05:54:10 volumiodell volumio[1140]: info: [1779742450834] CoreMusicLibrary::Adding element Last_100 May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 05:54:10 volumiodell volumio[1140]: info: [1779742450837] CoreMusicLibrary::Adding element Webradio May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 05:54:10 volumiodell systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 26 05:54:10 volumiodell volumio[1140]: info: Initializing BBC Radios May 26 05:54:10 volumiodell sudo[1786]: pam_unix(sudo:session): session closed for user root May 26 05:54:10 volumiodell systemd[1]: mpd.service: Deactivated successfully. May 26 05:54:10 volumiodell systemd[1]: Stopped mpd.service - Music Player Daemon. May 26 05:54:10 volumiodell systemd[1]: mpd.socket: Deactivated successfully. May 26 05:54:10 volumiodell systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 26 05:54:10 volumiodell systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 26 05:54:10 volumiodell systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 05:54:10 volumiodell systemd[1]: Starting mpd.service - Music Player Daemon... May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 05:54:10 volumiodell volumio[1140]: info: [1779742450905] CoreMusicLibrary::Adding element Radio Paradise (RP2) May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 05:54:10 volumiodell volumio[1140]: Cannot find translation for source Radio Paradise (RP2) May 26 05:54:10 volumiodell volumio[1140]: info: Creating Spotify config file May 26 05:54:10 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:10 volumiodell sudo[1812]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 26 05:54:10 volumiodell sudo[1812]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 05:54:10 volumiodell sudo[1826]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 26 05:54:10 volumiodell sudo[1812]: pam_unix(sudo:session): session closed for user root May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 05:54:11 volumiodell volumio[1140]: info: [1779742451041] CoreMusicLibrary::Adding element YouTube2 May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 05:54:11 volumiodell volumio[1140]: Cannot find translation for source Radio Paradise (RP2) May 26 05:54:11 volumiodell volumio[1140]: Cannot find translation for source YouTube2 May 26 05:54:11 volumiodell volumio[1140]: info: AutoStart - onStart - waiting for system ready state May 26 05:54:11 volumiodell volumio[1140]: info: AutoStart - Polling config: interval=5000ms, maxAttempts=60 May 26 05:54:11 volumiodell volumio[1140]: info: AutoStart - Maximum wait time: 300 seconds May 26 05:54:11 volumiodell volumio[1140]: info: AutoStart - Startup volume disabled May 26 05:54:11 volumiodell volumio[1140]: info: AutoStart - Check #1/60 - VOLUMIO_SYSTEM_STATUS = starting May 26 05:54:11 volumiodell volumio[1140]: info: FusionDsp - CamillaDSP binary found. May 26 05:54:11 volumiodell volumio[1140]: info: Loading i18n strings for locale ko May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: FusionDsp - mixtype--------------------- None May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 05:54:11 volumiodell volumio[1140]: info: [1779742451068] CoreMusicLibrary::Adding element Mother Earth Radio May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 05:54:11 volumiodell volumio[1140]: Cannot find translation for source Radio Paradise (RP2) May 26 05:54:11 volumiodell volumio[1140]: Cannot find translation for source YouTube2 May 26 05:54:11 volumiodell volumio[1140]: Cannot find translation for source Mother Earth Radio May 26 05:54:11 volumiodell volumio[1140]: info: [MER] Plugin started (SSE mode) May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 05:54:11 volumiodell volumio[1140]: info: [1779742451082] CoreMusicLibrary::Adding element 개인 라디오 May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 05:54:11 volumiodell volumio[1140]: Cannot find translation for source Radio Paradise (RP2) May 26 05:54:11 volumiodell volumio[1140]: Cannot find translation for source YouTube2 May 26 05:54:11 volumiodell volumio[1140]: Cannot find translation for source Mother Earth Radio May 26 05:54:11 volumiodell volumio[1140]: Cannot find translation for source 개인 라디오 May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 05:54:11 volumiodell volumio[1140]: info: [1779742451087] CoreMusicLibrary::Adding element Radio Paradise May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 05:54:11 volumiodell volumio[1140]: Cannot find translation for source Radio Paradise (RP2) May 26 05:54:11 volumiodell volumio[1140]: Cannot find translation for source YouTube2 May 26 05:54:11 volumiodell volumio[1140]: Cannot find translation for source Mother Earth Radio May 26 05:54:11 volumiodell volumio[1140]: Cannot find translation for source 개인 라디오 May 26 05:54:11 volumiodell volumio[1140]: Cannot find translation for source Radio Paradise May 26 05:54:11 volumiodell volumio[1140]: info: Loading i18n strings for locale ko May 26 05:54:11 volumiodell volumio[1140]: info: Display-configuration --- loadI18nStrings: loading from /data/plugins/user_interface/display_configuration/i18n/strings_en.json May 26 05:54:11 volumiodell volumio[1140]: info: Display-configuration --- loadI18nStrings: loaded 52 keys May 26 05:54:11 volumiodell volumio[1140]: warn: Display-configuration --- Failed to load ko translations, using English May 26 05:54:11 volumiodell volumio[1140]: info: Volumio Calling Home May 26 05:54:11 volumiodell volumio5-onboarding[1805]: time=2026-05-26T05:54:11.147+09:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z May 26 05:54:11 volumiodell volumio[1140]: info: Preparing to generate the ALSA configuration file May 26 05:54:11 volumiodell sudo[1830]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/bash -c 'if [ -f /root/.Xauthority ]; then cp /root/.Xauthority /home/volumio/ && chown volumio:volumio /home/volumio/.Xauthority; fi' May 26 05:54:11 volumiodell sudo[1830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:11 volumiodell sudo[1830]: pam_unix(sudo:session): session closed for user root May 26 05:54:11 volumiodell volumio[1140]: info: Discovery: adding fb2c2416-e134-49a1-a730-acd52b10d3c0 May 26 05:54:11 volumiodell volumio[1140]: info: Discovery: Found device VolumioDell May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetState May 26 05:54:11 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:11 volumiodell volumio[1140]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 26 05:54:11 volumiodell volumio[1140]: info: Reading ALSA contributions from plugins. May 26 05:54:11 volumiodell volumio[1140]: info: Display-configuration --- fixXauthority: /home/volumio/.Xauthority updated May 26 05:54:11 volumiodell volumio[1140]: info: MPD Permissions set May 26 05:54:11 volumiodell volumio[1140]: info: MPD Permissions set May 26 05:54:11 volumiodell volumio[1140]: info: Spotify config file written May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell sudo[1843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 26 05:54:11 volumiodell sudo[1843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:11 volumiodell systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 05:54:11 volumiodell systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: No need to fix Spotify hosts May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 26 05:54:11 volumiodell systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 05:54:11 volumiodell go-librespot[1853]: go-librespot daemon starting... May 26 05:54:11 volumiodell sudo[1843]: pam_unix(sudo:session): session closed for user root May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 May 26 05:54:11 volumiodell volumio[1140]: info: Starting Shairport Sync May 26 05:54:11 volumiodell volumio5-onboarding[1805]: time=2026-05-26T05:54:11.393+09:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist" May 26 05:54:11 volumiodell volumio5-onboarding[1805]: time=2026-05-26T05:54:11.397+09:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist" May 26 05:54:11 volumiodell volumio5-onboarding[1805]: time=2026-05-26T05:54:11.398+09:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist" May 26 05:54:11 volumiodell volumio5-onboarding[1805]: time=2026-05-26T05:54:11.399+09:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist" May 26 05:54:11 volumiodell volumio5-onboarding[1805]: time=2026-05-26T05:54:11.400+09:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist" May 26 05:54:11 volumiodell volumio5-onboarding[1805]: time=2026-05-26T05:54:11.400+09:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" May 26 05:54:11 volumiodell volumio[1140]: info: Starting Shairport Sync May 26 05:54:11 volumiodell volumio[1140]: info: Starting Shairport Sync May 26 05:54:11 volumiodell sudo[1875]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 05:54:11 volumiodell sudo[1875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:11 volumiodell sudo[1877]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 05:54:11 volumiodell sudo[1877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:11 volumiodell sudo[1880]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 05:54:11 volumiodell sudo[1880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:11 volumiodell volumio[1140]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 May 26 05:54:11 volumiodell volumio[1140]: info: Received Get System Info May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 05:54:11 volumiodell volumio[1140]: info: Discovery: Getting this device information May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetState May 26 05:54:11 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 05:54:11 volumiodell volumio5-onboarding[1805]: time=2026-05-26T05:54:11.454+09:00 level=INFO msg="system info for db84ffc7279d928bc4b16b6f1ff99003" deviceName=VolumioDell deviceVariant=volumio deviceModel= softwareVersion=4.119 May 26 05:54:11 volumiodell systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 05:54:11 volumiodell systemd[1]: shairport-sync.service: Deactivated successfully. May 26 05:54:11 volumiodell systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 05:54:11 volumiodell systemd[1]: shairport-sync.service: Consumed 1.822s CPU time. May 26 05:54:11 volumiodell volumio[1140]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 26 05:54:11 volumiodell volumio5-onboarding[1805]: time=2026-05-26T05:54:11.488+09:00 level=INFO msg="bootstrapping state" hasInternet=true May 26 05:54:11 volumiodell volumio[1140]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 26 05:54:11 volumiodell systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 05:54:11 volumiodell go-librespot[1860]: time="2026-05-26T05:54:11+09:00" level=info msg="running go-librespot 0.7.1" May 26 05:54:11 volumiodell go-librespot[1860]: time="2026-05-26T05:54:11+09:00" level=debug msg="app state loaded" May 26 05:54:11 volumiodell sudo[1880]: pam_unix(sudo:session): session closed for user root May 26 05:54:11 volumiodell volumio[1140]: info: Asound.conf file unchanged, so no further update is needed May 26 05:54:11 volumiodell volumio[1140]: info: Output device has changed, restarting MPD May 26 05:54:11 volumiodell sudo[1875]: pam_unix(sudo:session): session closed for user root May 26 05:54:11 volumiodell sudo[1877]: pam_unix(sudo:session): session closed for user root May 26 05:54:11 volumiodell go-librespot[1860]: time="2026-05-26T05:54:11+09:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 05:54:11 volumiodell volumio[1140]: info: Output device has changed, restarting Shairport Sync May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:11 volumiodell sudo[1887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 05:54:11 volumiodell sudo[1887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:11 volumiodell sudo[1887]: pam_unix(sudo:session): session closed for user root May 26 05:54:11 volumiodell sudo[1889]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 05:54:11 volumiodell sudo[1889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:11 volumiodell volumio[1140]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 05:54:11 volumiodell volumio[1140]: info: Received Get System Info May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 05:54:11 volumiodell volumio[1140]: info: Discovery: Getting this device information May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetState May 26 05:54:11 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 05:54:11 volumiodell systemd[1]: mpd.service: Deactivated successfully. May 26 05:54:11 volumiodell systemd[1]: Stopped mpd.service - Music Player Daemon. May 26 05:54:11 volumiodell systemd[1]: mpd.socket: Deactivated successfully. May 26 05:54:11 volumiodell systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 26 05:54:11 volumiodell systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 26 05:54:11 volumiodell volumio[1140]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 26 05:54:11 volumiodell volumio[1140]: info: Shairport-Sync Started May 26 05:54:11 volumiodell volumio[1140]: Error adding Membership: Error: addMembership EINVAL May 26 05:54:11 volumiodell systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 05:54:11 volumiodell systemd[1]: Starting mpd.service - Music Player Daemon... May 26 05:54:11 volumiodell volumio[1140]: info: MPD Permissions set May 26 05:54:11 volumiodell volumio[1140]: info: Shairport-Sync Started May 26 05:54:11 volumiodell volumio[1140]: info: Shairport-Sync Started May 26 05:54:11 volumiodell sudo[1911]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 26 05:54:11 volumiodell sudo[1911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 05:54:11 volumiodell sudo[1916]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 26 05:54:11 volumiodell sudo[1911]: pam_unix(sudo:session): session closed for user root May 26 05:54:11 volumiodell volumio[1140]: info: Discovery: this is already registered, fb2c2416-e134-49a1-a730-acd52b10d3c0 May 26 05:54:11 volumiodell volumio[1140]: info: Discovery: Found device VolumioDell May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetState May 26 05:54:11 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:11 volumiodell volumio[1140]: (node:1140) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. May 26 05:54:11 volumiodell volumio[1140]: (Use `node --trace-deprecation ...` to show where the warning was created) May 26 05:54:11 volumiodell volumio[1140]: (node:1140) [DEP0106] DeprecationWarning: crypto.createDecipher is deprecated. May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 26 05:54:11 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:11 volumiodell go-librespot[1860]: time="2026-05-26T05:54:11+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" May 26 05:54:11 volumiodell go-librespot[1860]: time="2026-05-26T05:54:11+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" May 26 05:54:11 volumiodell go-librespot[1860]: time="2026-05-26T05:54:11+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" May 26 05:54:11 volumiodell go-librespot[1860]: time="2026-05-26T05:54:11+09:00" level=info msg="zeroconf server listening on port 39517" May 26 05:54:11 volumiodell go-librespot[1860]: time="2026-05-26T05:54:11+09:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" May 26 05:54:11 volumiodell volumio[1140]: info: Display-configuration --- X server ready after 1 attempt(s) May 26 05:54:11 volumiodell volumio[1140]: info: Volumio called home May 26 05:54:11 volumiodell volumio[1140]: warn: Display-configuration --- No ACPI lid devices detected, lid monitoring disabled. May 26 05:54:11 volumiodell volumio[1140]: info: Starting Shairport Sync May 26 05:54:12 volumiodell sudo[1940]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 05:54:12 volumiodell sudo[1940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:12 volumiodell systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=debug msg="obtained new client token: AADWqGqekepcmbsvZOzarjbadesE6apfIgSXo0G8KlWRNiDWB3rP5vCwZrULlsO/0Mu6sZeQH6+f8u7BAdD3I85REMsMzcbQLRro/2T/MDerMzRJtOpQrQr7fJyzV685ndoybKvfUnQZROkN2BPa+kWKa0rDh0u6vehXG9rbPyxjOyXDxzAkk47ygRJrfzuz74MSExTi8hQklVBaaZCu3NwoZDNEgQPSKLgtFvrxGjE6WbvwXe5qVZs=" May 26 05:54:12 volumiodell volumio[1140]: info: Display-configuration --- xscreensaver started (using ~/.xscreensaver settings) May 26 05:54:12 volumiodell volumio[1140]: info: Display-configuration --- xscreensaver cleaned up before starting May 26 05:54:12 volumiodell volumio[1140]: info: Display-configuration --- DPMS disabled before playback state check May 26 05:54:12 volumiodell systemd[1]: shairport-sync.service: Deactivated successfully. May 26 05:54:12 volumiodell systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 05:54:12 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetState May 26 05:54:12 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:12 volumiodell systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 05:54:12 volumiodell sudo[1940]: pam_unix(sudo:session): session closed for user root May 26 05:54:12 volumiodell volumio[1140]: info: Display-configuration --- Connected screens: DisplayPort-1 May 26 05:54:12 volumiodell systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. May 26 05:54:12 volumiodell systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 26 05:54:12 volumiodell systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 26 05:54:12 volumiodell volumio[1140]: info: Shairport-Sync Started May 26 05:54:12 volumiodell upmpdcli[1949]: Could not open config: /tmp/upmpdcli.conf May 26 05:54:12 volumiodell systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 26 05:54:12 volumiodell systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 26 05:54:12 volumiodell volumio[1140]: info: Display-configuration --- No forced DRM orientation detected for DisplayPort-1 May 26 05:54:12 volumiodell volumio[1140]: info: Display-configuration --- TTY: display=normal(0) + offset=same(0) = 0 deg (fbconv=0) May 26 05:54:12 volumiodell volumio[1140]: info: Display-configuration --- Plymouth: display=normal(0) + offset=same(0) = 0 deg May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=debug msg="completed keyexchange" May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=debug msg="completed challenge" May 26 05:54:12 volumiodell volumio[1140]: info: Display-configuration --- Connected screens: DisplayPort-1 May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=info msg="authenticated AP" username="31************************6u" May 26 05:54:12 volumiodell volumio[1140]: info: Display-configuration --- Rotation config saved for Grub: screen=DisplayPort-1, plymouth=0, fbcon=0 May 26 05:54:12 volumiodell volumio[1140]: info: Display-configuration --- Panel Rotation applied via xrandr May 26 05:54:12 volumiodell sudo[1973]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/graphics/fbcon/rotate_all May 26 05:54:12 volumiodell sudo[1973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:12 volumiodell sudo[1973]: pam_unix(sudo:session): session closed for user root May 26 05:54:12 volumiodell volumio[1140]: info: Display-configuration --- Runtime rotation applied: normal | Boot config (plymouth=0, fbconv=0) May 26 05:54:12 volumiodell volumio[1140]: info: New Spotify access tokenBQDYNBIIXG... May 26 05:54:12 volumiodell volumio[1140]: info: Spotify credentials grant success - running version from March 24, 2019 May 26 05:54:12 volumiodell volumio[1140]: info: Display-configuration --- fbcon console rotation set to 0 May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=info msg="authenticated Login5" username="31************************6u" May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=debug msg="initializing zeroconf session" username="31************************6u" May 26 05:54:12 volumiodell volumio[1140]: info: Volumio Network Manager: Network status updated: 1 May 26 05:54:12 volumiodell volumio[1140]: info: Display-configuration --- Connected screens: DisplayPort-1 May 26 05:54:12 volumiodell volumio[1140]: SPOTIFY: User informations: {"country":"KR","display_name":"Js K🔥","email":"kijisy@naver.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31lb64mcfavfr4q7lpgyt6spcf6u"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31lb64mcfavfr4q7lpgyt6spcf6u","id":"31lb64mcfavfr4q7lpgyt6spcf6u","images":[],"product":"premium","type":"user","uri":"spotify:user:31lb64mcfavfr4q7lpgyt6spcf6u"} May 26 05:54:12 volumiodell volumio[1140]: info: Spotify Successfully logged in May 26 05:54:12 volumiodell volumio[1140]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=debug msg="dealer connection opened" May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=trace msg="starting accesspoint recv loop" May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=trace msg="starting dealer recv loop" May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=trace msg="received accesspoint ping" May 26 05:54:12 volumiodell volumio[1140]: info: [1779742452733] CoreMusicLibrary::Adding element Spotify May 26 05:54:12 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 26 05:54:12 volumiodell volumio[1140]: Cannot find translation for source Radio Paradise (RP2) May 26 05:54:12 volumiodell volumio[1140]: Cannot find translation for source YouTube2 May 26 05:54:12 volumiodell volumio[1140]: Cannot find translation for source Mother Earth Radio May 26 05:54:12 volumiodell volumio[1140]: Cannot find translation for source 개인 라디오 May 26 05:54:12 volumiodell volumio[1140]: Cannot find translation for source Radio Paradise May 26 05:54:12 volumiodell volumio[1140]: Cannot find translation for source Spotify May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=debug msg="received connection id: N2YxMzBiMzMtMTE2...ODcwQUI5Q0U0NQ==" May 26 05:54:12 volumiodell volumio[1140]: info: Display-configuration --- No touchscreen detected, skipping correction. May 26 05:54:12 volumiodell volumio[1140]: info: Display-configuration --- applyPointerCorrection: pointer_offset=0 May 26 05:54:12 volumiodell volumio[1140]: info: Display-configuration --- Pointer correction: none (xrandr handles relative devices) May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=trace msg="received accesspoint pong ack" May 26 05:54:12 volumiodell volumio[1140]: warn: Display-configuration --- No backlight device found, brightness control unavailable. Falling back to Soft Brightness May 26 05:54:12 volumiodell sudo[1996]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -9 -f unclutter May 26 05:54:12 volumiodell sudo[1996]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=debug msg="put connect state because NEW_DEVICE" May 26 05:54:12 volumiodell go-librespot[1860]: time="2026-05-26T05:54:12+09:00" level=debug msg="update volume requested to 65535/65535" May 26 05:54:12 volumiodell volumio[1140]: info: Display-configuration --- No unclutter process to stop May 26 05:54:13 volumiodell sudo[2002]: volumio : PWD=/ ; USER=root ; ENV=DISPLAY=:0 ; COMMAND=/usr/bin/unclutter-xfixes -idle 3 May 26 05:54:13 volumiodell sudo[2002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:13 volumiodell volumio[1140]: info: Display-configuration --- Connected screens: DisplayPort-1 May 26 05:54:13 volumiodell go-librespot[1860]: time="2026-05-26T05:54:13+09:00" level=debug msg="put connect state because VOLUME_CHANGED" May 26 05:54:13 volumiodell go-librespot[1860]: time="2026-05-26T05:54:13+09:00" level=trace msg="emitting websocket event: volume" May 26 05:54:13 volumiodell volumio[1140]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 26 05:54:13 volumiodell volumio[1140]: info: camilladsp service started and running in background, instance 1 May 26 05:54:13 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:13 volumiodell volumio[1140]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA May 26 05:54:13 volumiodell volumio[1140]: info: FusionDsp loaded May 26 05:54:13 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 05:54:13 volumiodell volumio[1140]: info: FusionDsp - --[object Object] May 26 05:54:13 volumiodell volumio[1140]: info: FusionDsp - Reporting Fusion DSP Enabled May 26 05:54:13 volumiodell volumio[1140]: info: Adding Signal Path Element [object Object] May 26 05:54:13 volumiodell volumio[1140]: info: Adding fusiondspeq DSP Signal Path Element May 26 05:54:13 volumiodell volumio[1140]: info: FusionDsp - ---- installed callbackRead (debounced 200ms) May 26 05:54:13 volumiodell volumio[1140]: info: FusionDsp - PEQ graph server listening on port 10015 May 26 05:54:13 volumiodell volumio[1140]: info: Display-configuration --- Brightness set to 80% for screen DisplayPort-1 May 26 05:54:13 volumiodell sudo[2021]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 26 05:54:13 volumiodell sudo[2021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:13 volumiodell volumio[1140]: error: FusionDsp - Reload WebSocket error: [object Object] May 26 05:54:13 volumiodell systemd[1]: Starting fusiondsp.service - FusionDsp Daemon... May 26 05:54:13 volumiodell volumio[1140]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 26 05:54:13 volumiodell mpd[1917]: 2026-05-26T05:54:13 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 26 05:54:14 volumiodell systemd[1]: Started mpd.service - Music Player Daemon. May 26 05:54:14 volumiodell sudo[1889]: pam_unix(sudo:session): session closed for user root May 26 05:54:14 volumiodell sudo[1792]: pam_unix(sudo:session): session closed for user root May 26 05:54:14 volumiodell sudo[1778]: pam_unix(sudo:session): session closed for user root May 26 05:54:14 volumiodell volumio[1140]: info: Completed starting Core Plugins May 26 05:54:14 volumiodell volumio[1140]: info: ------------------------------------------- May 26 05:54:14 volumiodell volumio[1140]: info: ----- MyVolumio plugins startup ---- May 26 05:54:14 volumiodell volumio[1140]: info: ------------------------------------------- May 26 05:54:14 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Fetching plans data.... May 26 05:54:14 volumiodell volumio[1140]: error: MPD error: The expression evaluated to a falsy value: May 26 05:54:14 volumiodell volumio[1140]: assert.ok(self.idling) May 26 05:54:14 volumiodell volumio[1140]: error: The expression evaluated to a falsy value: May 26 05:54:14 volumiodell volumio[1140]: assert.ok(self.idling) May 26 05:54:14 volumiodell volumio[1140]: info: Display-configuration --- Kiosk browser detected after 1s, re-applying input settings in 3s May 26 05:54:14 volumiodell volumio[1140]: error: MPD error: The expression evaluated to a falsy value: May 26 05:54:14 volumiodell volumio[1140]: assert.ok(self.idling) May 26 05:54:14 volumiodell volumio[1140]: error: The expression evaluated to a falsy value: May 26 05:54:14 volumiodell volumio[1140]: assert.ok(self.idling) May 26 05:54:14 volumiodell volumio[1140]: info: MPD running with PID1917 May 26 05:54:14 volumiodell volumio[1140]: ,establishing connection May 26 05:54:14 volumiodell volumio[1140]: error: MPD error: The expression evaluated to a falsy value: May 26 05:54:14 volumiodell volumio[1140]: assert.ok(self.idling) May 26 05:54:14 volumiodell volumio[1140]: error: The expression evaluated to a falsy value: May 26 05:54:14 volumiodell volumio[1140]: assert.ok(self.idling) May 26 05:54:14 volumiodell volumio[1140]: error: updateQueue error: null May 26 05:54:14 volumiodell volumio[1140]: info: go-librespot daemon successfully initialized May 26 05:54:14 volumiodell volumio[1140]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 26 05:54:14 volumiodell volumio-remote-updater[792]: [2026-05-26 05:54:14] [connect] Successful connection May 26 05:54:14 volumiodell volumio-remote-updater[792]: [2026-05-26 05:54:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1779742454 101 May 26 05:54:14 volumiodell volumio[1140]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 7 May 26 05:54:14 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 26 05:54:14 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetVisibleSources May 26 05:54:14 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 05:54:14 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetState May 26 05:54:14 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:14 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 26 05:54:14 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 26 05:54:14 volumiodell volumio[1140]: info: Received Get System Info May 26 05:54:14 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 05:54:14 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 05:54:14 volumiodell volumio[1140]: info: Discovery: Getting this device information May 26 05:54:14 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetState May 26 05:54:14 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:14 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 05:54:14 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetState May 26 05:54:14 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:14 volumiodell volumio[1140]: info: Listing playlists May 26 05:54:14 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 26 05:54:14 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 26 05:54:15 volumiodell ntpd[1087]: CLOCK: time stepped by 0.133843 May 26 05:54:15 volumiodell ntpd[1087]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes May 26 05:54:15 volumiodell volumio[1140]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 05:54:15 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetState May 26 05:54:15 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:16 volumiodell volumio[1140]: info: AutoStart - Check #2/60 - VOLUMIO_SYSTEM_STATUS = starting May 26 05:54:16 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 05:54:16 volumiodell volumio[1140]: info: Received Get System Info May 26 05:54:16 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 05:54:16 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 05:54:16 volumiodell volumio[1140]: info: Discovery: Getting this device information May 26 05:54:16 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetState May 26 05:54:16 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:16 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 05:54:16 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 26 05:54:17 volumiodell volumio[1140]: info: Display-configuration --- Connected screens: DisplayPort-1 May 26 05:54:17 volumiodell volumio[1140]: info: Display-configuration --- No touchscreen detected, skipping correction. May 26 05:54:17 volumiodell volumio[1140]: info: Display-configuration --- applyPointerCorrection: pointer_offset=0 May 26 05:54:17 volumiodell volumio[1140]: info: Display-configuration --- Pointer correction: none (xrandr handles relative devices) May 26 05:54:17 volumiodell volumio[1140]: info: Display-configuration --- Input settings re-applied after kiosk start May 26 05:54:17 volumiodell volumio[1140]: info: Initializing connection to go-librespot Websocket May 26 05:54:17 volumiodell go-librespot[1860]: time="2026-05-26T05:54:17+09:00" level=debug msg="new websocket client" May 26 05:54:17 volumiodell volumio[1140]: info: Connection to go-librespot Websocket established May 26 05:54:18 volumiodell systemd[1]: Started fusiondsp.service - FusionDsp Daemon. May 26 05:54:18 volumiodell sudo[2021]: pam_unix(sudo:session): session closed for user root May 26 05:54:18 volumiodell systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 26 05:54:20 volumiodell volumio[1140]: info: Getting Spotify volume May 26 05:54:21 volumiodell volumio[1140]: info: Spotify volume: 100 May 26 05:54:21 volumiodell volumio[1140]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 26 05:54:21 volumiodell volumio[1140]: info: AutoStart - Check #3/60 - VOLUMIO_SYSTEM_STATUS = starting May 26 05:54:22 volumiodell systemd[1]: setdatetime-helper.service: Deactivated successfully. May 26 05:54:22 volumiodell systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 26 05:54:23 volumiodell systemd[1]: Starting fstrim.service - Discard unused blocks on filesystems from /etc/fstab... May 26 05:54:24 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetState May 26 05:54:24 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:24 volumiodell fstrim[2097]: /boot: 274.2 MiB (287551488 bytes) trimmed on /dev/sdb1 May 26 05:54:24 volumiodell systemd[1]: fstrim.service: Deactivated successfully. May 26 05:54:24 volumiodell systemd[1]: Finished fstrim.service - Discard unused blocks on filesystems from /etc/fstab. May 26 05:54:25 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 26 05:54:25 volumiodell systemd[1]: systemd-fsckd.service: Deactivated successfully. May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 26 05:54:26 volumiodell volumio[1140]: info: Adding plugin bluetooth to MyMusic Plugins May 26 05:54:26 volumiodell volumio[1140]: info: Adding plugin multiroom to MyMusic Plugins May 26 05:54:26 volumiodell volumio[1140]: info: Adding plugin metavolumio to MyMusic Plugins May 26 05:54:26 volumiodell volumio[1140]: info: Adding plugin cd_controller to MyMusic Plugins May 26 05:54:26 volumiodell volumio[1140]: info: Adding plugin qobuzconnect to MyMusic Plugins May 26 05:54:26 volumiodell volumio[1140]: info: Adding plugin smart_inputs to MyMusic Plugins May 26 05:54:26 volumiodell volumio[1140]: info: Adding plugin tidalconnect to MyMusic Plugins May 26 05:54:26 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 26 05:54:27 volumiodell volumio[1140]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 26 05:54:27 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 26 05:54:27 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:27 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:27 volumiodell volumio[1140]: info: Starting MyVolumio Remote Streaming Endpoints May 26 05:54:27 volumiodell volumio[1140]: info: MyVolumio login type: Token May 26 05:54:27 volumiodell volumio[1140]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 26 05:54:27 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 26 05:54:27 volumiodell systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. May 26 05:54:27 volumiodell systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 26 05:54:27 volumiodell systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 26 05:54:27 volumiodell upmpdcli[2102]: Could not open config: /tmp/upmpdcli.conf May 26 05:54:27 volumiodell systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 26 05:54:27 volumiodell systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 26 05:54:27 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 26 05:54:27 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 26 05:54:27 volumiodell volumio[1140]: info: Streaming services startup May 26 05:54:27 volumiodell volumio[1140]: info: Starting Streaming Daemon May 26 05:54:27 volumiodell sudo[2104]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 26 05:54:27 volumiodell sudo[2104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:27 volumiodell volumio[1140]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 26 05:54:27 volumiodell sudo[2104]: pam_unix(sudo:session): session closed for user root May 26 05:54:27 volumiodell volumio[1140]: info: AutoStart - Check #4/60 - VOLUMIO_SYSTEM_STATUS = starting May 26 05:54:28 volumiodell volumio[1140]: error: Cannot start Volumio Streaming Daemon May 26 05:54:28 volumiodell volumio[1140]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 26 05:54:28 volumiodell volumio[1140]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 26 05:54:29 volumiodell volumio[1140]: Cannot compose Albumart path May 26 05:54:29 volumiodell volumio[1140]: Cannot compose Albumart path May 26 05:54:29 volumiodell volumio[1140]: Cannot compose Albumart path May 26 05:54:29 volumiodell volumio[1140]: Cannot compose Albumart path May 26 05:54:30 volumiodell volumio[1140]: error: MyVolumio Custom Token format not valid, refreshing it May 26 05:54:31 volumiodell volumio[1140]: info: MyVolumio login type: Token May 26 05:54:32 volumiodell volumio[1140]: info: AutoStart - Check #5/60 - VOLUMIO_SYSTEM_STATUS = starting May 26 05:54:32 volumiodell volumio[1140]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 26 05:54:33 volumiodell volumio[1140]: info: MyVolumio token set successfully May 26 05:54:33 volumiodell volumio[1140]: info: MYVOLUMIO: Adding device May 26 05:54:33 volumiodell volumio[1140]: info: MYVOLUMIO: Evaluating Server May 26 05:54:33 volumiodell volumio[1140]: info: MyVolumio status changed May 26 05:54:33 volumiodell volumio[1140]: info: Streaming services startup May 26 05:54:33 volumiodell volumio[1140]: info: Starting Streaming Daemon May 26 05:54:33 volumiodell volumio[1140]: info: Removing browser output: myVolumio user plan is not superstar May 26 05:54:33 volumiodell volumio[1140]: info: Removing audio output: May 26 05:54:33 volumiodell volumio[1140]: info: Stoppping Tunnel 1 May 26 05:54:33 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 26 05:54:33 volumiodell volumio[1140]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 26 05:54:33 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 26 05:54:33 volumiodell sudo[2151]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 26 05:54:33 volumiodell sudo[2151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:33 volumiodell volumio[1140]: info: Received Get System Version May 26 05:54:33 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 05:54:33 volumiodell volumio[1140]: info: Received Get System Info May 26 05:54:33 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 05:54:33 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 05:54:33 volumiodell volumio[1140]: info: Discovery: Getting this device information May 26 05:54:33 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetState May 26 05:54:33 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:33 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 05:54:33 volumiodell sudo[2153]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 26 05:54:33 volumiodell sudo[2153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 05:54:33 volumiodell sudo[2151]: pam_unix(sudo:session): session closed for user root May 26 05:54:33 volumiodell systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 05:54:33 volumiodell systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 05:54:33 volumiodell systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 05:54:33 volumiodell systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 05:54:33 volumiodell systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 05:54:33 volumiodell volumio[1140]: error: Cannot start Volumio Streaming Daemon May 26 05:54:33 volumiodell volumio[1140]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 26 05:54:33 volumiodell volumio[1140]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 26 05:54:33 volumiodell systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 05:54:33 volumiodell sudo[2153]: pam_unix(sudo:session): session closed for user root May 26 05:54:33 volumiodell volumio[1140]: info: Remote SSH Stopped May 26 05:54:34 volumiodell volumio[1140]: info: Setting Geolocation for MyVolumio to as1 May 26 05:54:34 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:34 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:34 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:34 volumiodell volumio[1140]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 26 05:54:35 volumiodell volumio[1140]: info: Updating MyVolumio device info May 26 05:54:35 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:35 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:35 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:35 volumiodell volumio[1140]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 26 05:54:36 volumiodell systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 26 05:54:36 volumiodell volumio[1140]: info: Bluetoothremote--- Checking for trusted devices to reconnect... May 26 05:54:36 volumiodell volumio[1140]: info: Bluetoothremote--- Device list cleared and placeholder written. May 26 05:54:36 volumiodell volumio[1140]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 05:54:36 volumiodell volumio[1140]: info: Bluetoothremote--- Device found: Press scan to detect BT device - xx May 26 05:54:37 volumiodell volumio[1140]: info: AutoStart - Check #6/60 - VOLUMIO_SYSTEM_STATUS = starting May 26 05:54:38 volumiodell volumio[1140]: info: MYVOLUMIO: Adding device May 26 05:54:38 volumiodell volumio[1140]: info: MYVOLUMIO: Evaluating Server May 26 05:54:39 volumiodell volumio[1140]: info: Setting Geolocation for MyVolumio to as1 May 26 05:54:39 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:39 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:39 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:39 volumiodell volumio[1140]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 26 05:54:40 volumiodell volumio[1140]: info: Updating MyVolumio device info May 26 05:54:40 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:40 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:40 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:41 volumiodell volumio[1140]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 26 05:54:41 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 05:54:41 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:41 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 26 05:54:41 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 26 05:54:41 volumiodell volumio-remote-updater[792]: Test mode disabled May 26 05:54:41 volumiodell volumio-remote-updater[792]: Alpha mode disabled May 26 05:54:41 volumiodell volumio-remote-updater[792]: Alpha legacy test mode disabled May 26 05:54:41 volumiodell volumio5-onboarding[1805]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded May 26 05:54:41 volumiodell systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE May 26 05:54:41 volumiodell systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. May 26 05:54:41 volumiodell systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. May 26 05:54:41 volumiodell systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. May 26 05:54:41 volumiodell systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 26 05:54:41 volumiodell volumio5-onboarding[2194]: time=2026-05-26T05:54:41.919+09:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z May 26 05:54:41 volumiodell volumio[1140]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 8 May 26 05:54:41 volumiodell volumio[1140]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 8 May 26 05:54:41 volumiodell volumio5-onboarding[2194]: time=2026-05-26T05:54:41.933+09:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist" May 26 05:54:41 volumiodell volumio5-onboarding[2194]: time=2026-05-26T05:54:41.934+09:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist" May 26 05:54:41 volumiodell volumio5-onboarding[2194]: time=2026-05-26T05:54:41.934+09:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist" May 26 05:54:41 volumiodell volumio5-onboarding[2194]: time=2026-05-26T05:54:41.935+09:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist" May 26 05:54:41 volumiodell volumio5-onboarding[2194]: time=2026-05-26T05:54:41.935+09:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist" May 26 05:54:41 volumiodell volumio5-onboarding[2194]: time=2026-05-26T05:54:41.936+09:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" May 26 05:54:41 volumiodell volumio[1140]: info: Received Get System Info May 26 05:54:41 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 05:54:41 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 05:54:41 volumiodell volumio[1140]: info: Discovery: Getting this device information May 26 05:54:41 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetState May 26 05:54:41 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:41 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 05:54:41 volumiodell volumio5-onboarding[2194]: time=2026-05-26T05:54:41.939+09:00 level=INFO msg="system info for db84ffc7279d928bc4b16b6f1ff99003" deviceName=VolumioDell deviceVariant=volumio deviceModel= softwareVersion=4.119 May 26 05:54:41 volumiodell volumio5-onboarding[2194]: time=2026-05-26T05:54:41.970+09:00 level=INFO msg="bootstrapping state" hasInternet=true May 26 05:54:41 volumiodell volumio[1140]: info: Received Get System Info May 26 05:54:41 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 05:54:41 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 05:54:41 volumiodell volumio[1140]: info: Discovery: Getting this device information May 26 05:54:41 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetState May 26 05:54:41 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:41 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 05:54:42 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 26 05:54:42 volumiodell volumio[1140]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 26 05:54:42 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetState May 26 05:54:42 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:42 volumiodell volumio-remote-updater[792]: Test mode disabled May 26 05:54:42 volumiodell volumio-remote-updater[792]: Alpha mode disabled May 26 05:54:42 volumiodell volumio-remote-updater[792]: Alpha legacy test mode disabled May 26 05:54:42 volumiodell volumio[1140]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 26 05:54:42 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 26 05:54:42 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 05:54:42 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 05:54:42 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 26 05:54:42 volumiodell systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. May 26 05:54:42 volumiodell systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 26 05:54:42 volumiodell volumio[1140]: info: AutoStart - Check #7/60 - VOLUMIO_SYSTEM_STATUS = starting May 26 05:54:42 volumiodell systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 26 05:54:42 volumiodell upmpdcli[2205]: Could not open config: /tmp/upmpdcli.conf May 26 05:54:42 volumiodell systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 26 05:54:42 volumiodell systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 26 05:54:42 volumiodell volumio[1140]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 26 05:54:42 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 26 05:54:42 volumiodell go-librespot[1860]: time="2026-05-26T05:54:42+09:00" level=trace msg="sent dealer ping" May 26 05:54:42 volumiodell go-librespot[1860]: time="2026-05-26T05:54:42+09:00" level=trace msg="received dealer pong" May 26 05:54:44 volumiodell volumio5-onboarding[2194]: time=2026-05-26T05:54:44.033+09:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" May 26 05:54:44 volumiodell volumio5-onboarding[2194]: time=2026-05-26T05:54:44.035+09:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" May 26 05:54:44 volumiodell volumio5-onboarding[2194]: time=2026-05-26T05:54:44.036+09:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" May 26 05:54:44 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 05:54:44 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 05:54:44 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 26 05:54:44 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 05:54:44 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 26 05:54:45 volumiodell volumio5-onboarding[2194]: time=2026-05-26T05:54:45.617+09:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=EKwsrcO1SFSPNsA3gW1jdaNeEOm1 tokenExpiry=2026-05-26T06:54:45.617+09:00 May 26 05:54:46 volumiodell volumio[1140]: info: Received Get System Info May 26 05:54:46 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 05:54:46 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 05:54:46 volumiodell volumio[1140]: info: Discovery: Getting this device information May 26 05:54:46 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetState May 26 05:54:46 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:46 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 05:54:46 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 05:54:46 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 05:54:46 volumiodell volumio5-onboarding[2194]: time=2026-05-26T05:54:46.882+09:00 level=INFO msg="enabling local network discovery" May 26 05:54:47 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:47 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:47 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 26 05:54:47 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 26 05:54:47 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 26 05:54:47 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetBrowseSources May 26 05:54:47 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 05:54:47 volumiodell volumio[1140]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 26 05:54:47 volumiodell volumio[1140]: info: Completed starting MyVolumio Plugin May 26 05:54:47 volumiodell volumio[1140]: [Metrics] CommandRouter: 46s 873.35ms May 26 05:54:47 volumiodell volumio[1140]: info: CoreCommandRouter::volumiosetStartupVolume May 26 05:54:47 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 05:54:47 volumiodell volumio[1140]: info: VolumeController:: Setting startup Volume 10 May 26 05:54:47 volumiodell volumio[1140]: info: VolumeController::SetAlsaVolume10 May 26 05:54:47 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 05:54:47 volumiodell volumio[1140]: info: CoreCommandRouter::Close All Modals sent May 26 05:54:47 volumiodell volumio[1140]: info: CoreCommandRouter::Close All Modals sent May 26 05:54:47 volumiodell volumio[1140]: info: CoreStateMachine::pushState May 26 05:54:47 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:54:47 volumiodell volumio[1140]: info: CoreCommandRouter::volumioPushState May 26 05:54:47 volumiodell volumio[1140]: info: FusionDsp - Volumio is not playing May 26 05:54:47 volumiodell volumio[1140]: info: FusionDsp - Clipped samples monitor stopped May 26 05:54:47 volumiodell volumio[1140]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=xscreensaver May 26 05:54:47 volumiodell volumio[1140]: info: Display-configuration --- → Sleep (xscreensaver) triggered May 26 05:54:47 volumiodell volumio[1140]: info: AutoStart - Check #8/60 - VOLUMIO_SYSTEM_STATUS = starting May 26 05:54:47 volumiodell volumio5-onboarding[2194]: time=2026-05-26T05:54:47.732+09:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 05:54:47 volumiodell volumio[1140]: info: Display-configuration --- sleepScreen: xscreensaver activated (screen blanked) May 26 05:54:47 volumiodell volumio[1140]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 26 05:54:47 volumiodell volumio[1140]: info: FusionDsp - ---- read samplerate from file: 44100 May 26 05:54:47 volumiodell volumio[1140]: info: camilladsp stopping service pid 2014... May 26 05:54:48 volumiodell volumio[1140]: grep: /proc/2014/cmdline: binary file matches May 26 05:54:48 volumiodell volumio[1140]: grep: /proc/2014/cmdline: binary file matches May 26 05:54:48 volumiodell volumio[1140]: grep: /proc/2014/cmdline: binary file matches May 26 05:54:48 volumiodell volumio[1140]: grep: /proc/2014/cmdline: binary file matches May 26 05:54:48 volumiodell volumio[1140]: grep: /proc/2014/cmdline: binary file matches May 26 05:54:48 volumiodell volumio[1140]: info: camilladsp service terminated, instance 1 May 26 05:54:48 volumiodell volumio[1140]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 26 05:54:48 volumiodell volumio[1140]: info: camilladsp service started and running in background, instance 1 May 26 05:54:48 volumiodell volumio[1140]: info: camilladsp respawn in 100 ms (attempt 1/10) May 26 05:54:48 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 26 05:54:48 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 05:54:48 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 26 05:54:52 volumiodell volumio[1140]: info: AutoStart - Check #9/60 - VOLUMIO_SYSTEM_STATUS = starting May 26 05:54:52 volumiodell volumio[1140]: info: camilladsp respawn in 200 ms (attempt 2/10) May 26 05:54:54 volumiodell volumio[1140]: info: BOOT COMPLETED May 26 05:54:57 volumiodell volumio[1140]: info: AutoStart - Check #10/60 - VOLUMIO_SYSTEM_STATUS = ready May 26 05:54:57 volumiodell volumio[1140]: info: AutoStart - System ready state CONFIRMED after 10 checks May 26 05:54:57 volumiodell volumio[1140]: info: AutoStart - Applying additional delay of 5000ms before playback May 26 05:54:57 volumiodell systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. May 26 05:54:57 volumiodell systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 26 05:54:57 volumiodell systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 26 05:54:57 volumiodell upmpdcli[2307]: Could not open config: /tmp/upmpdcli.conf May 26 05:54:57 volumiodell systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 26 05:54:57 volumiodell systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 26 05:55:02 volumiodell volumio[1140]: info: AutoStart - startPlayback called May 26 05:55:02 volumiodell volumio[1140]: info: CoreCommandRouter::volumioGetQueue May 26 05:55:02 volumiodell volumio[1140]: info: CoreStateMachine::getQueue May 26 05:55:02 volumiodell volumio[1140]: info: CorePlayQueue::getQueue May 26 05:55:02 volumiodell volumio[1140]: info: AutoStart - Queue has 1 items May 26 05:55:02 volumiodell volumio[1140]: info: AutoStart - Playing from position 0 May 26 05:55:02 volumiodell volumio[1140]: info: CoreCommandRouter::volumioPlay May 26 05:55:02 volumiodell volumio[1140]: info: CoreStateMachine::play index 0 May 26 05:55:02 volumiodell volumio[1140]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 05:55:02 volumiodell volumio[1140]: info: CoreStateMachine::stop May 26 05:55:02 volumiodell volumio[1140]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 05:55:02 volumiodell volumio[1140]: info: CoreStateMachine::play index undefined May 26 05:55:02 volumiodell volumio[1140]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 05:55:02 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:02 volumiodell volumio[1140]: info: CoreStateMachine::startPlaybackTimer May 26 05:55:02 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:02 volumiodell volumio[1140]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth_instrumental/motherearth.instrumental May 26 05:55:02 volumiodell volumio[1140]: info: [MER] 🔌 Starting SSE for Instrumental May 26 05:55:02 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand stop May 26 05:55:02 volumiodell volumio[1140]: info: sendMpdCommand stop took 2 milliseconds May 26 05:55:02 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand clear May 26 05:55:02 volumiodell volumio[1140]: info: sendMpdCommand clear took 0 milliseconds May 26 05:55:02 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth_instrumental/motherearth.instrumental" May 26 05:55:02 volumiodell volumio[1140]: info: May 26 05:55:02 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:02 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:02 volumiodell volumio[1140]: info: May 26 05:55:02 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:02 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:02 volumiodell volumio[1140]: info: sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth_instrumental/motherearth.instrumental" took 2 milliseconds May 26 05:55:02 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand play May 26 05:55:02 volumiodell volumio[1140]: info: May 26 05:55:02 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:02 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:02 volumiodell volumio[1140]: info: May 26 05:55:02 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:02 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:02 volumiodell volumio[1140]: info: May 26 05:55:02 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:02 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:02 volumiodell volumio[1140]: info: ------------------------------ 8ms May 26 05:55:02 volumiodell volumio[1140]: info: ------------------------------ 8ms May 26 05:55:02 volumiodell volumio[1140]: info: sendMpdCommand play took 4 milliseconds May 26 05:55:02 volumiodell volumio[1140]: info: ------------------------------ 4ms May 26 05:55:02 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:02 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:02 volumiodell volumio[1140]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","title":"Connecting...","artist":"Mother Earth Radio","album":"Instrumental · FLAC 192kHz/24bit Stereo","albumart":"/albumart?sourceicon=music_service/motherearthradio/mer-logo-cube-bold-1x 512.png","uri":"motherearthradio/instrumental/flac192","streaming":true,"disableUiControls":true,"samplerate":"192 kHz","bitdepth":"24 bit","duration":0,"seek":0} May 26 05:55:02 volumiodell volumio[1140]: verbose: CURRENT POSITION 0 May 26 05:55:02 volumiodell volumio[1140]: info: CoreStateMachine::syncState stateService play May 26 05:55:02 volumiodell volumio[1140]: info: CoreStateMachine::syncState currentStatus stop May 26 05:55:02 volumiodell volumio[1140]: info: May 26 05:55:02 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:02 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:02 volumiodell volumio[1140]: info: ------------------------------ 11ms May 26 05:55:02 volumiodell volumio[1140]: info: ------------------------------ 10ms May 26 05:55:02 volumiodell volumio[1140]: info: ------------------------------ 4ms May 26 05:55:02 volumiodell volumio[1140]: info: May 26 05:55:02 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:02 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:02 volumiodell volumio[1140]: info: May 26 05:55:02 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:02 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:02 volumiodell volumio[1140]: info: ------------------------------ 3ms May 26 05:55:02 volumiodell volumio[1140]: info: ------------------------------ 2ms May 26 05:55:04 volumiodell volumio[1140]: info: [MER] ✅ SSE connected May 26 05:55:04 volumiodell volumio[1140]: info: May 26 05:55:04 volumiodell volumio[1140]: ---------------------------- MPD announces state update: player May 26 05:55:04 volumiodell volumio[1140]: info: ControllerMpd::getState May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand status May 26 05:55:04 volumiodell volumio[1140]: info: May 26 05:55:04 volumiodell volumio[1140]: ---------------------------- MPD announces state update: player May 26 05:55:04 volumiodell volumio[1140]: info: ControllerMpd::getState May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand status May 26 05:55:04 volumiodell volumio[1140]: info: May 26 05:55:04 volumiodell volumio[1140]: ---------------------------- MPD announces state update: player May 26 05:55:04 volumiodell volumio[1140]: info: ControllerMpd::getState May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand status May 26 05:55:04 volumiodell volumio[1140]: info: May 26 05:55:04 volumiodell volumio[1140]: ---------------------------- MPD announces state update: player May 26 05:55:04 volumiodell volumio[1140]: info: ControllerMpd::getState May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand status May 26 05:55:04 volumiodell volumio[1140]: info: sendMpdCommand status took 24 milliseconds May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::parseState May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 05:55:04 volumiodell volumio[1140]: info: May 26 05:55:04 volumiodell volumio[1140]: ---------------------------- MPD announces state update: player May 26 05:55:04 volumiodell volumio[1140]: info: ControllerMpd::getState May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand status May 26 05:55:04 volumiodell volumio[1140]: info: May 26 05:55:04 volumiodell volumio[1140]: ---------------------------- MPD announces state update: player May 26 05:55:04 volumiodell volumio[1140]: info: ControllerMpd::getState May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand status May 26 05:55:04 volumiodell volumio[1140]: info: sendMpdCommand status took 27 milliseconds May 26 05:55:04 volumiodell volumio[1140]: info: sendMpdCommand status took 25 milliseconds May 26 05:55:04 volumiodell volumio[1140]: info: sendMpdCommand status took 25 milliseconds May 26 05:55:04 volumiodell volumio[1140]: info: sendMpdCommand playlistinfo took 3 milliseconds May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::parseState May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::parseState May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::parseState May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::parseTrackInfo May 26 05:55:04 volumiodell volumio[1140]: info: ControllerMpd::pushState May 26 05:55:04 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:04 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:04 volumiodell volumio[1140]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 26 05:55:04 volumiodell volumio[1140]: info: ------------------------------ 33ms May 26 05:55:04 volumiodell volumio[1140]: info: May 26 05:55:04 volumiodell volumio[1140]: ---------------------------- MPD announces state update: player May 26 05:55:04 volumiodell volumio[1140]: info: ControllerMpd::getState May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand status May 26 05:55:04 volumiodell volumio[1140]: info: May 26 05:55:04 volumiodell volumio[1140]: ---------------------------- MPD announces state update: player May 26 05:55:04 volumiodell volumio[1140]: info: ControllerMpd::getState May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand status May 26 05:55:04 volumiodell volumio[1140]: info: sendMpdCommand status took 7 milliseconds May 26 05:55:04 volumiodell volumio[1140]: info: sendMpdCommand status took 6 milliseconds May 26 05:55:04 volumiodell volumio[1140]: info: sendMpdCommand playlistinfo took 5 milliseconds May 26 05:55:04 volumiodell volumio[1140]: info: sendMpdCommand playlistinfo took 5 milliseconds May 26 05:55:04 volumiodell volumio[1140]: info: sendMpdCommand playlistinfo took 6 milliseconds May 26 05:55:04 volumiodell volumio[1140]: info: sendMpdCommand status took 2 milliseconds May 26 05:55:04 volumiodell volumio[1140]: info: sendMpdCommand status took 2 milliseconds May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::parseState May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::parseState May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::parseTrackInfo May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::parseTrackInfo May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::parseTrackInfo May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::parseState May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::parseState May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 05:55:04 volumiodell volumio[1140]: info: ControllerMpd::pushState May 26 05:55:04 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:04 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:04 volumiodell volumio[1140]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 26 05:55:04 volumiodell volumio[1140]: info: ControllerMpd::pushState May 26 05:55:04 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:04 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:04 volumiodell volumio[1140]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 26 05:55:04 volumiodell volumio[1140]: info: ControllerMpd::pushState May 26 05:55:04 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:04 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:04 volumiodell volumio[1140]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 26 05:55:04 volumiodell volumio[1140]: info: ------------------------------ 38ms May 26 05:55:04 volumiodell volumio[1140]: info: ------------------------------ 36ms May 26 05:55:04 volumiodell volumio[1140]: info: ------------------------------ 35ms May 26 05:55:04 volumiodell volumio[1140]: info: sendMpdCommand playlistinfo took 4 milliseconds May 26 05:55:04 volumiodell volumio[1140]: info: sendMpdCommand playlistinfo took 5 milliseconds May 26 05:55:04 volumiodell volumio[1140]: info: sendMpdCommand playlistinfo took 4 milliseconds May 26 05:55:04 volumiodell volumio[1140]: info: sendMpdCommand playlistinfo took 3 milliseconds May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::parseTrackInfo May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::parseTrackInfo May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::parseTrackInfo May 26 05:55:04 volumiodell volumio[1140]: verbose: ControllerMpd::parseTrackInfo May 26 05:55:04 volumiodell volumio[1140]: info: ControllerMpd::pushState May 26 05:55:04 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:04 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:04 volumiodell volumio[1140]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 26 05:55:04 volumiodell volumio[1140]: info: ControllerMpd::pushState May 26 05:55:04 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:04 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:04 volumiodell volumio[1140]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 26 05:55:04 volumiodell volumio[1140]: info: ControllerMpd::pushState May 26 05:55:04 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:04 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:04 volumiodell volumio[1140]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 26 05:55:04 volumiodell volumio[1140]: info: ControllerMpd::pushState May 26 05:55:04 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:04 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:04 volumiodell volumio[1140]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 26 05:55:04 volumiodell volumio[1140]: info: ------------------------------ 16ms May 26 05:55:04 volumiodell volumio[1140]: info: ------------------------------ 15ms May 26 05:55:04 volumiodell volumio[1140]: info: ------------------------------ 10ms May 26 05:55:04 volumiodell volumio[1140]: info: ------------------------------ 9ms May 26 05:55:04 volumiodell volumio[1140]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 May 26 05:55:04 volumiodell volumio[1140]: info: FusionDsp - ---- read samplerate from file: 192000 May 26 05:55:04 volumiodell volumio[1140]: info: camilladsp stopping service pid 2299... May 26 05:55:05 volumiodell volumio[1140]: grep: /proc/2299/cmdline: binary file matches May 26 05:55:05 volumiodell volumio[1140]: info: camilladsp service terminated, instance 1 May 26 05:55:05 volumiodell volumio[1140]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 26 05:55:05 volumiodell volumio[1140]: info: camilladsp service started and running in background, instance 1 May 26 05:55:05 volumiodell volumio[1140]: info: camilladsp respawn in 100 ms (attempt 1/10) May 26 05:55:05 volumiodell volumio[1140]: info: May 26 05:55:05 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:05 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:05 volumiodell volumio[1140]: info: May 26 05:55:05 volumiodell volumio[1140]: ---------------------------- MPD announces state update: player May 26 05:55:05 volumiodell volumio[1140]: info: ControllerMpd::getState May 26 05:55:05 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand status May 26 05:55:05 volumiodell volumio[1140]: info: May 26 05:55:05 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:05 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:05 volumiodell volumio[1140]: info: May 26 05:55:05 volumiodell volumio[1140]: ---------------------------- MPD announces state update: player May 26 05:55:05 volumiodell volumio[1140]: info: ControllerMpd::getState May 26 05:55:05 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand status May 26 05:55:05 volumiodell volumio[1140]: info: May 26 05:55:05 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:05 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:05 volumiodell volumio[1140]: info: May 26 05:55:05 volumiodell volumio[1140]: ---------------------------- MPD announces state update: player May 26 05:55:05 volumiodell volumio[1140]: info: ControllerMpd::getState May 26 05:55:05 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand status May 26 05:55:05 volumiodell volumio[1140]: info: May 26 05:55:05 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:05 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:05 volumiodell volumio[1140]: info: May 26 05:55:05 volumiodell volumio[1140]: ---------------------------- MPD announces state update: player May 26 05:55:05 volumiodell volumio[1140]: info: ControllerMpd::getState May 26 05:55:05 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand status May 26 05:55:05 volumiodell volumio[1140]: info: ------------------------------ 12ms May 26 05:55:05 volumiodell volumio[1140]: info: sendMpdCommand status took 10 milliseconds May 26 05:55:05 volumiodell volumio[1140]: info: ------------------------------ 9ms May 26 05:55:05 volumiodell volumio[1140]: info: sendMpdCommand status took 10 milliseconds May 26 05:55:05 volumiodell volumio[1140]: info: ------------------------------ 7ms May 26 05:55:05 volumiodell volumio[1140]: info: sendMpdCommand status took 5 milliseconds May 26 05:55:05 volumiodell volumio[1140]: verbose: ControllerMpd::parseState May 26 05:55:05 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 05:55:05 volumiodell volumio[1140]: verbose: ControllerMpd::parseState May 26 05:55:05 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 05:55:05 volumiodell volumio[1140]: verbose: ControllerMpd::parseState May 26 05:55:05 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 05:55:05 volumiodell volumio[1140]: info: ------------------------------ 7ms May 26 05:55:05 volumiodell volumio[1140]: info: sendMpdCommand status took 5 milliseconds May 26 05:55:05 volumiodell volumio[1140]: info: sendMpdCommand playlistinfo took 3 milliseconds May 26 05:55:05 volumiodell volumio[1140]: info: sendMpdCommand playlistinfo took 3 milliseconds May 26 05:55:05 volumiodell volumio[1140]: info: sendMpdCommand playlistinfo took 3 milliseconds May 26 05:55:05 volumiodell volumio[1140]: verbose: ControllerMpd::parseState May 26 05:55:05 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 05:55:05 volumiodell volumio[1140]: verbose: ControllerMpd::parseTrackInfo May 26 05:55:05 volumiodell volumio[1140]: verbose: ControllerMpd::parseTrackInfo May 26 05:55:05 volumiodell volumio[1140]: verbose: ControllerMpd::parseTrackInfo May 26 05:55:05 volumiodell volumio[1140]: info: ControllerMpd::pushState May 26 05:55:05 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:05 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:05 volumiodell volumio[1140]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 26 05:55:05 volumiodell volumio[1140]: info: ControllerMpd::pushState May 26 05:55:05 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:05 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:05 volumiodell volumio[1140]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 26 05:55:05 volumiodell volumio[1140]: info: ControllerMpd::pushState May 26 05:55:05 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:05 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:05 volumiodell volumio[1140]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 26 05:55:05 volumiodell volumio[1140]: info: ------------------------------ 20ms May 26 05:55:05 volumiodell volumio[1140]: info: ------------------------------ 18ms May 26 05:55:05 volumiodell volumio[1140]: info: ------------------------------ 14ms May 26 05:55:05 volumiodell volumio[1140]: info: sendMpdCommand playlistinfo took 2 milliseconds May 26 05:55:05 volumiodell volumio[1140]: verbose: ControllerMpd::parseTrackInfo May 26 05:55:05 volumiodell volumio[1140]: info: ControllerMpd::pushState May 26 05:55:05 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:05 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:05 volumiodell volumio[1140]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 26 05:55:05 volumiodell volumio[1140]: info: ------------------------------ 11ms May 26 05:55:06 volumiodell volumio[1140]: info: [MER] 🎵 muralim - acho May 26 05:55:06 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:06 volumiodell volumio[1140]: info: [MER] 📤 muralim - acho May 26 05:55:06 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:06 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:06 volumiodell volumio[1140]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://stream.motherearthradio.de/api/station/motherearth_instrumental/art/6790ce93273d1207b6bbfa51-1772207928.jpg","uri":"motherearthradio/instrumental/flac192","name":"acho","title":"acho","artist":"muralim","album":"acho","streaming":true,"disableUiControls":true,"duration":350,"seek":0,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2} May 26 05:55:06 volumiodell volumio[1140]: verbose: CURRENT POSITION 0 May 26 05:55:06 volumiodell volumio[1140]: info: CoreStateMachine::syncState stateService play May 26 05:55:06 volumiodell volumio[1140]: info: CoreStateMachine::syncState currentStatus play May 26 05:55:06 volumiodell volumio[1140]: info: Received an update from plugin. extracting info from payload May 26 05:55:06 volumiodell volumio[1140]: info: CoreStateMachine::pushState May 26 05:55:06 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:06 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 05:55:06 volumiodell volumio[1140]: info: CoreCommandRouter::volumioPushState May 26 05:55:06 volumiodell volumio[1140]: info: CoreStateMachine::pushState May 26 05:55:06 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:06 volumiodell volumio[1140]: info: CoreCommandRouter::volumioPushState May 26 05:55:06 volumiodell volumio[1140]: info: FusionDsp - Volumio is playing May 26 05:55:06 volumiodell volumio[1140]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 26 05:55:06 volumiodell volumio[1140]: info: FusionDsp - Volumio is playing May 26 05:55:06 volumiodell volumio[1140]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=xscreensaver May 26 05:55:06 volumiodell volumio[1140]: info: Display-configuration --- → Wakeup triggered May 26 05:55:06 volumiodell volumio[1140]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=xscreensaver May 26 05:55:06 volumiodell volumio[1140]: info: Display-configuration --- → Wakeup triggered May 26 05:55:06 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:06 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 1 May 26 05:55:06 volumiodell volumio[1140]: info: Display-configuration --- wakeupScreen: xscreensaver deactivated (screen on) May 26 05:55:06 volumiodell volumio[1140]: info: Display-configuration --- wakeupScreen: xscreensaver deactivated (screen on) May 26 05:55:06 volumiodell volumio[1140]: info: FusionDsp - Clipping Monitor started May 26 05:55:06 volumiodell volumio[1140]: info: FusionDsp - Clipping Monitor started May 26 05:55:12 volumiodell go-librespot[1860]: time="2026-05-26T05:55:12+09:00" level=trace msg="sent dealer ping" May 26 05:55:12 volumiodell go-librespot[1860]: time="2026-05-26T05:55:12+09:00" level=trace msg="received dealer pong" May 26 05:55:13 volumiodell systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. May 26 05:55:13 volumiodell systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 26 05:55:13 volumiodell systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 26 05:55:13 volumiodell upmpdcli[2417]: Could not open config: /tmp/upmpdcli.conf May 26 05:55:13 volumiodell systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 26 05:55:13 volumiodell systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 26 05:55:17 volumiodell thd[789]: Executing trigger action: volumio toggle May 26 05:55:17 volumiodell thd[789]: Executing trigger action: volumio toggle May 26 05:55:17 volumiodell thd[2438]: % Total % Received % Xferd Average Speed Time Time Time Current May 26 05:55:17 volumiodell thd[2438]: Dload Upload Total Spent Left Speed May 26 05:55:17 volumiodell thd[2438]: [79B blob data] May 26 05:55:17 volumiodell thd[2437]: % Total % Received % Xferd Average Speed Time Time Time Current May 26 05:55:17 volumiodell thd[2437]: Dload Upload Total Spent Left Speed May 26 05:55:17 volumiodell volumio[1140]: info: May 26 05:55:17 volumiodell volumio[1140]: ---------------------------- Client requests Volumio toggle May 26 05:55:17 volumiodell volumio[1140]: info: CoreCommandRouter::volumioToggle May 26 05:55:17 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:17 volumiodell volumio[1140]: info: CoreStateMachine::pause May 26 05:55:17 volumiodell volumio[1140]: info: CoreStateMachine::stPlaybackTimer May 26 05:55:17 volumiodell volumio[1140]: info: CoreStateMachine::servicePause May 26 05:55:17 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:17 volumiodell volumio[1140]: info: CoreCommandRouter::servicePause May 26 05:55:17 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:17 volumiodell volumio[1140]: info: CoreStateMachine::pushState May 26 05:55:17 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:17 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 05:55:17 volumiodell volumio[1140]: info: CoreCommandRouter::volumioPushState May 26 05:55:17 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:17 volumiodell volumio[1140]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} May 26 05:55:17 volumiodell volumio[1140]: verbose: CURRENT POSITION 0 May 26 05:55:17 volumiodell volumio[1140]: info: CoreStateMachine::syncState stateService stop May 26 05:55:17 volumiodell volumio[1140]: info: CoreStateMachine::syncState currentStatus pause May 26 05:55:17 volumiodell volumio[1140]: info: CoreStateMachine::pushState May 26 05:55:17 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:17 volumiodell volumio[1140]: info: CoreCommandRouter::volumioPushState May 26 05:55:17 volumiodell volumio[1140]: info: ControllerMpd::stop May 26 05:55:17 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand stop May 26 05:55:17 volumiodell volumio[1140]: info: May 26 05:55:17 volumiodell volumio[1140]: ---------------------------- Client requests Volumio toggle May 26 05:55:17 volumiodell volumio[1140]: info: CoreCommandRouter::volumioToggle May 26 05:55:17 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:17 volumiodell volumio[1140]: info: CoreStateMachine::play index undefined May 26 05:55:17 volumiodell volumio[1140]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 05:55:17 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:17 volumiodell volumio[1140]: info: CoreStateMachine::startPlaybackTimer May 26 05:55:17 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:17 volumiodell volumio[1140]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth_instrumental/motherearth.instrumental May 26 05:55:17 volumiodell volumio[1140]: info: [MER] 🔌 Starting SSE for Instrumental May 26 05:55:17 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand stop May 26 05:55:17 volumiodell thd[2437]: [158B blob data] May 26 05:55:17 volumiodell volumio[1140]: info: FusionDsp - Volumio is not playing May 26 05:55:17 volumiodell volumio[1140]: info: FusionDsp - Clipped samples monitor stopped May 26 05:55:17 volumiodell volumio[1140]: info: FusionDsp - Volumio is not playing May 26 05:55:17 volumiodell volumio[1140]: info: FusionDsp - Clipped samples monitor stopped May 26 05:55:17 volumiodell volumio[1140]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=xscreensaver May 26 05:55:17 volumiodell volumio[1140]: info: Display-configuration --- → Sleep (xscreensaver) triggered May 26 05:55:17 volumiodell volumio[1140]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=xscreensaver May 26 05:55:17 volumiodell volumio[1140]: info: Display-configuration --- → Sleep (xscreensaver) triggered May 26 05:55:17 volumiodell volumio[1140]: info: May 26 05:55:17 volumiodell volumio[1140]: ---------------------------- MPD announces state update: player May 26 05:55:17 volumiodell volumio[1140]: info: sendMpdCommand stop took 91 milliseconds May 26 05:55:17 volumiodell volumio[1140]: info: ControllerMpd::getState May 26 05:55:17 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand status May 26 05:55:17 volumiodell volumio[1140]: info: sendMpdCommand stop took 74 milliseconds May 26 05:55:17 volumiodell thd[2437]: {"time":1779742517856,"response":"toggle Success"} May 26 05:55:17 volumiodell thd[2438]: [79B blob data] May 26 05:55:17 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand clear May 26 05:55:17 volumiodell volumio[1140]: info: May 26 05:55:17 volumiodell volumio[1140]: ---------------------------- MPD announces state update: player May 26 05:55:17 volumiodell volumio[1140]: info: ControllerMpd::getState May 26 05:55:17 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand status May 26 05:55:17 volumiodell volumio[1140]: info: May 26 05:55:17 volumiodell volumio[1140]: ---------------------------- MPD announces state update: player May 26 05:55:17 volumiodell volumio[1140]: info: ControllerMpd::getState May 26 05:55:17 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand status May 26 05:55:17 volumiodell volumio[1140]: info: May 26 05:55:17 volumiodell volumio[1140]: ---------------------------- MPD announces state update: player May 26 05:55:17 volumiodell volumio[1140]: info: ControllerMpd::getState May 26 05:55:17 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand status May 26 05:55:17 volumiodell volumio[1140]: info: May 26 05:55:17 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:17 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:17 volumiodell volumio[1140]: info: sendMpdCommand status took 21 milliseconds May 26 05:55:17 volumiodell volumio[1140]: info: sendMpdCommand clear took 10 milliseconds May 26 05:55:17 volumiodell volumio[1140]: info: sendMpdCommand status took 9 milliseconds May 26 05:55:17 volumiodell volumio[1140]: info: sendMpdCommand status took 9 milliseconds May 26 05:55:17 volumiodell volumio[1140]: info: sendMpdCommand status took 10 milliseconds May 26 05:55:17 volumiodell volumio[1140]: verbose: ControllerMpd::parseState May 26 05:55:17 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 05:55:17 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth_instrumental/motherearth.instrumental" May 26 05:55:17 volumiodell volumio[1140]: verbose: ControllerMpd::parseState May 26 05:55:17 volumiodell volumio[1140]: verbose: ControllerMpd::parseState May 26 05:55:17 volumiodell volumio[1140]: verbose: ControllerMpd::parseState May 26 05:55:17 volumiodell volumio[1140]: info: ControllerMpd::pushState May 26 05:55:17 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:17 volumiodell volumio[1140]: info: CoreStateMachine::pushState May 26 05:55:17 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:17 volumiodell volumio[1140]: info: CoreCommandRouter::volumioPushState May 26 05:55:17 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:17 volumiodell volumio[1140]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 26 05:55:17 volumiodell volumio[1140]: info: ControllerMpd::pushState May 26 05:55:17 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:17 volumiodell volumio[1140]: info: CoreStateMachine::pushState May 26 05:55:17 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:17 volumiodell volumio[1140]: info: CoreCommandRouter::volumioPushState May 26 05:55:17 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:17 volumiodell volumio[1140]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 26 05:55:17 volumiodell volumio[1140]: info: ControllerMpd::pushState May 26 05:55:17 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:17 volumiodell volumio[1140]: info: CoreStateMachine::pushState May 26 05:55:17 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:17 volumiodell volumio[1140]: info: CoreCommandRouter::volumioPushState May 26 05:55:17 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:17 volumiodell volumio[1140]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 26 05:55:17 volumiodell volumio[1140]: info: ------------------------------ 35ms May 26 05:55:17 volumiodell volumio[1140]: info: ------------------------------ 35ms May 26 05:55:17 volumiodell volumio[1140]: info: ------------------------------ 35ms May 26 05:55:17 volumiodell volumio[1140]: info: May 26 05:55:17 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:17 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:17 volumiodell volumio[1140]: info: May 26 05:55:17 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:17 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:17 volumiodell volumio[1140]: info: May 26 05:55:17 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:17 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:17 volumiodell volumio[1140]: error: updateQueue error: null May 26 05:55:17 volumiodell volumio[1140]: info: May 26 05:55:17 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:17 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:17 volumiodell volumio[1140]: info: ------------------------------ 39ms May 26 05:55:17 volumiodell volumio[1140]: info: sendMpdCommand playlistinfo took 35 milliseconds May 26 05:55:17 volumiodell volumio[1140]: info: sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth_instrumental/motherearth.instrumental" took 36 milliseconds May 26 05:55:18 volumiodell volumio[1140]: info: ------------------------------ 13ms May 26 05:55:18 volumiodell volumio[1140]: info: ------------------------------ 11ms May 26 05:55:18 volumiodell volumio[1140]: info: ------------------------------ 11ms May 26 05:55:18 volumiodell volumio[1140]: verbose: ControllerMpd::parseTrackInfo May 26 05:55:18 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand play May 26 05:55:18 volumiodell volumio[1140]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 26 05:55:18 volumiodell volumio[1140]: info: ------------------------------ 73ms May 26 05:55:18 volumiodell volumio[1140]: info: May 26 05:55:18 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:18 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:18 volumiodell volumio[1140]: info: May 26 05:55:18 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:18 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:18 volumiodell volumio[1140]: info: May 26 05:55:18 volumiodell volumio[1140]: ---------------------------- MPD announces system playlist update May 26 05:55:18 volumiodell volumio[1140]: info: Ignoring MPD Status Update May 26 05:55:18 volumiodell volumio[1140]: info: FusionDsp - Volumio is not playing May 26 05:55:18 volumiodell volumio[1140]: info: FusionDsp - Clipped samples monitor stopped May 26 05:55:18 volumiodell volumio[1140]: info: FusionDsp - Volumio is not playing May 26 05:55:18 volumiodell volumio[1140]: info: FusionDsp - Clipped samples monitor stopped May 26 05:55:18 volumiodell volumio[1140]: info: FusionDsp - Volumio is not playing May 26 05:55:18 volumiodell volumio[1140]: info: FusionDsp - Clipped samples monitor stopped May 26 05:55:18 volumiodell volumio[1140]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=xscreensaver May 26 05:55:18 volumiodell volumio[1140]: info: Display-configuration --- → Sleep (xscreensaver) triggered May 26 05:55:18 volumiodell volumio[1140]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=xscreensaver May 26 05:55:18 volumiodell volumio[1140]: info: Display-configuration --- → Sleep (xscreensaver) triggered May 26 05:55:18 volumiodell volumio[1140]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=xscreensaver May 26 05:55:18 volumiodell volumio[1140]: info: Display-configuration --- → Sleep (xscreensaver) triggered May 26 05:55:18 volumiodell volumio[1140]: warn: Display-configuration --- sleepScreen: xscreensaver not running or failed → Command failed: DISPLAY=:0 xscreensaver-command -activate May 26 05:55:18 volumiodell volumio[1140]: xscreensaver-command: already active May 26 05:55:18 volumiodell volumio[1140]: info: ------------------------------ 96ms May 26 05:55:18 volumiodell volumio[1140]: info: sendMpdCommand play took 88 milliseconds May 26 05:55:18 volumiodell volumio[1140]: info: ------------------------------ 88ms May 26 05:55:18 volumiodell volumio[1140]: info: ------------------------------ 86ms May 26 05:55:18 volumiodell volumio[1140]: info: ------------------------------ 86ms May 26 05:55:18 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:18 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:18 volumiodell volumio[1140]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","title":"Connecting...","artist":"Mother Earth Radio","album":"Instrumental · FLAC 192kHz/24bit Stereo","albumart":"/albumart?sourceicon=music_service/motherearthradio/mer-logo-cube-bold-1x 512.png","uri":"motherearthradio/instrumental/flac192","streaming":true,"disableUiControls":true,"samplerate":"192 kHz","bitdepth":"24 bit","duration":0,"seek":0} May 26 05:55:18 volumiodell volumio[1140]: verbose: CURRENT POSITION 0 May 26 05:55:18 volumiodell volumio[1140]: info: CoreStateMachine::syncState stateService play May 26 05:55:18 volumiodell volumio[1140]: info: CoreStateMachine::syncState currentStatus pause May 26 05:55:18 volumiodell volumio[1140]: info: CoreStateMachine::pushState May 26 05:55:18 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:18 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 05:55:18 volumiodell volumio[1140]: info: CoreCommandRouter::volumioPushState May 26 05:55:18 volumiodell volumio[1140]: warn: Display-configuration --- sleepScreen: xscreensaver not running or failed → Command failed: DISPLAY=:0 xscreensaver-command -activate May 26 05:55:18 volumiodell volumio[1140]: xscreensaver-command: already active May 26 05:55:18 volumiodell volumio[1140]: info: camilladsp respawn in 200 ms (attempt 2/10) May 26 05:55:18 volumiodell volumio[1140]: info: Display-configuration --- sleepScreen: xscreensaver activated (screen blanked) May 26 05:55:18 volumiodell volumio[1140]: info: FusionDsp - Volumio is playing May 26 05:55:18 volumiodell volumio[1140]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 26 05:55:18 volumiodell volumio[1140]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=xscreensaver May 26 05:55:18 volumiodell volumio[1140]: info: Display-configuration --- → Wakeup triggered May 26 05:55:18 volumiodell volumio[1140]: error: FusionDsp - Monitor WebSocket error: [object Object] May 26 05:55:18 volumiodell volumio[1140]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms May 26 05:55:18 volumiodell thd[2438]: {"time":1779742517832,"response":"toggle Success"} May 26 05:55:18 volumiodell thd[789]: Executing trigger action: volumio toggle May 26 05:55:18 volumiodell thd[789]: Executing trigger action: volumio toggle May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: % May 26 05:55:18 volumiodell thd[2472]: % May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: T May 26 05:55:18 volumiodell thd[2472]: T May 26 05:55:18 volumiodell thd[2471]: o May 26 05:55:18 volumiodell thd[2472]: o May 26 05:55:18 volumiodell thd[2471]: ta May 26 05:55:18 volumiodell thd[2472]: ta May 26 05:55:18 volumiodell thd[2471]: l May 26 05:55:18 volumiodell thd[2472]: l May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell volumio[1140]: info: May 26 05:55:18 volumiodell volumio[1140]: ---------------------------- Client requests Volumio toggle May 26 05:55:18 volumiodell volumio[1140]: info: CoreCommandRouter::volumioToggle May 26 05:55:18 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:18 volumiodell volumio[1140]: info: CoreStateMachine::pause May 26 05:55:18 volumiodell volumio[1140]: info: CoreStateMachine::stPlaybackTimer May 26 05:55:18 volumiodell volumio[1140]: info: CoreStateMachine::servicePause May 26 05:55:18 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:18 volumiodell volumio[1140]: info: CoreCommandRouter::servicePause May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: % May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell volumio[1140]: info: CoreCommandRouter::servicePushState May 26 05:55:18 volumiodell thd[2472]: % May 26 05:55:18 volumiodell volumio[1140]: info: CoreStateMachine::pushState May 26 05:55:18 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:18 volumiodell thd[2471]: R May 26 05:55:18 volumiodell volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: ec May 26 05:55:18 volumiodell thd[2472]: R May 26 05:55:18 volumiodell volumio[1140]: info: CoreCommandRouter::volumioPushState May 26 05:55:18 volumiodell thd[2471]: e May 26 05:55:18 volumiodell thd[2472]: ec May 26 05:55:18 volumiodell thd[2471]: i May 26 05:55:18 volumiodell thd[2472]: ei May 26 05:55:18 volumiodell thd[2471]: v May 26 05:55:18 volumiodell thd[2472]: v May 26 05:55:18 volumiodell thd[2471]: e May 26 05:55:18 volumiodell thd[2472]: e May 26 05:55:18 volumiodell thd[2471]: d May 26 05:55:18 volumiodell thd[2472]: d May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: % May 26 05:55:18 volumiodell thd[2472]: % May 26 05:55:18 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:18 volumiodell volumio[1140]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} May 26 05:55:18 volumiodell volumio[1140]: verbose: CURRENT POSITION 0 May 26 05:55:18 volumiodell volumio[1140]: info: CoreStateMachine::syncState stateService stop May 26 05:55:18 volumiodell volumio[1140]: info: CoreStateMachine::syncState currentStatus pause May 26 05:55:18 volumiodell volumio[1140]: info: CoreStateMachine::pushState May 26 05:55:18 volumiodell volumio[1140]: info: CorePlayQueue::getTrack 0 May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell volumio[1140]: info: CoreCommandRouter::volumioPushState May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: Xf May 26 05:55:18 volumiodell thd[2472]: X May 26 05:55:18 volumiodell thd[2471]: e May 26 05:55:18 volumiodell thd[2472]: f May 26 05:55:18 volumiodell thd[2471]: r May 26 05:55:18 volumiodell thd[2472]: e May 26 05:55:18 volumiodell thd[2471]: d May 26 05:55:18 volumiodell thd[2472]: r May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: d May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: A May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell volumio[1140]: info: ControllerMpd::stop May 26 05:55:18 volumiodell thd[2471]: v May 26 05:55:18 volumiodell volumio[1140]: verbose: ControllerMpd::sendMpdCommand stop May 26 05:55:18 volumiodell thd[2472]: A May 26 05:55:18 volumiodell thd[2471]: e May 26 05:55:18 volumiodell thd[2472]: ve May 26 05:55:18 volumiodell thd[2471]: r May 26 05:55:18 volumiodell thd[2472]: r May 26 05:55:18 volumiodell thd[2471]: a May 26 05:55:18 volumiodell thd[2472]: a May 26 05:55:18 volumiodell thd[2471]: g May 26 05:55:18 volumiodell thd[2472]: g May 26 05:55:18 volumiodell thd[2471]: e May 26 05:55:18 volumiodell thd[2472]: e May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: S May 26 05:55:18 volumiodell thd[2471]: Sp May 26 05:55:18 volumiodell thd[2472]: p May 26 05:55:18 volumiodell thd[2471]: e May 26 05:55:18 volumiodell thd[2472]: e May 26 05:55:18 volumiodell thd[2471]: e May 26 05:55:18 volumiodell thd[2472]: ed May 26 05:55:18 volumiodell thd[2471]: d May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: T May 26 05:55:18 volumiodell thd[2471]: T May 26 05:55:18 volumiodell thd[2472]: i May 26 05:55:18 volumiodell thd[2471]: i May 26 05:55:18 volumiodell thd[2472]: m May 26 05:55:18 volumiodell thd[2471]: m May 26 05:55:18 volumiodell thd[2472]: e May 26 05:55:18 volumiodell thd[2471]: e May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: Time Time May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: C May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: u May 26 05:55:18 volumiodell thd[2471]: T May 26 05:55:18 volumiodell thd[2472]: r May 26 05:55:18 volumiodell thd[2471]: i May 26 05:55:18 volumiodell thd[2472]: r May 26 05:55:18 volumiodell thd[2471]: m May 26 05:55:18 volumiodell thd[2472]: e May 26 05:55:18 volumiodell thd[2471]: e May 26 05:55:18 volumiodell thd[2472]: n May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: t May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: T May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: i May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: m May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: e May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: C May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: u May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: r May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: r May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: e May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: n May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: t May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell volumio[1140]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: D May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: l May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: o May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: a May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: d May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: U May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: p May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: lo May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: a May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: d May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: T May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: o May 26 05:55:18 volumiodell thd[2471]: D May 26 05:55:18 volumiodell thd[2472]: t May 26 05:55:18 volumiodell thd[2471]: l May 26 05:55:18 volumiodell thd[2472]: al May 26 05:55:18 volumiodell thd[2471]: oad May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: U May 26 05:55:18 volumiodell thd[2472]: S May 26 05:55:18 volumiodell thd[2471]: p May 26 05:55:18 volumiodell thd[2472]: p May 26 05:55:18 volumiodell thd[2471]: l May 26 05:55:18 volumiodell thd[2472]: e May 26 05:55:18 volumiodell thd[2471]: o May 26 05:55:18 volumiodell thd[2472]: n May 26 05:55:18 volumiodell thd[2471]: a May 26 05:55:18 volumiodell thd[2472]: t May 26 05:55:18 volumiodell thd[2471]: d May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: Left Sp May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: e May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: e May 26 05:55:18 volumiodell thd[2471]: T May 26 05:55:18 volumiodell thd[2472]: d May 26 05:55:18 volumiodell thd[2471]: o May 26 05:55:18 volumiodell thd[2471]: tal May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: 0 May 26 05:55:18 volumiodell thd[2471]: S May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: p May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: e May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: nt May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: 0 May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: L May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: e May 26 05:55:18 volumiodell thd[2472]: 0 May 26 05:55:18 volumiodell thd[2471]: f May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: t May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: S May 26 05:55:18 volumiodell thd[2472]: 0 May 26 05:55:18 volumiodell thd[2471]: p May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: e May 26 05:55:18 volumiodell volumio[1140]: Error: socket hang up May 26 05:55:18 volumiodell volumio[1140]: at connResetException (node:internal/errors:720:14) May 26 05:55:18 volumiodell volumio[1140]: at TLSSocket.socketCloseListener (node:_http_client:468:25) May 26 05:55:18 volumiodell volumio[1140]: at TLSSocket.emit (node:events:526:35) May 26 05:55:18 volumiodell volumio[1140]: at node:net:337:12 May 26 05:55:18 volumiodell volumio[1140]: at TCP.done (node:_tls_wrap:631:7) { May 26 05:55:18 volumiodell volumio[1140]: code: 'ECONNRESET' May 26 05:55:18 volumiodell volumio[1140]: } May 26 05:55:18 volumiodell volumio[1140]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: e May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: d May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2472]: 0 May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: 0 May 26 05:55:18 volumiodell thd[2472]: 0 May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: 0 May 26 05:55:18 volumiodell thd[2472]: 0 May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: 0 May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: 0 May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: - May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: -: May 26 05:55:18 volumiodell thd[2471]: 0 May 26 05:55:18 volumiodell thd[2472]: - May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: - May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: : May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: - May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: - May 26 05:55:18 volumiodell thd[2471]: 0 May 26 05:55:18 volumiodell thd[2472]: - May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: - May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: : May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: - May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: - May 26 05:55:18 volumiodell thd[2471]: 0 May 26 05:55:18 volumiodell thd[2472]: : May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: - May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: - May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: - May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: -: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: - May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: - May 26 05:55:18 volumiodell thd[2471]: 0 May 26 05:55:18 volumiodell thd[2472]: : May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: - May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: - May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: 0 May 26 05:55:18 volumiodell thd[2472]: May 26 05:55:18 volumiodell thd[2471]: May 26 05:55:18 volumiodell thd[2472]: 0 May 26 05:55:18 volumiodell sudo[2487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-26 05:54' May 26 05:55:18 volumiodell sudo[2487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"