-- Logs begin at Sun 2025-10-26 21:40:44 CET, end at Sun 2025-10-26 21:42:58 CET. -- Oct 26 21:41:00 volumio wireless.js[674]: trying... Oct 26 21:41:00 volumio sudo[989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 26 21:41:00 volumio sudo[989]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:01 volumio sudo[989]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:01 volumio volumio-remote-updater[673]: [2025-10-26 21:41:01] [info] asio async_connect error: system:111 (Connection refused) Oct 26 21:41:01 volumio volumio-remote-updater[673]: [2025-10-26 21:41:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 26 21:41:01 volumio volumio-remote-updater[673]: [2025-10-26 21:41:01] [error] handle_connect error: Underlying Transport Error Oct 26 21:41:01 volumio wireless.js[674]: trying... Oct 26 21:41:01 volumio sudo[992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 26 21:41:01 volumio sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:02 volumio sudo[992]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:02 volumio dhcpcd[792]: wlan0: leased 192.168.100.21 for 86400 seconds Oct 26 21:41:02 volumio dhcpcd[792]: wlan0: adding route to 192.168.100.0/24 Oct 26 21:41:02 volumio dhcpcd[792]: wlan0: adding default route via 192.168.100.1 Oct 26 21:41:02 volumio avahi-daemon[665]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.21. Oct 26 21:41:02 volumio avahi-daemon[665]: New relevant interface wlan0.IPv4 for mDNS. Oct 26 21:41:02 volumio avahi-daemon[665]: Registering new address record for 192.168.100.21 on wlan0.IPv4. Oct 26 21:41:02 volumio ntpd[879]: ntpd exiting on signal 15 (Terminated) Oct 26 21:41:02 volumio systemd[1]: Stopping Network Time Service... Oct 26 21:41:02 volumio systemd[1]: ntp.service: Succeeded. Oct 26 21:41:02 volumio systemd[1]: Stopped Network Time Service. Oct 26 21:41:02 volumio systemd[1]: Starting Network Time Service... Oct 26 21:41:02 volumio ntpd[1026]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 26 21:41:02 volumio ntpd[1026]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 26 21:41:02 volumio ntpd[1032]: proto: precision = 0.115 usec (-23) Oct 26 21:41:02 volumio systemd[1]: Started Network Time Service. Oct 26 21:41:02 volumio ntpd[1032]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 26 21:41:02 volumio ntpd[1032]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 26 21:41:02 volumio ntpd[1032]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1034 days ago Oct 26 21:41:02 volumio ntpd[1032]: Listen and drop on 0 v6wildcard [::]:123 Oct 26 21:41:02 volumio ntpd[1032]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 26 21:41:02 volumio ntpd[1032]: Listen normally on 2 lo 127.0.0.1:123 Oct 26 21:41:02 volumio ntpd[1032]: Listen normally on 3 wlan0 192.168.100.21:123 Oct 26 21:41:02 volumio ntpd[1032]: Listening on routing socket on fd #20 for interface updates Oct 26 21:41:02 volumio ntpd[1032]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 26 21:41:02 volumio ntpd[1032]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 26 21:41:02 volumio wireless.js[674]: trying... Oct 26 21:41:03 volumio sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 26 21:41:03 volumio sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:03 volumio sudo[1035]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:03 volumio wireless.js[674]: Connected to: ----A1_442375887 Oct 26 21:41:03 volumio wireless.js[674]: ---- Oct 26 21:41:03 volumio sudo[1038]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 26 21:41:03 volumio sudo[1038]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:03 volumio sudo[1038]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:03 volumio wireless.js[674]: ... joined AP, wlan0 IPv4 is 192.168.100.21, ipV6 is undefined Oct 26 21:41:03 volumio wireless.js[674]: It's done! AP Oct 26 21:41:03 volumio iwconfig[1042]: Error for wireless request "Set Power Management" (8B2C) : Oct 26 21:41:03 volumio iwconfig[1042]: SET failed on device wlan0 ; Operation not supported. Oct 26 21:41:03 volumio systemd[1]: Started Wireless Services. Oct 26 21:41:03 volumio systemd[1]: Started Volumio Backend Module. Oct 26 21:41:03 volumio systemd[1]: Started Volumio Cpu Tweaker. Oct 26 21:41:03 volumio systemd[1]: Started Intel SST and HDA soundcard init service. Oct 26 21:41:03 volumio systemd[1]: Started Start Volumio Kiosk. Oct 26 21:41:03 volumio volumio-cpu-tweak[1044]: Setting RT Priority for mpd Oct 26 21:41:03 volumio volumio-cpu-tweak[1044]: Not enough cores to set MPD affinity Oct 26 21:41:03 volumio volumio-cpu-tweak[1044]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 26 21:41:03 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 26 21:41:03 volumio ntpd[1032]: Soliciting pool server 161.53.131.133 Oct 26 21:41:03 volumio startx[1050]: X.Org X Server 1.20.4 Oct 26 21:41:03 volumio startx[1050]: X Protocol Version 11, Revision 0 Oct 26 21:41:03 volumio startx[1050]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Oct 26 21:41:03 volumio startx[1050]: Current Operating System: Linux volumio 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 Oct 26 21:41:03 volumio startx[1050]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=d35eb915-f24a-437a-a43b-4afe991304f7 bootpart=UUID=79FB-2BA3 datapart=UUID=2fc5abe1-9579-4377-a3f7-b00c1ebaeecd hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no Oct 26 21:41:03 volumio startx[1050]: Build Date: 18 December 2021 09:05:36AM Oct 26 21:41:03 volumio startx[1050]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Oct 26 21:41:03 volumio startx[1050]: Current version of pixman: 0.36.0 Oct 26 21:41:03 volumio startx[1050]: Before reporting problems, check http://wiki.x.org Oct 26 21:41:03 volumio startx[1050]: to make sure that you have the latest version. Oct 26 21:41:03 volumio startx[1050]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 26 21:41:03 volumio startx[1050]: (++) from command line, (!!) notice, (II) informational, Oct 26 21:41:03 volumio startx[1050]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 26 21:41:03 volumio startx[1050]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Oct 26 21:41:03 2025 Oct 26 21:41:03 volumio startx[1050]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 26 21:41:03 volumio systemd[1]: soundcard-init.service: Succeeded. Oct 26 21:41:04 volumio volumio-time-update[663]: volumio-time-update-util: Fetching time from Volumio... Oct 26 21:41:04 volumio volumio-time-update[663]: volumio-time-update-util: Setting system time to: 2025-10-26 21:41:04 Oct 26 21:41:04 volumio sudo[1188]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-10-26 21:41:04 Oct 26 21:41:04 volumio sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:04 volumio dbus-daemon[659]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.6' (uid=0 pid=1189 comm="timedatectl set-time 2025-10-26 21:41:04 ") Oct 26 21:41:04 volumio ntpd[1032]: Soliciting pool server 185.156.110.183 Oct 26 21:41:04 volumio systemd[1]: Starting Time & Date Service... Oct 26 21:41:04 volumio ntpd[1032]: Soliciting pool server 161.53.131.232 Oct 26 21:41:04 volumio kernel: broken atomic modeset userspace detected, disabling atomic Oct 26 21:41:04 volumio dbus-daemon[659]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 26 21:41:04 volumio systemd[1]: Started Time & Date Service. Oct 26 21:41:04 volumio systemd-timedated[1190]: Changed local time to Sun Oct 26 21:41:04 2025 Oct 26 21:41:04 volumio sudo[1188]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:04 volumio systemd[1]: Started Volumio Time Update Utility. Oct 26 21:41:04 volumio volumio-time-update[663]: volumio-time-update-util: System time updated successfully. Oct 26 21:41:04 volumio startx[1050]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Oct 26 21:41:04 volumio ntpd[1032]: Soliciting pool server 161.53.131.231 Oct 26 21:41:04 volumio ntpd[1032]: Soliciting pool server 161.53.78.71 Oct 26 21:41:05 volumio volumio[1043]: info: ------------------------------------------- Oct 26 21:41:05 volumio volumio[1043]: info: ----- Volumio3 ---- Oct 26 21:41:05 volumio volumio[1043]: info: ------------------------------------------- Oct 26 21:41:05 volumio volumio[1043]: info: ----- System startup ---- Oct 26 21:41:05 volumio volumio[1043]: info: ------------------------------------------- Oct 26 21:41:05 volumio ntpd[1032]: Soliciting pool server 161.53.78.72 Oct 26 21:41:05 volumio ntpd[1032]: Soliciting pool server 185.143.194.7 Oct 26 21:41:06 volumio nmbd[812]: [2025/10/26 21:41:06.004006, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 26 21:41:06 volumio nmbd[812]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 26 21:41:06 volumio systemd[1]: Started Samba NMB Daemon. Oct 26 21:41:06 volumio systemd[1]: Starting Samba Winbind Daemon... Oct 26 21:41:06 volumio winbindd[1245]: [2025/10/26 21:41:06.404033, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 26 21:41:06 volumio winbindd[1245]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 26 21:41:06 volumio winbindd[1245]: [2025/10/26 21:41:06.439846, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 26 21:41:06 volumio systemd[1]: Started Samba Winbind Daemon. Oct 26 21:41:06 volumio winbindd[1245]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 26 21:41:06 volumio systemd[1]: Starting Samba SMB Daemon... Oct 26 21:41:06 volumio ntpd[1032]: Soliciting pool server 185.103.216.7 Oct 26 21:41:06 volumio ntpd[1032]: Soliciting pool server 2a0c:9f00:2:9b50::1 Oct 26 21:41:07 volumio smbd[1260]: [2025/10/26 21:41:07.124264, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 26 21:41:07 volumio systemd[1]: Started Samba SMB Daemon. Oct 26 21:41:07 volumio smbd[1260]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 26 21:41:07 volumio systemd[1]: Reached target Multi-User System. Oct 26 21:41:07 volumio systemd[1]: Reached target Graphical Interface. Oct 26 21:41:07 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 26 21:41:07 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 26 21:41:07 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 26 21:41:07 volumio systemd[1]: Startup finished in 5.582s (firmware) + 9.441s (loader) + 10.667s (kernel) + 24.037s (userspace) = 49.729s. Oct 26 21:41:07 volumio volumio[1043]: info: MYVOLUMIO Environment detected Oct 26 21:41:07 volumio volumio[1043]: info: Plugin folders cleanup Oct 26 21:41:07 volumio volumio[1043]: info: Scanning into folder /volumio/app/plugins/ Oct 26 21:41:07 volumio volumio[1043]: info: Scanning category audio_interface Oct 26 21:41:07 volumio volumio[1043]: info: Scanning category miscellanea Oct 26 21:41:07 volumio volumio[1043]: info: Scanning category music_service Oct 26 21:41:07 volumio volumio[1043]: info: Scanning category plugins.json Oct 26 21:41:07 volumio volumio[1043]: info: Scanning category system_controller Oct 26 21:41:07 volumio volumio[1043]: info: Scanning category user_interface Oct 26 21:41:07 volumio volumio[1043]: info: Scanning into folder /data/plugins/ Oct 26 21:41:07 volumio volumio[1043]: info: Scanning category music_service Oct 26 21:41:07 volumio volumio[1043]: info: Scanning category user_interface Oct 26 21:41:07 volumio volumio[1043]: info: Plugin folders cleanup completed Oct 26 21:41:07 volumio volumio[1043]: info: ------------------------------------------- Oct 26 21:41:07 volumio volumio[1043]: info: ----- Core plugins startup ---- Oct 26 21:41:07 volumio volumio[1043]: info: ------------------------------------------- Oct 26 21:41:07 volumio volumio[1043]: info: Loading plugins from folder /volumio/app/plugins/ Oct 26 21:41:07 volumio volumio[1043]: info: Adding plugin upnp to MyMusic Plugins Oct 26 21:41:07 volumio volumio[1043]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 26 21:41:07 volumio volumio[1043]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 26 21:41:07 volumio volumio[1043]: info: Loading plugins from folder /data/plugins/ Oct 26 21:41:07 volumio volumio[1043]: info: Loading plugin "system"... Oct 26 21:41:08 volumio volumio[1043]: info: Loading plugin "appearance"... Oct 26 21:41:11 volumio volumio[1043]: info: Loading plugin "network"... Oct 26 21:41:11 volumio volumio[1043]: info: Refreshing Cached IP Addresses Oct 26 21:41:11 volumio sudo[1282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 26 21:41:11 volumio sudo[1282]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:11 volumio sudo[1282]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:11 volumio sudo[1284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 26 21:41:11 volumio sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:11 volumio sudo[1284]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:11 volumio volumio[1043]: info: Loading plugin "services"... Oct 26 21:41:11 volumio volumio[1043]: info: Loading plugin "alsa_controller"... Oct 26 21:41:11 volumio sudo[1293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 26 21:41:11 volumio sudo[1293]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:11 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 21:41:11 volumio volumio[1043]: info: Loading plugin "wizard"... Oct 26 21:41:11 volumio volumio[1043]: info: Loading plugin "networkfs"... Oct 26 21:41:11 volumio volumio[1043]: info: Starting Udev Watcher for removable devices Oct 26 21:41:11 volumio volumio[1043]: info: Ignoring mount for partition: boot Oct 26 21:41:11 volumio volumio[1043]: info: Ignoring mount for partition: volumio Oct 26 21:41:11 volumio volumio[1043]: info: Ignoring mount for partition: volumio_data Oct 26 21:41:11 volumio volumio[1043]: info: Mounting Device USB_ROUTER Oct 26 21:41:11 volumio sudo[1316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/USB_ROUTER -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 26 21:41:11 volumio sudo[1316]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:11 volumio volumio-remote-updater[673]: [2025-10-26 21:41:11] [connect] Successful connection Oct 26 21:41:11 volumio kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Oct 26 21:41:11 volumio kernel: FAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 26 21:41:11 volumio sudo[1316]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:11 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 26 21:41:11 volumio volumio[1043]: info: Loading plugin "volumio_command_line_client"... Oct 26 21:41:11 volumio volumio[1043]: info: Loading plugin "upnp"... Oct 26 21:41:11 volumio volumio[1043]: info: [1761511271948] Starting Upmpd Daemon Oct 26 21:41:11 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 26 21:41:11 volumio volumio[1043]: info: Loading plugin "my_music"... Oct 26 21:41:11 volumio volumio[1043]: info: Loading plugin "mpd"... Oct 26 21:41:12 volumio volumio[1043]: info: Loading plugin "upnp_browser"... Oct 26 21:41:12 volumio volumio[1043]: info: Loading plugin "alarm-clock"... Oct 26 21:41:13 volumio ntpd[1032]: receive: Unexpected origin timestamp 0xeca905e8.e2b4a1b5 does not match aorg 0000000000.00000000 from server@161.53.78.71 xmt 0xeca905e9.8e23f909 Oct 26 21:41:13 volumio ntpd[1032]: receive: Unexpected origin timestamp 0xeca905e8.e2b71e5f does not match aorg 0000000000.00000000 from server@161.53.131.231 xmt 0xeca905e9.8e66a8f5 Oct 26 21:41:13 volumio ntpd[1032]: receive: Unexpected origin timestamp 0xeca905e8.e2b9ac04 does not match aorg 0000000000.00000000 from server@161.53.131.232 xmt 0xeca905e9.8dc7a297 Oct 26 21:41:13 volumio ntpd[1032]: receive: Unexpected origin timestamp 0xeca905e8.e2af8485 does not match aorg 0000000000.00000000 from server@185.143.194.7 xmt 0xeca905e9.8e6c1625 Oct 26 21:41:13 volumio volumio[1043]: info: Plugin airplay_emulation is not enabled Oct 26 21:41:13 volumio volumio[1043]: info: Loading plugin "last_100"... Oct 26 21:41:13 volumio volumio[1043]: info: Loading plugin "webradio"... Oct 26 21:41:13 volumio dbus-daemon[659]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.9' (uid=0 pid=1241 comm="/usr/lib/chromium/chromium --show-component-extens") Oct 26 21:41:13 volumio volumio[1043]: info: Loading plugin "i2s_dacs"... Oct 26 21:41:13 volumio systemd[1]: Starting Bluetooth service... Oct 26 21:41:13 volumio volumio[1043]: info: I2S DAC not set, start Auto-detection Oct 26 21:41:13 volumio volumio[1043]: info: Loading plugin "volumiodiscovery"... Oct 26 21:41:13 volumio volumio[1043]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 26 21:41:13 volumio volumio[1043]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 26 21:41:13 volumio volumio[1043]: *** WARNING *** For more information see Oct 26 21:41:13 volumio volumio[1043]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 26 21:41:13 volumio volumio[1043]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 26 21:41:13 volumio volumio[1043]: *** WARNING *** For more information see Oct 26 21:41:13 volumio node[1043]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 26 21:41:13 volumio node[1043]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 26 21:41:13 volumio node[1043]: *** WARNING *** For more information see Oct 26 21:41:13 volumio node[1043]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 26 21:41:13 volumio node[1043]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 26 21:41:13 volumio node[1043]: *** WARNING *** For more information see Oct 26 21:41:13 volumio volumio[1043]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 26 21:41:13 volumio volumio[1043]: info: Discovery: Started advertising with name: Volumio Oct 26 21:41:13 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 26 21:41:14 volumio volumio[1043]: info: Loading plugin "calmradio"... Oct 26 21:41:14 volumio kernel: Bluetooth: Core ver 2.22 Oct 26 21:41:14 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Oct 26 21:41:14 volumio kernel: Bluetooth: HCI device and connection manager initialized Oct 26 21:41:14 volumio kernel: Bluetooth: HCI socket layer initialized Oct 26 21:41:14 volumio kernel: Bluetooth: L2CAP socket layer initialized Oct 26 21:41:14 volumio kernel: Bluetooth: SCO socket layer initialized Oct 26 21:41:14 volumio bluetoothd[1330]: Bluetooth daemon 5.50 Oct 26 21:41:14 volumio bluetoothd[1330]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Oct 26 21:41:14 volumio dbus-daemon[659]: [system] Successfully activated service 'org.bluez' Oct 26 21:41:14 volumio systemd[1]: Started Bluetooth service. Oct 26 21:41:14 volumio bluetoothd[1330]: Starting SDP server Oct 26 21:41:14 volumio bluetoothd[1330]: Excluding (cli) sap Oct 26 21:41:14 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Oct 26 21:41:14 volumio kernel: Bluetooth: BNEP filters: protocol multicast Oct 26 21:41:14 volumio kernel: Bluetooth: BNEP socket layer initialized Oct 26 21:41:14 volumio bluetoothd[1330]: Bluetooth management interface 1.22 initialized Oct 26 21:41:14 volumio dbus-daemon[659]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.12' (uid=0 pid=1330 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Oct 26 21:41:14 volumio systemd[1]: Starting Hostname Service... Oct 26 21:41:15 volumio dbus-daemon[659]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 26 21:41:15 volumio systemd[1]: Started Hostname Service. Oct 26 21:41:16 volumio sudo[1293]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:16 volumio volumio[1043]: info: Loading plugin "spop"... Oct 26 21:41:17 volumio volumio[1043]: info: Loading plugin "outputs"... Oct 26 21:41:17 volumio volumio[1043]: info: Loading plugin "albumart"... Oct 26 21:41:17 volumio volumio[1043]: info: Plugin example_plugin is not enabled Oct 26 21:41:17 volumio volumio[1043]: info: Loading plugin "inputs"... Oct 26 21:41:17 volumio volumio[1043]: info: Loading plugin "updater_comm"... Oct 26 21:41:17 volumio systemd[1]: systemd-fsckd.service: Succeeded. Oct 26 21:41:18 volumio volumio[1043]: Forking 1 albumart workers Oct 26 21:41:18 volumio volumio[1043]: info: Plugin mpdemulation is not enabled Oct 26 21:41:18 volumio volumio[1043]: info: Loading plugin "rest_api"... Oct 26 21:41:18 volumio volumio[1043]: info: Loading plugin "websocket"... Oct 26 21:41:18 volumio volumio[1043]: info: Starting Socket.io Server version 2.3.0 Oct 26 21:41:18 volumio volumio[1043]: info: Loading plugin "minidlna"... Oct 26 21:41:18 volumio volumio[1043]: info: Applying required configuration parameters for plugin minidlna Oct 26 21:41:18 volumio volumio[1043]: Starting albumart workers Oct 26 21:41:18 volumio volumio[1043]: info: Loading plugin "motherearthradio"... Oct 26 21:41:18 volumio volumio[1043]: info: Applying required configuration parameters for plugin motherearthradio Oct 26 21:41:18 volumio volumio[1043]: info: [1761511278735] [MotherEarth] API delay: 5 Oct 26 21:41:18 volumio volumio[1043]: info: Loading plugin "Systeminfo"... Oct 26 21:41:19 volumio volumio[1043]: info: Loading i18n strings for locale en Oct 26 21:41:19 volumio volumio[1043]: Updating browse sources language Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::initPlayerControls Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 21:41:19 volumio volumio[1043]: Express server listening on port 3000 Oct 26 21:41:19 volumio volumio[1043]: [Metrics] WebUI: 14s 368.11ms Oct 26 21:41:19 volumio volumio[1043]: info: Setting Device type: x86 Oct 26 21:41:19 volumio volumio[1043]: info: CoreStateMachine::resetVolumioState Oct 26 21:41:19 volumio volumio[1043]: info: CoreStateMachine::getcurrentVolume Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::volumioRetrievevolume Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:41:19 volumio volumio[1043]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 26 21:41:19 volumio volumio[1043]: info: Completed loading Core Plugins Oct 26 21:41:19 volumio volumio[1043]: info: Preparing to generate the ALSA configuration file Oct 26 21:41:19 volumio volumio[1043]: info: Volumio Network Manager: Network status updated: 2 Oct 26 21:41:19 volumio volumio[1043]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 26 21:41:19 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.100.21:3000 from 192.168.100.3 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Oct 26 21:41:19 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.100.21 from 192.168.100.3 UA: Mozilla/5.0 (Linux; Android 15; 23021RAA2Y Build/AQ3A.240829.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/140.0.7339.207 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 26 21:41:19 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.100.21 from 192.168.100.3 UA: Mozilla/5.0 (Linux; Android 15; 23021RAA2Y Build/AQ3A.240829.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/140.0.7339.207 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 21:41:19 volumio volumio[1043]: info: Discovery: Getting this device information Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:19 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 21:41:19 volumio volumio[1043]: info: Reloading queue from file Oct 26 21:41:19 volumio volumio[1043]: info: VolumeController:: Volume=100 Mute =false Oct 26 21:41:19 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:19 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:19 volumio volumio[1043]: info: CoreStateMachine::updateTrackBlock Oct 26 21:41:19 volumio volumio[1043]: info: CorePlayQueue::getTrackBlock Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::volumioRetrievevolume Oct 26 21:41:19 volumio volumio[1043]: info: Asound.conf file unchanged, so no further update is needed Oct 26 21:41:19 volumio volumio[1043]: info: Output device has changed, restarting MPD Oct 26 21:41:19 volumio sh[587]: timed out Oct 26 21:41:19 volumio sh[587]: dhcpcd exited Oct 26 21:41:19 volumio volumio[1043]: info: ___________ START PLUGINS ___________ Oct 26 21:41:19 volumio dhcpcd[647]: timed out Oct 26 21:41:19 volumio sh[587]: ifup: failed to bring up eth0 Oct 26 21:41:19 volumio dhcpcd[647]: dhcpcd exited Oct 26 21:41:19 volumio sudo[1474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 26 21:41:19 volumio sudo[1474]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:19 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:41:19 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Oct 26 21:41:19 volumio sudo[1476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 26 21:41:19 volumio sudo[1476]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:19 volumio volumio[1043]: info: ControllerMpd::onStart: Initializing MPD Oct 26 21:41:19 volumio volumio[1043]: info: Creating MPD Configuration file Oct 26 21:41:19 volumio sudo[1474]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:19 volumio systemd[1]: Stopping Music Player Daemon... Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 21:41:19 volumio volumio[1043]: info: [1761511279626] CoreMusicLibrary::Adding element Media Servers Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 21:41:19 volumio sudo[1480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 26 21:41:19 volumio sudo[1480]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:19 volumio sudo[1480]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:19 volumio sudo[1482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 26 21:41:19 volumio sudo[1482]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 21:41:19 volumio volumio[1043]: info: [1761511279650] CoreMusicLibrary::Adding element Last_100 Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 21:41:19 volumio volumio[1043]: info: [1761511279652] CoreMusicLibrary::Adding element Webradio Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 21:41:19 volumio volumio[1043]: info: Initializing BBC Radios Oct 26 21:41:19 volumio systemd[1]: mpd.service: Succeeded. Oct 26 21:41:19 volumio systemd[1]: Stopped Music Player Daemon. Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:41:19 volumio volumio[1043]: info: Adding Calm Radio to Browse Sources Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 21:41:19 volumio volumio[1043]: info: [1761511279720] CoreMusicLibrary::Adding element Calm Radio Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 21:41:19 volumio volumio[1043]: Cannot find translation for source Calm Radio Oct 26 21:41:19 volumio systemd[1]: Starting Music Player Daemon... Oct 26 21:41:19 volumio volumio[1043]: info: Creating Spotify config file Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:19 volumio volumio[1043]: info: Loading i18n strings for locale en Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 21:41:19 volumio volumio[1043]: info: [1761511279773] CoreMusicLibrary::Adding element Mother Earth Radio Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 21:41:19 volumio volumio[1043]: Cannot find translation for source Calm Radio Oct 26 21:41:19 volumio volumio[1043]: Cannot find translation for source Mother Earth Radio Oct 26 21:41:19 volumio volumio[1043]: info: Volumio Calling Home Oct 26 21:41:19 volumio sudo[1491]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 26 21:41:19 volumio sudo[1491]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:19 volumio sudo[1491]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:19 volumio volumio[1043]: info: CoreStateMachine::setRepeat true single undefined Oct 26 21:41:19 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:19 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:19 volumio volumio[1043]: info: CoreStateMachine::setRandom null Oct 26 21:41:19 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:19 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:19 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:19 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.100.21:3000 from 192.168.100.3 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Oct 26 21:41:19 volumio volumio[1043]: info: minidlna: Found miniDLNA version 1.2.1 Oct 26 21:41:19 volumio volumio[1043]: info: minidlna: Starting minidlna.service Oct 26 21:41:20 volumio volumio[1043]: info: MPD Permissions set Oct 26 21:41:20 volumio volumio[1043]: info: MPD Permissions set Oct 26 21:41:20 volumio sudo[1504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 26 21:41:20 volumio sudo[1504]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 26 21:41:20 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.100.21 from 192.168.100.3 UA: Mozilla/5.0 (Linux; Android 15; 23021RAA2Y Build/AQ3A.240829.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/140.0.7339.207 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 26 21:41:20 volumio volumio[1043]: info: Volumio called home Oct 26 21:41:20 volumio volumio[1043]: info: Discovery: adding a7da7d09-22b2-4887-af85-25f879e02979 Oct 26 21:41:20 volumio volumio[1043]: info: Discovery: Found device Volumio Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:20 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Oct 26 21:41:20 volumio sudo[1504]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:20 volumio volumio[1043]: info: Spotify config file written Oct 26 21:41:20 volumio volumio[1043]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 21:41:20 volumio sudo[1510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 26 21:41:20 volumio sudo[1510]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:20 volumio volumio[1043]: info: VolumeController:: Volume=100 Mute =false Oct 26 21:41:20 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:20 volumio volumio[1043]: info: No need to fix Spotify hosts Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:20 volumio systemd[1]: Started go-librespot Daemon. Oct 26 21:41:20 volumio go-librespot[1513]: go-librespot daemon starting... Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 26 21:41:20 volumio sudo[1510]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 26 21:41:20 volumio mpd[1500]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 26 21:41:20 volumio mpd[1500]: output: No 'audio_output' defined in config file Oct 26 21:41:20 volumio mpd[1500]: output: Successfully detected a sndio audio device Oct 26 21:41:20 volumio mpd[1500]: zeroconf: No global port, disabling zeroconf Oct 26 21:41:20 volumio systemd[1]: Started Music Player Daemon. Oct 26 21:41:20 volumio sudo[1482]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:20 volumio sudo[1476]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:20 volumio volumio[1043]: info: minidlna: systemctl start minidlna.service succeeded. Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::volumioGetVisibleSources Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::volumioGetQueue Oct 26 21:41:20 volumio volumio[1043]: info: CoreStateMachine::getQueue Oct 26 21:41:20 volumio volumio[1043]: info: CorePlayQueue::getQueue Oct 26 21:41:20 volumio volumio[1043]: info: Listing playlists Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 26 21:41:20 volumio volumio[1043]: info: Received Get System Info Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 21:41:20 volumio volumio[1043]: info: Discovery: Getting this device information Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:20 volumio volumio[1043]: info: Completed starting Core Plugins Oct 26 21:41:20 volumio volumio[1043]: info: ------------------------------------------- Oct 26 21:41:20 volumio volumio[1043]: info: ----- MyVolumio plugins startup ---- Oct 26 21:41:20 volumio volumio[1043]: info: ------------------------------------------- Oct 26 21:41:20 volumio volumio[1043]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 26 21:41:20 volumio volumio[1043]: info: MPD running with PID1500 Oct 26 21:41:20 volumio volumio[1043]: ,establishing connection Oct 26 21:41:20 volumio volumio[1043]: error: MPD error: The expression evaluated to a falsy value: Oct 26 21:41:20 volumio volumio[1043]: assert.ok(self.idling) Oct 26 21:41:20 volumio volumio[1043]: error: The expression evaluated to a falsy value: Oct 26 21:41:20 volumio volumio[1043]: assert.ok(self.idling) Oct 26 21:41:20 volumio volumio[1043]: error: MPD error: The expression evaluated to a falsy value: Oct 26 21:41:20 volumio volumio[1043]: assert.ok(self.idling) Oct 26 21:41:20 volumio volumio[1043]: error: The expression evaluated to a falsy value: Oct 26 21:41:20 volumio volumio[1043]: assert.ok(self.idling) Oct 26 21:41:20 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:20 volumio volumio[1043]: error: updateQueue error: null Oct 26 21:41:21 volumio go-librespot[1513]: time="2025-10-26T21:41:21+01:00" level=info msg="running go-librespot 0.4.0" Oct 26 21:41:21 volumio go-librespot[1513]: time="2025-10-26T21:41:21+01:00" level=debug msg="app state loaded" Oct 26 21:41:21 volumio go-librespot[1513]: time="2025-10-26T21:41:21+01:00" level=debug msg="stored credentials not found" Oct 26 21:41:21 volumio go-librespot[1513]: time="2025-10-26T21:41:21+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:41:21 volumio go-librespot[1513]: time="2025-10-26T21:41:21+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 26 21:41:21 volumio go-librespot[1513]: time="2025-10-26T21:41:21+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 26 21:41:21 volumio go-librespot[1513]: time="2025-10-26T21:41:21+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 26 21:41:21 volumio go-librespot[1513]: time="2025-10-26T21:41:21+01:00" level=info msg="zeroconf server listening on port 37605" Oct 26 21:41:22 volumio volumio[1043]: 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/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 26 21:41:22 volumio sudo[1557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 26 21:41:22 volumio sudo[1557]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:22 volumio sudo[1557]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:22 volumio sudo[1559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 26 21:41:22 volumio sudo[1559]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:22 volumio sudo[1559]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:22 volumio sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 26 21:41:22 volumio sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:22 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 26 21:41:22 volumio sudo[1563]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:22 volumio volumio[1043]: info: Upmpdcli Daemon Started Oct 26 21:41:22 volumio volumio[1565]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 26 21:41:23 volumio volumio[1565]: ............................++++ Oct 26 21:41:23 volumio volumio[1043]: info: go-librespot daemon successfully initialized Oct 26 21:41:24 volumio volumio[1043]: 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/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 26 21:41:25 volumio mpd[1500]: exception: No database Oct 26 21:41:25 volumio mpd[1500]: exception: No database Oct 26 21:41:25 volumio mpd[1500]: exception: No database Oct 26 21:41:25 volumio volumio[1043]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Oct 26 21:41:25 volumio volumio[1565]: .............................................................................................................................................................++++ Oct 26 21:41:25 volumio volumio[1565]: e is 65537 (0x010001) Oct 26 21:41:25 volumio volumio[1565]: writing RSA key Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 21:41:26 volumio volumio[1043]: info: Discovery: Getting this device information Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:26 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 21:41:26 volumio volumio[1043]: 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/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::volumioGetVisibleSources Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:26 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 26 21:41:26 volumio volumio[1043]: info: Received Get System Info Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 21:41:26 volumio volumio[1043]: info: Discovery: Getting this device information Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:26 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:26 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:26 volumio volumio[1043]: info: Listing playlists Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 26 21:41:26 volumio volumio[1043]: info: Received Get System Info Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 21:41:26 volumio volumio[1043]: info: Discovery: Getting this device information Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:26 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:26 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::volumioGetVisibleSources Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:26 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:26 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 26 21:41:26 volumio volumio[1043]: info: Received Get System Info Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 21:41:26 volumio volumio[1043]: info: Discovery: Getting this device information Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:26 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 21:41:26 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:26 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:26 volumio volumio[1043]: info: Listing playlists Oct 26 21:41:26 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Oct 26 21:41:26 volumio go-librespot[1513]: time="2025-10-26T21:41:26+01:00" level=debug msg="new websocket client" Oct 26 21:41:26 volumio volumio[1043]: info: Connection to go-librespot Websocket established Oct 26 21:41:27 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 26 21:41:27 volumio volumio-remote-updater[673]: [2025-10-26 21:41:27] [connect] Successful connection Oct 26 21:41:27 volumio volumio-remote-updater[673]: [2025-10-26 21:41:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1761511287 101 Oct 26 21:41:27 volumio volumio[1043]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 10 Oct 26 21:41:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 26 21:41:28 volumio volumio[1043]: info: Received Get System Info Oct 26 21:41:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 21:41:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 21:41:28 volumio volumio[1043]: info: Discovery: Getting this device information Oct 26 21:41:28 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:28 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 21:41:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 26 21:41:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 26 21:41:29 volumio volumio[1043]: info: Adding plugin bluetooth to MyMusic Plugins Oct 26 21:41:29 volumio volumio[1043]: info: Adding plugin multiroom to MyMusic Plugins Oct 26 21:41:29 volumio volumio[1043]: info: Adding plugin metavolumio to MyMusic Plugins Oct 26 21:41:29 volumio volumio[1043]: info: Adding plugin cd_controller to MyMusic Plugins Oct 26 21:41:29 volumio volumio[1043]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 26 21:41:29 volumio volumio[1043]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 26 21:41:29 volumio volumio[1043]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 26 21:41:30 volumio volumio[1043]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 26 21:41:30 volumio volumio[1043]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 26 21:41:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:41:30 volumio volumio[1043]: info: Starting MyVolumio Remote Streaming Endpoints Oct 26 21:41:30 volumio volumio[1043]: info: MyVolumio login type: Token Oct 26 21:41:30 volumio volumio[1043]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 26 21:41:30 volumio volumio[1043]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 26 21:41:30 volumio volumio[1043]: info: Starting Streaming Service Transparent Proxy Oct 26 21:41:30 volumio volumio[1043]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 26 21:41:30 volumio volumio[1043]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 26 21:41:30 volumio volumio[1043]: info: Streaming services startup Oct 26 21:41:30 volumio volumio[1043]: info: Starting Streaming Daemon Oct 26 21:41:30 volumio sudo[1780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 26 21:41:30 volumio sudo[1780]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:30 volumio sudo[1780]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:30 volumio volumio[1043]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 26 21:41:31 volumio volumio[1043]: info: Getting Spotify volume Oct 26 21:41:31 volumio volumio[1043]: info: CoreCommandRouter::volumioPlay Oct 26 21:41:31 volumio volumio[1043]: info: CoreStateMachine::play index undefined Oct 26 21:41:31 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 21:41:31 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:31 volumio volumio[1043]: info: CoreStateMachine::startPlaybackTimer Oct 26 21:41:31 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:31 volumio volumio[1043]: info: [1761511291042] ControllerWebradio::clearAddPlayTrack Oct 26 21:41:31 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 21:41:31 volumio volumio[1043]: error: Cannot start Volumio Streaming Daemon Oct 26 21:41:31 volumio volumio[1043]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 26 21:41:31 volumio volumio[1043]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 26 21:41:31 volumio volumio[1043]: info: sendMpdCommand stop took 56 milliseconds Oct 26 21:41:31 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 21:41:31 volumio volumio[1043]: info: Oct 26 21:41:31 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Oct 26 21:41:31 volumio volumio[1043]: info: Ignoring MPD Status Update Oct 26 21:41:31 volumio volumio[1043]: info: sendMpdCommand clear took 4 milliseconds Oct 26 21:41:31 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand load "https://radio.doc.ba/radio/8000/radio.mp3" Oct 26 21:41:31 volumio volumio[1043]: info: Oct 26 21:41:31 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Oct 26 21:41:31 volumio volumio[1043]: info: Ignoring MPD Status Update Oct 26 21:41:31 volumio volumio[1043]: info: Oct 26 21:41:31 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Oct 26 21:41:31 volumio volumio[1043]: info: Ignoring MPD Status Update Oct 26 21:41:31 volumio volumio[1043]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Oct 26 21:41:31 volumio volumio[1043]: error: updateQueue error: null Oct 26 21:41:31 volumio volumio[1043]: info: ------------------------------ 9ms Oct 26 21:41:31 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:31 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:31 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 26 21:41:31 volumio volumio[1043]: SPOTIFY: SPOTIFY VOLUME undefined Oct 26 21:41:31 volumio volumio[1043]: SPOTIFY: VOLUMIO VOLUME 100 Oct 26 21:41:31 volumio volumio[1043]: info: Aligning Spotify Volume to Volumio Volume Oct 26 21:41:31 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:31 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:31 volumio volumio[1043]: info: Setting Spotify Volume from Volumio: 100 Oct 26 21:41:31 volumio volumio[1043]: STREAMING PROXY: Starting server on port 3245 Oct 26 21:41:31 volumio volumio[1043]: Node JS runtime: 14 Oct 26 21:41:31 volumio volumio[1043]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 26 21:41:32 volumio mpd[1500]: exception: No such playlist Oct 26 21:41:32 volumio volumio[1043]: error: updateQueue error: null Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand add "https://radio.doc.ba/radio/8000/radio.mp3" Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 1284ms Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 1282ms Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Oct 26 21:41:32 volumio volumio[1043]: info: Ignoring MPD Status Update Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand add "https://radio.doc.ba/radio/8000/radio.mp3" took 1 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand play Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Oct 26 21:41:32 volumio volumio[1043]: info: Ignoring MPD Status Update Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Oct 26 21:41:32 volumio volumio[1043]: info: Ignoring MPD Status Update Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 5ms Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand play took 4 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 4ms Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces state update: player Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::getState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces state update: player Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::getState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces state update: player Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::getState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Oct 26 21:41:32 volumio mpd[1500]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Oct 26 21:41:32 volumio mpd[1500]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Oct 26 21:41:32 volumio mpd[1500]: player: problems opening audio device while playing "https://radio.doc.ba/radio/8000/radio.mp3" Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand status took 35 milliseconds Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces state update: player Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::getState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand status took 54 milliseconds Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand status took 52 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand clearerror took 23 milliseconds Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces state update: player Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::getState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces state update: player Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::getState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 44 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand status took 39 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand clearerror took 30 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand clearerror took 29 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 15 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand status took 15 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand status took 10 milliseconds Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Oct 26 21:41:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:32 volumio volumio[1043]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":null,"album":null,"uri":"https://radio.doc.ba/radio/8000/radio.mp3","trackType":"mp3"} Oct 26 21:41:32 volumio volumio[1043]: verbose: CURRENT POSITION 0 Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::syncState stateService pause Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Oct 26 21:41:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:32 volumio volumio[1043]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":null,"album":null,"uri":"https://radio.doc.ba/radio/8000/radio.mp3","trackType":"mp3"} Oct 26 21:41:32 volumio volumio[1043]: verbose: CURRENT POSITION 0 Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::syncState stateService pause Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Oct 26 21:41:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:32 volumio volumio[1043]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":null,"album":null,"uri":"https://radio.doc.ba/radio/8000/radio.mp3","trackType":"mp3"} Oct 26 21:41:32 volumio volumio[1043]: verbose: CURRENT POSITION 0 Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::syncState stateService pause Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 129ms Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 128ms Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 127ms Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 44 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 40 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 41 milliseconds Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Oct 26 21:41:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:32 volumio volumio[1043]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":null,"album":null,"uri":"https://radio.doc.ba/radio/8000/radio.mp3","trackType":"mp3"} Oct 26 21:41:32 volumio volumio[1043]: verbose: CURRENT POSITION 0 Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::syncState stateService pause Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Oct 26 21:41:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:32 volumio volumio[1043]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":null,"album":null,"uri":"https://radio.doc.ba/radio/8000/radio.mp3","trackType":"mp3"} Oct 26 21:41:32 volumio volumio[1043]: verbose: CURRENT POSITION 0 Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::syncState stateService pause Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Oct 26 21:41:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:32 volumio volumio[1043]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":null,"album":null,"uri":"https://radio.doc.ba/radio/8000/radio.mp3","trackType":"mp3"} Oct 26 21:41:32 volumio volumio[1043]: verbose: CURRENT POSITION 0 Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::syncState stateService pause Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 138ms Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 110ms Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 106ms Oct 26 21:41:32 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 26 21:41:32 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 26 21:41:32 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 26 21:41:32 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 26 21:41:32 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 26 21:41:32 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 26 21:41:32 volumio volumio[1043]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Oct 26 21:41:32 volumio volumio[1043]: info: Sending Spotify command with payload to local API: /player/volume Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPlay Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::play index undefined Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 21:41:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::startPlaybackTimer Oct 26 21:41:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:32 volumio volumio[1043]: info: [1761511292687] ControllerWebradio::clearAddPlayTrack Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 21:41:32 volumio mpd[1500]: player: played "https://radio.doc.ba/radio/8000/radio.mp3" Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand stop took 9 milliseconds Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces state update: player Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Oct 26 21:41:32 volumio volumio[1043]: info: Ignoring MPD Status Update Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::getState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand clear took 2 milliseconds Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand load "https://radio.doc.ba/radio/8000/radio.mp3" Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces state update: player Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::getState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces state update: player Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::getState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand status took 7 milliseconds Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseState Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:32 volumio volumio[1043]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 24ms Oct 26 21:41:32 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 26 21:41:32 volumio mpd[1500]: exception: No such playlist Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Oct 26 21:41:32 volumio volumio[1043]: info: Ignoring MPD Status Update Oct 26 21:41:32 volumio volumio[1043]: error: updateQueue error: null Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 66ms Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand add "https://radio.doc.ba/radio/8000/radio.mp3" Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand status took 63 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand status took 58 milliseconds Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseState Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:32 volumio volumio[1043]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:32 volumio volumio[1043]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 98ms Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 99ms Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Oct 26 21:41:32 volumio volumio[1043]: info: Ignoring MPD Status Update Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Oct 26 21:41:32 volumio volumio[1043]: info: Ignoring MPD Status Update Oct 26 21:41:32 volumio volumio[1043]: error: updateQueue error: null Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Oct 26 21:41:32 volumio volumio[1043]: info: Ignoring MPD Status Update Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 45ms Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand add "https://radio.doc.ba/radio/8000/radio.mp3" took 43 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 7ms Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 4ms Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand play Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Oct 26 21:41:32 volumio volumio[1043]: info: Ignoring MPD Status Update Oct 26 21:41:32 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 14ms Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand play took 11 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: ------------------------------ 6ms Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces state update: player Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::getState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces state update: player Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::getState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces state update: player Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::getState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Oct 26 21:41:32 volumio mpd[1500]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Oct 26 21:41:32 volumio mpd[1500]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Oct 26 21:41:32 volumio mpd[1500]: player: problems opening audio device while playing "https://radio.doc.ba/radio/8000/radio.mp3" Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces state update: player Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand status took 59 milliseconds Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand status took 61 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::getState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces state update: player Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::getState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand status took 79 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand clearerror took 30 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand clearerror took 29 milliseconds Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 21:41:32 volumio volumio[1043]: info: Oct 26 21:41:32 volumio volumio[1043]: ---------------------------- MPD announces state update: player Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::getState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand status took 30 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand status took 17 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand clearerror took 8 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 26 21:41:32 volumio volumio[1043]: info: sendMpdCommand status took 3 milliseconds Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::parseState Oct 26 21:41:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Oct 26 21:41:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:32 volumio volumio[1043]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":null,"album":null,"uri":"https://radio.doc.ba/radio/8000/radio.mp3","trackType":"mp3"} Oct 26 21:41:32 volumio volumio[1043]: verbose: CURRENT POSITION 0 Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::syncState stateService pause Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Oct 26 21:41:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:32 volumio volumio[1043]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":null,"album":null,"uri":"https://radio.doc.ba/radio/8000/radio.mp3","trackType":"mp3"} Oct 26 21:41:32 volumio volumio[1043]: verbose: CURRENT POSITION 0 Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::syncState stateService pause Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:32 volumio volumio[1043]: info: ControllerMpd::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Oct 26 21:41:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:32 volumio volumio[1043]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":null,"album":null,"uri":"https://radio.doc.ba/radio/8000/radio.mp3","trackType":"mp3"} Oct 26 21:41:32 volumio volumio[1043]: verbose: CURRENT POSITION 0 Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::syncState stateService pause Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Oct 26 21:41:32 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:33 volumio volumio[1043]: info: ------------------------------ 138ms Oct 26 21:41:33 volumio volumio[1043]: info: ------------------------------ 136ms Oct 26 21:41:33 volumio volumio[1043]: info: ------------------------------ 136ms Oct 26 21:41:33 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 44 milliseconds Oct 26 21:41:33 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 44 milliseconds Oct 26 21:41:33 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Oct 26 21:41:33 volumio volumio[1043]: info: ControllerMpd::pushState Oct 26 21:41:33 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Oct 26 21:41:33 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:33 volumio volumio[1043]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":null,"album":null,"uri":"https://radio.doc.ba/radio/8000/radio.mp3","trackType":"mp3"} Oct 26 21:41:33 volumio volumio[1043]: verbose: CURRENT POSITION 0 Oct 26 21:41:33 volumio volumio[1043]: info: CoreStateMachine::syncState stateService pause Oct 26 21:41:33 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Oct 26 21:41:33 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:33 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:33 volumio volumio[1043]: info: ControllerMpd::pushState Oct 26 21:41:33 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Oct 26 21:41:33 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:33 volumio volumio[1043]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":null,"album":null,"uri":"https://radio.doc.ba/radio/8000/radio.mp3","trackType":"mp3"} Oct 26 21:41:33 volumio volumio[1043]: verbose: CURRENT POSITION 0 Oct 26 21:41:33 volumio volumio[1043]: info: CoreStateMachine::syncState stateService pause Oct 26 21:41:33 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Oct 26 21:41:33 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:33 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:33 volumio volumio[1043]: info: ControllerMpd::pushState Oct 26 21:41:33 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Oct 26 21:41:33 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Oct 26 21:41:33 volumio volumio[1043]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":null,"album":null,"uri":"https://radio.doc.ba/radio/8000/radio.mp3","trackType":"mp3"} Oct 26 21:41:33 volumio volumio[1043]: verbose: CURRENT POSITION 0 Oct 26 21:41:33 volumio volumio[1043]: info: CoreStateMachine::syncState stateService pause Oct 26 21:41:33 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Oct 26 21:41:33 volumio volumio[1043]: info: CoreStateMachine::pushState Oct 26 21:41:33 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Oct 26 21:41:33 volumio volumio[1043]: info: ------------------------------ 138ms Oct 26 21:41:33 volumio volumio[1043]: info: ------------------------------ 106ms Oct 26 21:41:33 volumio volumio[1043]: info: ------------------------------ 92ms Oct 26 21:41:33 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 26 21:41:33 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 26 21:41:33 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 26 21:41:33 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 26 21:41:33 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 26 21:41:33 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 26 21:41:34 volumio systemd[1]: systemd-timedated.service: Succeeded. Oct 26 21:41:34 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 26 21:41:36 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 21:41:36 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 21:41:36 volumio volumio[1043]: info: Retrieving Cloud Streaming UI Oct 26 21:41:36 volumio volumio[1043]: info: Getting Tidal Cloud Configuration Oct 26 21:41:36 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 21:41:36 volumio volumio[1043]: info: Getting Qobuz Cloud Configuration Oct 26 21:41:36 volumio volumio[1043]: info: Asking plugin for UI Config Oct 26 21:41:36 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 21:41:36 volumio volumio[1043]: info: Getting Spotify Cloud Configuration Oct 26 21:41:36 volumio volumio[1043]: info: Asking plugin for UI Config Oct 26 21:41:36 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 21:41:36 volumio volumio[1043]: info: Saving Spotify Acccount Oct 26 21:41:36 volumio volumio[1043]: info: Got Tidal Cloud Configuration Oct 26 21:41:36 volumio volumio[1043]: info: Got it Oct 26 21:41:36 volumio volumio[1043]: info: Got it Oct 26 21:41:36 volumio volumio[1043]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 26 21:41:36 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 21:41:36 volumio volumio[1043]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 26 21:41:36 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 26 21:41:36 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 21:41:36 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 21:41:36 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 21:41:36 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 21:41:36 volumio volumio[1043]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 21:41:36 volumio volumio[1043]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 21:41:36 volumio volumio[1043]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 21:41:36 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 21:41:36 volumio mpd[1500]: exception: No database Oct 26 21:41:36 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 26 21:41:38 volumio volumio[1043]: info: MyVolumio token set successfully Oct 26 21:41:38 volumio volumio[1043]: info: MYVOLUMIO: Adding device Oct 26 21:41:38 volumio volumio[1043]: info: MYVOLUMIO: Evaluating Server Oct 26 21:41:39 volumio volumio[1043]: info: MyVolumio status changed Oct 26 21:41:39 volumio volumio[1043]: info: Streaming services startup Oct 26 21:41:39 volumio volumio[1043]: info: Starting Streaming Daemon Oct 26 21:41:39 volumio volumio[1043]: info: Removing browser output: myVolumio user plan is not superstar Oct 26 21:41:39 volumio volumio[1043]: info: Removing audio output: Oct 26 21:41:39 volumio volumio[1043]: info: Stoppping Tunnel 1 Oct 26 21:41:39 volumio sudo[1832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 26 21:41:39 volumio sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:39 volumio sudo[1832]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:39 volumio volumio[1043]: error: Cannot start Volumio Streaming Daemon Oct 26 21:41:39 volumio volumio[1043]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 26 21:41:39 volumio volumio[1043]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 26 21:41:39 volumio sudo[1835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 26 21:41:39 volumio sudo[1835]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:41:39 volumio sudo[1835]: pam_unix(sudo:session): session closed for user root Oct 26 21:41:39 volumio volumio[1043]: info: Remote SSH Stopped Oct 26 21:41:39 volumio volumio[1043]: info: Setting Geolocation for MyVolumio to eu4 Oct 26 21:41:39 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:41:39 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:41:39 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:41:39 volumio volumio[1043]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 26 21:41:40 volumio volumio[1043]: info: Updating MyVolumio device info Oct 26 21:41:40 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:41:40 volumio volumio[1043]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 26 21:41:41 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 26 21:41:41 volumio volumio[1043]: info: CURURI: music-library Oct 26 21:41:41 volumio mpd[1500]: exception: No database Oct 26 21:41:41 volumio volumio[1043]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Oct 26 21:41:41 volumio volumio[1043]: info: Preload queue cleared Oct 26 21:41:42 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 26 21:41:42 volumio volumio[1043]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 26 21:41:42 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 26 21:41:42 volumio volumio[1043]: info: Received Get System Version Oct 26 21:41:42 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 21:41:42 volumio volumio[1043]: info: Received Get System Info Oct 26 21:41:42 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 21:41:42 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 21:41:42 volumio volumio[1043]: info: Discovery: Getting this device information Oct 26 21:41:42 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:42 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 21:41:43 volumio volumio[1043]: info: MYVOLUMIO: Adding device Oct 26 21:41:43 volumio volumio[1043]: info: MYVOLUMIO: Evaluating Server Oct 26 21:41:44 volumio volumio[1043]: info: Setting Geolocation for MyVolumio to eu6 Oct 26 21:41:44 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:41:44 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:41:44 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:41:45 volumio volumio[1043]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 26 21:41:45 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 26 21:41:45 volumio volumio[1043]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Oct 26 21:41:45 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Oct 26 21:41:45 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:45 volumio volumio[1043]: info: Updating MyVolumio device info Oct 26 21:41:45 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:41:45 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:41:45 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:41:45 volumio volumio[1043]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 21:41:47 volumio volumio[1043]: info: Retrieving Cloud Streaming UI Oct 26 21:41:47 volumio volumio[1043]: info: Getting Tidal Cloud Configuration Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 21:41:47 volumio volumio[1043]: info: Getting Qobuz Cloud Configuration Oct 26 21:41:47 volumio volumio[1043]: info: Asking plugin for UI Config Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 21:41:47 volumio volumio[1043]: info: Getting Spotify Cloud Configuration Oct 26 21:41:47 volumio volumio[1043]: info: Asking plugin for UI Config Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 21:41:47 volumio volumio[1043]: info: Saving Spotify Acccount Oct 26 21:41:47 volumio volumio[1043]: info: Got Tidal Cloud Configuration Oct 26 21:41:47 volumio volumio[1043]: info: Got it Oct 26 21:41:47 volumio volumio[1043]: info: Got it Oct 26 21:41:47 volumio volumio[1043]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 21:41:47 volumio volumio[1043]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 21:41:47 volumio mpd[1500]: exception: No database Oct 26 21:41:47 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 26 21:41:49 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 26 21:41:49 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand rescan Oct 26 21:41:49 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 21:41:49 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:41:49 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 26 21:41:49 volumio volumio-remote-updater[673]: No test mode Oct 26 21:41:49 volumio volumio-remote-updater[673]: No alpha test mode Oct 26 21:41:49 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 26 21:41:50 volumio volumio[1043]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n
    \n
  • Fix plugin installations due to apt repository
    • \n
  • Fix chromecast playback
    • \n
\n

NEW ADDITIONS

\n
    \n
  • Add link for downloading CORRD in the side-menu
    • \n
\n","title":"Update v3.832","updateavailable":true} Oct 26 21:41:50 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 26 21:41:50 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:50 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:50 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 26 21:41:50 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 26 21:41:50 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 26 21:41:50 volumio volumio[1043]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 21:41:50 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 21:41:50 volumio volumio[1043]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 26 21:41:50 volumio volumio[1043]: info: Completed starting MyVolumio Plugin Oct 26 21:41:50 volumio volumio[1043]: [Metrics] CommandRouter: 45s 182.59ms Oct 26 21:41:50 volumio volumio[1043]: info: CoreCommandRouter::volumiosetStartupVolume Oct 26 21:41:50 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:41:50 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:41:51 volumio volumio[1043]: info: CoreCommandRouter::Close All Modals sent Oct 26 21:41:51 volumio volumio[1043]: info: CoreCommandRouter::Close All Modals sent Oct 26 21:41:51 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 21:41:51 volumio mpd[1500]: exception: No database Oct 26 21:41:52 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 26 21:41:52 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 26 21:41:52 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 26 21:41:54 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 26 21:41:54 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand update Oct 26 21:41:55 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 21:41:55 volumio mpd[1500]: exception: No database Oct 26 21:41:56 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 21:41:56 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 21:41:56 volumio volumio[1043]: info: Discovery: Getting this device information Oct 26 21:41:56 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:41:56 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 21:41:57 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 26 21:41:57 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 26 21:41:58 volumio volumio[1043]: info: BOOT COMPLETED Oct 26 21:41:59 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 21:41:59 volumio mpd[1500]: exception: No database Oct 26 21:42:01 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 26 21:42:01 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand update Oct 26 21:42:03 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 21:42:03 volumio mpd[1500]: exception: No database Oct 26 21:42:10 volumio kernel: usb 2-1.2: USB disconnect, device number 3 Oct 26 21:42:10 volumio volumio[1043]: info: Partition removed: {"syspath":"/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2/2-1.2:1.0/host5/target5:0:0/5:0:0:0/block/sdb/sdb1","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/pci-0000:00:1d.0-usb-0:1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/2E14-717B /dev/disk/by-partuuid/05db147b-01 /dev/disk/by-label/USB\\x20ROUTER /dev/disk/by-id/usb-Kingston_DataTraveler_3.0_E0D55EA574B118A0A8820295-0:0-part1","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2/2-1.2:1.0/host5/target5:0:0/5:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"2","ID_BUS":"usb","ID_FS_LABEL":"USB_ROUTER","ID_FS_LABEL_ENC":"USB\\x20ROUTER","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"2E14-717B","ID_FS_UUID_ENC":"2E14-717B","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"DataTraveler_3.0","ID_MODEL_ENC":"DataTraveler\\x203.0","ID_MODEL_ID":"1666","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"64","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"242155456","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"05db147b-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"05db147b","ID_PATH":"pci-0000:00:1d.0-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"pci-0000_00_1d_0-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"0000","ID_SERIAL":"Kingston_DataTraveler_3.0_E0D55EA574B118A0A8820295-0:0","ID_SERIAL_SHORT":"E0D55EA574B118A0A8820295","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Kingston","ID_VENDOR_ENC":"Kingston","ID_VENDOR_ID":"0951","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"3257","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3564551"} Oct 26 21:42:10 volumio sudo[1959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1 Oct 26 21:42:10 volumio sudo[1959]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:42:10 volumio systemd[1]: media-USB_ROUTER.mount: Succeeded. Oct 26 21:42:10 volumio sudo[1959]: pam_unix(sudo:session): session closed for user root Oct 26 21:42:13 volumio ntpd[1032]: Soliciting pool server 2a0c:b1c2::c Oct 26 21:42:14 volumio volumio[1043]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/USB_ROUTER Oct 26 21:42:15 volumio volumio[1043]: MPD error: No database Oct 26 21:42:16 volumio kernel: usb 2-1.1: new high-speed USB device number 4 using ehci-pci Oct 26 21:42:16 volumio kernel: usb 2-1.1: New USB device found, idVendor=0951, idProduct=1666, bcdDevice= 2.00 Oct 26 21:42:16 volumio kernel: usb 2-1.1: New USB device strings: Mfr=2, Product=3, SerialNumber=4 Oct 26 21:42:16 volumio kernel: usb 2-1.1: Product: DataTraveler 3.0 Oct 26 21:42:16 volumio kernel: usb 2-1.1: Manufacturer: Kingston Oct 26 21:42:16 volumio kernel: usb 2-1.1: SerialNumber: E0D55EA574B118A0A8820295 Oct 26 21:42:16 volumio kernel: usb-storage 2-1.1:1.0: USB Mass Storage device detected Oct 26 21:42:16 volumio kernel: scsi host5: usb-storage 2-1.1:1.0 Oct 26 21:42:17 volumio kernel: scsi 5:0:0:0: Direct-Access Kingston DataTraveler 3.0 0000 PQ: 0 ANSI: 4 Oct 26 21:42:17 volumio kernel: sd 5:0:0:0: Attached scsi generic sg1 type 0 Oct 26 21:42:17 volumio kernel: sd 5:0:0:0: [sdb] 242155520 512-byte logical blocks: (124 GB/115 GiB) Oct 26 21:42:17 volumio kernel: sd 5:0:0:0: [sdb] Write Protect is off Oct 26 21:42:17 volumio kernel: sd 5:0:0:0: [sdb] Mode Sense: 23 00 00 00 Oct 26 21:42:17 volumio kernel: sd 5:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Oct 26 21:42:17 volumio kernel: sdb: sdb1 Oct 26 21:42:17 volumio kernel: sd 5:0:0:0: [sdb] Attached SCSI removable disk Oct 26 21:42:18 volumio volumio[1043]: info: Mounting Device USB_ROUTER Oct 26 21:42:18 volumio sudo[1991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/USB_ROUTER -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 26 21:42:18 volumio sudo[1991]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 21:42:18 volumio kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Oct 26 21:42:18 volumio kernel: FAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 26 21:42:18 volumio sudo[1991]: pam_unix(sudo:session): session closed for user root Oct 26 21:42:18 volumio volumio[1043]: MPD error: No database Oct 26 21:42:20 volumio volumio[1043]: info: UPDATER: Scheduling automatic update Oct 26 21:42:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime Oct 26 21:42:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime Oct 26 21:42:20 volumio volumio[1043]: info: UPDATER: Auto update will take place at: Mon Oct 27 2025 03:59:05 GMT+0100 (Central European Standard Time) Oct 26 21:42:25 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 21:42:25 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 21:42:25 volumio volumio[1043]: info: Retrieving Cloud Streaming UI Oct 26 21:42:25 volumio volumio[1043]: info: Getting Tidal Cloud Configuration Oct 26 21:42:25 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 21:42:25 volumio volumio[1043]: info: Getting Qobuz Cloud Configuration Oct 26 21:42:25 volumio volumio[1043]: info: Asking plugin for UI Config Oct 26 21:42:25 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 21:42:25 volumio volumio[1043]: info: Getting Spotify Cloud Configuration Oct 26 21:42:25 volumio volumio[1043]: info: Asking plugin for UI Config Oct 26 21:42:25 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 21:42:25 volumio volumio[1043]: info: Saving Spotify Acccount Oct 26 21:42:25 volumio volumio[1043]: info: Got Tidal Cloud Configuration Oct 26 21:42:25 volumio volumio[1043]: info: Got it Oct 26 21:42:25 volumio volumio[1043]: info: Got it Oct 26 21:42:25 volumio volumio[1043]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 26 21:42:25 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 21:42:25 volumio volumio[1043]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 26 21:42:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 26 21:42:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 21:42:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 21:42:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 21:42:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 21:42:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 21:42:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 21:42:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 21:42:25 volumio volumio[1043]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 21:42:25 volumio volumio[1043]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 21:42:25 volumio volumio[1043]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 21:42:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 21:42:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 21:42:26 volumio volumio[1043]: info: Discovery: Getting this device information Oct 26 21:42:26 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:42:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 21:42:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 21:42:26 volumio mpd[1500]: exception: No database Oct 26 21:42:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 26 21:42:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 26 21:42:28 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand rescan Oct 26 21:42:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 21:42:30 volumio mpd[1500]: exception: No database Oct 26 21:42:34 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 21:42:34 volumio mpd[1500]: exception: No database Oct 26 21:42:38 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 21:42:38 volumio mpd[1500]: exception: No database Oct 26 21:42:40 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 26 21:42:40 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand update Oct 26 21:42:46 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 26 21:42:46 volumio volumio[1043]: info: CURURI: music-library Oct 26 21:42:46 volumio mpd[1500]: exception: No database Oct 26 21:42:46 volumio volumio[1043]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Oct 26 21:42:46 volumio volumio[1043]: info: Preload queue cleared Oct 26 21:42:51 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 26 21:42:51 volumio volumio[1043]: info: Preload queue cleared Oct 26 21:42:52 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 26 21:42:52 volumio volumio[1043]: info: Preload queue cleared Oct 26 21:42:54 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 26 21:42:54 volumio volumio[1043]: info: Preload queue cleared Oct 26 21:42:56 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 26 21:42:56 volumio volumio[1043]: info: Preload queue cleared Oct 26 21:42:56 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 21:42:56 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 21:42:56 volumio volumio[1043]: info: Discovery: Getting this device information Oct 26 21:42:56 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Oct 26 21:42:56 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 21:42:57 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 26 21:42:57 volumio volumio[1043]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 21:42:57 volumio volumio[1043]: TypeError: Cannot read property 'length' of undefined Oct 26 21:42:57 volumio volumio[1043]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 26 21:42:57 volumio volumio[1043]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 26 21:42:57 volumio volumio[1043]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 26 21:42:57 volumio volumio[1043]: at Parser.emit (events.js:400:28) Oct 26 21:42:57 volumio volumio[1043]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 26 21:42:57 volumio volumio[1043]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 26 21:42:57 volumio volumio[1043]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 26 21:42:57 volumio volumio[1043]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 26 21:42:57 volumio volumio[1043]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 26 21:42:57 volumio volumio[1043]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 26 21:42:57 volumio volumio[1043]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 26 21:42:57 volumio volumio[1043]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 26 21:42:57 volumio volumio[1043]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 26 21:42:57 volumio volumio[1043]: at IncomingMessage.emit (events.js:412:35) Oct 26 21:42:57 volumio volumio[1043]: at endReadableNT (internal/streams/readable.js:1333:12) Oct 26 21:42:57 volumio volumio[1043]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 26 21:42:57 volumio volumio[1043]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 21:42:58 volumio sudo[2118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-26 21:41 Oct 26 21:42:58 volumio sudo[2118]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"