-- Logs begin at Thu 2019-02-14 13:11:59 MSK, end at Wed 2026-02-25 18:07:17 MSK. -- Feb 25 18:06:43 volumio-rpi-4 systemd-timedated[932]: Changed local time to Wed Feb 25 18:06:43 2026 Feb 25 18:06:43 volumio-rpi-4 sudo[930]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:43 volumio-rpi-4 volumio-time-update[548]: volumio-time-update-util: System time updated successfully. Feb 25 18:06:43 volumio-rpi-4 systemd[1]: Started Volumio Time Update Utility. Feb 25 18:06:43 volumio-rpi-4 ntpd[773]: Listen normally on 3 eth0 192.168.1.103:123 Feb 25 18:06:43 volumio-rpi-4 ntpd[773]: new interface(s) found: waking up resolver Feb 25 18:06:43 volumio-rpi-4 wireless.js[558]: trying... Feb 25 18:06:43 volumio-rpi-4 sudo[934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 18:06:43 volumio-rpi-4 sudo[934]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:06:43 volumio-rpi-4 sudo[934]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:43 volumio-rpi-4 nmbd[753]: [2026/02/25 18:06:43.816685, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 25 18:06:43 volumio-rpi-4 systemd[1]: Started Samba NMB Daemon. Feb 25 18:06:43 volumio-rpi-4 nmbd[753]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 25 18:06:43 volumio-rpi-4 systemd[1]: Starting Samba Winbind Daemon... Feb 25 18:06:44 volumio-rpi-4 dhcpcd[844]: eth0: leased 192.168.1.103 for 25200 seconds Feb 25 18:06:44 volumio-rpi-4 dhcpcd[844]: eth0: adding route to 192.168.1.0/24 Feb 25 18:06:44 volumio-rpi-4 dhcpcd[844]: eth0: adding default route via 192.168.1.1 Feb 25 18:06:44 volumio-rpi-4 dhcpcd[844]: forked to background, child pid 958 Feb 25 18:06:44 volumio-rpi-4 winbindd[937]: [2026/02/25 18:06:44.079898, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 25 18:06:44 volumio-rpi-4 winbindd[937]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 25 18:06:44 volumio-rpi-4 winbindd[937]: [2026/02/25 18:06:44.099792, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 25 18:06:44 volumio-rpi-4 systemd[1]: Started Samba Winbind Daemon. Feb 25 18:06:44 volumio-rpi-4 winbindd[937]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 25 18:06:44 volumio-rpi-4 sudo[843]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:44 volumio-rpi-4 systemd[1]: Starting Samba SMB Daemon... Feb 25 18:06:44 volumio-rpi-4 wireless.js[558]: trying... Feb 25 18:06:44 volumio-rpi-4 sudo[964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 18:06:44 volumio-rpi-4 sudo[964]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:06:44 volumio-rpi-4 sudo[964]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:44 volumio-rpi-4 smbd[962]: [2026/02/25 18:06:44.524904, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 25 18:06:44 volumio-rpi-4 systemd[1]: Started Samba SMB Daemon. Feb 25 18:06:44 volumio-rpi-4 smbd[962]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 25 18:06:44 volumio-rpi-4 volumio-remote-updater[555]: [2026-02-25 18:06:44] [info] asio async_connect error: system:111 (Connection refused) Feb 25 18:06:44 volumio-rpi-4 volumio-remote-updater[555]: [2026-02-25 18:06:44] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 25 18:06:44 volumio-rpi-4 volumio-remote-updater[555]: [2026-02-25 18:06:44] [error] handle_connect error: Underlying Transport Error Feb 25 18:06:45 volumio-rpi-4 wireless.js[558]: trying... Feb 25 18:06:45 volumio-rpi-4 sudo[974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 18:06:45 volumio-rpi-4 sudo[974]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:06:45 volumio-rpi-4 sudo[974]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:46 volumio-rpi-4 wireless.js[558]: trying... Feb 25 18:06:46 volumio-rpi-4 sudo[977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 18:06:46 volumio-rpi-4 sudo[977]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:06:46 volumio-rpi-4 sudo[977]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:47 volumio-rpi-4 wireless.js[558]: trying... Feb 25 18:06:47 volumio-rpi-4 sudo[980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 18:06:47 volumio-rpi-4 sudo[980]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:06:47 volumio-rpi-4 sudo[980]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:48 volumio-rpi-4 wireless.js[558]: trying... Feb 25 18:06:48 volumio-rpi-4 sudo[983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 18:06:48 volumio-rpi-4 sudo[983]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:06:48 volumio-rpi-4 sudo[983]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:49 volumio-rpi-4 wireless.js[558]: trying... Feb 25 18:06:49 volumio-rpi-4 sudo[986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 18:06:49 volumio-rpi-4 sudo[986]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:06:49 volumio-rpi-4 sudo[986]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:50 volumio-rpi-4 wireless.js[558]: trying... Feb 25 18:06:50 volumio-rpi-4 sudo[989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 18:06:50 volumio-rpi-4 sudo[989]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:06:50 volumio-rpi-4 sudo[989]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:51 volumio-rpi-4 wireless.js[558]: trying... Feb 25 18:06:51 volumio-rpi-4 sudo[992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 18:06:51 volumio-rpi-4 sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:06:51 volumio-rpi-4 sudo[992]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:52 volumio-rpi-4 wireless.js[558]: trying... Feb 25 18:06:52 volumio-rpi-4 sudo[995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 18:06:52 volumio-rpi-4 sudo[995]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:06:52 volumio-rpi-4 sudo[995]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:53 volumio-rpi-4 wireless.js[558]: trying... Feb 25 18:06:53 volumio-rpi-4 sudo[998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 18:06:53 volumio-rpi-4 sudo[998]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:06:53 volumio-rpi-4 sudo[998]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:53 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Feb 25 18:06:53 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Feb 25 18:06:53 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Feb 25 18:06:53 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Feb 25 18:06:53 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Feb 25 18:06:53 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Feb 25 18:06:54 volumio-rpi-4 wireless.js[558]: trying... Feb 25 18:06:54 volumio-rpi-4 sudo[1001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 18:06:54 volumio-rpi-4 sudo[1001]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:06:54 volumio-rpi-4 sudo[1001]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:54 volumio-rpi-4 volumio-remote-updater[555]: [2026-02-25 18:06:54] [info] asio async_connect error: system:111 (Connection refused) Feb 25 18:06:54 volumio-rpi-4 volumio-remote-updater[555]: [2026-02-25 18:06:54] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 25 18:06:54 volumio-rpi-4 volumio-remote-updater[555]: [2026-02-25 18:06:54] [error] handle_connect error: Underlying Transport Error Feb 25 18:06:55 volumio-rpi-4 wireless.js[558]: trying... Feb 25 18:06:55 volumio-rpi-4 sudo[1004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 18:06:55 volumio-rpi-4 sudo[1004]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:06:55 volumio-rpi-4 sudo[1004]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:56 volumio-rpi-4 wireless.js[558]: trying... Feb 25 18:06:56 volumio-rpi-4 sudo[1007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 18:06:56 volumio-rpi-4 sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:06:56 volumio-rpi-4 sudo[1007]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:57 volumio-rpi-4 wireless.js[558]: trying... Feb 25 18:06:57 volumio-rpi-4 sudo[1010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 18:06:57 volumio-rpi-4 sudo[1010]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:06:57 volumio-rpi-4 sudo[1010]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:58 volumio-rpi-4 wireless.js[558]: trying... Feb 25 18:06:58 volumio-rpi-4 sudo[1013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 18:06:58 volumio-rpi-4 sudo[1013]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:06:58 volumio-rpi-4 sudo[1013]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:59 volumio-rpi-4 wireless.js[558]: Overtime, starting plan B Feb 25 18:06:59 volumio-rpi-4 systemd[1]: Started Wireless Services. Feb 25 18:06:59 volumio-rpi-4 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 25 18:06:59 volumio-rpi-4 systemd[1]: Started Volumio Backend Module. Feb 25 18:06:59 volumio-rpi-4 systemd[1]: Started Volumio Cpu Tweaker. Feb 25 18:06:59 volumio-rpi-4 systemd[1]: Reached target Multi-User System. Feb 25 18:06:59 volumio-rpi-4 systemd[1]: Reached target Graphical Interface. Feb 25 18:06:59 volumio-rpi-4 systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 25 18:06:59 volumio-rpi-4 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 25 18:06:59 volumio-rpi-4 systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 25 18:06:59 volumio-rpi-4 systemd[1]: Startup finished in 13.168s (kernel) + 31.707s (userspace) = 44.875s. Feb 25 18:06:59 volumio-rpi-4 volumio-cpu-tweak[1019]: Setting RT Priority for mpd Feb 25 18:06:59 volumio-rpi-4 volumio-cpu-tweak[1019]: pid 35's current scheduling policy: SCHED_OTHER Feb 25 18:06:59 volumio-rpi-4 volumio-cpu-tweak[1019]: pid 35's current scheduling priority: 0 Feb 25 18:06:59 volumio-rpi-4 volumio-cpu-tweak[1019]: Setting MPD Affinity Feb 25 18:06:59 volumio-rpi-4 volumio-cpu-tweak[1019]: pid 3's current affinity mask: f Feb 25 18:06:59 volumio-rpi-4 volumio-cpu-tweak[1019]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 25 18:06:59 volumio-rpi-4 volumio-cpu-tweak[1019]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Feb 25 18:06:59 volumio-rpi-4 systemd[1]: volumio_cpu_tweak.service: Succeeded. Feb 25 18:07:00 volumio-rpi-4 systemd[1]: systemd-fsckd.service: Succeeded. Feb 25 18:07:01 volumio-rpi-4 volumio[1018]: info: ------------------------------------------- Feb 25 18:07:01 volumio-rpi-4 volumio[1018]: info: ----- Volumio3 ---- Feb 25 18:07:01 volumio-rpi-4 volumio[1018]: info: ------------------------------------------- Feb 25 18:07:01 volumio-rpi-4 volumio[1018]: info: ----- System startup ---- Feb 25 18:07:01 volumio-rpi-4 volumio[1018]: info: ------------------------------------------- Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: MYVOLUMIO Environment detected Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Plugin folders cleanup Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Scanning into folder /volumio/app/plugins/ Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Scanning category audio_interface Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Scanning category miscellanea Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Scanning category music_service Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Scanning category plugins.json Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Scanning category system_controller Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Scanning category user_interface Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Scanning into folder /data/plugins/ Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Scanning category music_service Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Scanning category system_controller Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Scanning category user_interface Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Plugin folders cleanup completed Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: ------------------------------------------- Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: ----- Core plugins startup ---- Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: ------------------------------------------- Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Loading plugins from folder /volumio/app/plugins/ Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Adding plugin upnp to MyMusic Plugins Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Loading plugins from folder /data/plugins/ Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Loading plugin "system"... Feb 25 18:07:02 volumio-rpi-4 volumio[1018]: info: Loading plugin "appearance"... Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: info: Loading plugin "network"... Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: info: Refreshing Cached IP Addresses Feb 25 18:07:03 volumio-rpi-4 sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 18:07:03 volumio-rpi-4 sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:07:03 volumio-rpi-4 sudo[1050]: pam_unix(sudo:session): session closed for user root Feb 25 18:07:03 volumio-rpi-4 sudo[1052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 18:07:03 volumio-rpi-4 sudo[1052]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:07:03 volumio-rpi-4 sudo[1052]: pam_unix(sudo:session): session closed for user root Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: info: Loading plugin "services"... Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: info: Loading plugin "volumio5onboarding"... Feb 25 18:07:03 volumio-rpi-4 sudo[1061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 25 18:07:03 volumio-rpi-4 sudo[1061]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: info: Loading plugin "alsa_controller"... Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: warn: Unable to locate the audio output device SMSL M9 1.0.1. Please configure a valid output device. Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: Invalid card number. Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer Feb 25 18:07:03 volumio-rpi-4 kernel: usb 1-1.1: new high-speed USB device number 3 using xhci_hcd Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: Invalid card number. Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: info: Loading plugin "wizard"... Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: info: Loading plugin "networkfs"... Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: info: Starting Udev Watcher for removable devices Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: info: Ignoring mount for partition: boot Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: info: Ignoring mount for partition: volumio Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: info: Ignoring mount for partition: volumio_data Feb 25 18:07:03 volumio-rpi-4 kernel: usb 1-1.1: New USB device found, idVendor=20b1, idProduct=0020, bcdDevice= 6.f2 Feb 25 18:07:03 volumio-rpi-4 kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Feb 25 18:07:03 volumio-rpi-4 kernel: usb 1-1.1: Product: SMSL M9 1.0.1 Feb 25 18:07:03 volumio-rpi-4 kernel: usb 1-1.1: Manufacturer: SMSL Feb 25 18:07:03 volumio-rpi-4 volumio[1018]: info: Mounting Device 0D78-1A39 Feb 25 18:07:04 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Feb 25 18:07:04 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Feb 25 18:07:04 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Feb 25 18:07:04 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Feb 25 18:07:04 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Feb 25 18:07:04 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Feb 25 18:07:05 volumio-rpi-4 kernel: usb 1-1.1: 1:3 : unsupported format bits 0x100000000 Feb 25 18:07:05 volumio-rpi-4 kernel: usbcore: registered new interface driver snd-usb-audio Feb 25 18:07:05 volumio-rpi-4 systemd-udevd[1084]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Feb 25 18:07:06 volumio-rpi-4 volumio-remote-updater[555]: [2026-02-25 18:07:06] [connect] Successful connection Feb 25 18:07:06 volumio-rpi-4 wpa_supplicant[857]: wlan0: Failed to initiate sched scan Feb 25 18:07:06 volumio-rpi-4 sudo[1092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/0D78-1A39 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 25 18:07:06 volumio-rpi-4 sudo[1092]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:07:06 volumio-rpi-4 kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Feb 25 18:07:06 volumio-rpi-4 kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Feb 25 18:07:06 volumio-rpi-4 sudo[1092]: pam_unix(sudo:session): session closed for user root Feb 25 18:07:06 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 18:07:06 volumio-rpi-4 volumio[1018]: info: Loading plugin "volumio_command_line_client"... Feb 25 18:07:06 volumio-rpi-4 volumio[1018]: info: Loading plugin "upnp"... Feb 25 18:07:06 volumio-rpi-4 volumio[1018]: info: [1772032026226] Starting Upmpd Daemon Feb 25 18:07:06 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 18:07:06 volumio-rpi-4 volumio[1018]: info: Loading plugin "my_music"... Feb 25 18:07:06 volumio-rpi-4 volumio[1018]: info: Loading plugin "mpd"... Feb 25 18:07:06 volumio-rpi-4 volumio[1018]: info: Loading plugin "upnp_browser"... Feb 25 18:07:06 volumio-rpi-4 volumio[1018]: info: Starting UPNP Browser Feb 25 18:07:06 volumio-rpi-4 volumio[1018]: info: Loading plugin "alarm-clock"... Feb 25 18:07:07 volumio-rpi-4 volumio[1018]: info: Loading plugin "airplay_emulation"... Feb 25 18:07:07 volumio-rpi-4 volumio[1018]: info: Starting Shairport Sync Feb 25 18:07:07 volumio-rpi-4 volumio[1018]: info: Loading plugin "last_100"... Feb 25 18:07:07 volumio-rpi-4 volumio[1018]: info: Loading plugin "webradio"... Feb 25 18:07:07 volumio-rpi-4 volumio[1018]: info: Loading plugin "i2s_dacs"... Feb 25 18:07:07 volumio-rpi-4 volumio[1018]: info: Loading plugin "volumiodiscovery"... Feb 25 18:07:07 volumio-rpi-4 volumio[1018]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 25 18:07:07 volumio-rpi-4 volumio[1018]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 18:07:07 volumio-rpi-4 volumio[1018]: *** WARNING *** For more information see Feb 25 18:07:07 volumio-rpi-4 node[1018]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 25 18:07:07 volumio-rpi-4 volumio[1018]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 25 18:07:07 volumio-rpi-4 volumio[1018]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 18:07:07 volumio-rpi-4 volumio[1018]: *** WARNING *** For more information see Feb 25 18:07:07 volumio-rpi-4 node[1018]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 18:07:07 volumio-rpi-4 node[1018]: *** WARNING *** For more information see Feb 25 18:07:07 volumio-rpi-4 node[1018]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 25 18:07:07 volumio-rpi-4 node[1018]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 18:07:07 volumio-rpi-4 node[1018]: *** WARNING *** For more information see Feb 25 18:07:07 volumio-rpi-4 volumio[1018]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 25 18:07:07 volumio-rpi-4 volumio[1018]: info: Discovery: Started advertising with name: Volumio RPi-4 Feb 25 18:07:07 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 18:07:07 volumio-rpi-4 volumio[1018]: info: Loading plugin "calmradio"... Feb 25 18:07:08 volumio-rpi-4 sudo[1061]: pam_unix(sudo:session): session closed for user root Feb 25 18:07:09 volumio-rpi-4 volumio[1018]: info: Plugin autostart is not enabled Feb 25 18:07:09 volumio-rpi-4 volumio[1018]: info: Loading plugin "now_playing"... Feb 25 18:07:10 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Feb 25 18:07:10 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Feb 25 18:07:10 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Feb 25 18:07:10 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Feb 25 18:07:10 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Feb 25 18:07:10 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Feb 25 18:07:11 volumio-rpi-4 volumio[1018]: info: Loading plugin "peppy_screensaver"... Feb 25 18:07:12 volumio-rpi-4 volumio[1018]: info: Applying required configuration parameters for plugin peppy_screensaver Feb 25 18:07:12 volumio-rpi-4 volumio[1018]: info: Loading plugin "outputs"... Feb 25 18:07:12 volumio-rpi-4 volumio[1018]: info: Loading plugin "albumart"... Feb 25 18:07:12 volumio-rpi-4 volumio[1018]: info: Plugin example_plugin is not enabled Feb 25 18:07:12 volumio-rpi-4 volumio[1018]: info: Loading plugin "inputs"... Feb 25 18:07:12 volumio-rpi-4 volumio[1018]: info: Loading plugin "updater_comm"... Feb 25 18:07:12 volumio-rpi-4 volumio[1018]: info: Plugin mpdemulation is not enabled Feb 25 18:07:12 volumio-rpi-4 volumio[1018]: info: Loading plugin "rest_api"... Feb 25 18:07:12 volumio-rpi-4 volumio[1018]: info: Loading plugin "websocket"... Feb 25 18:07:12 volumio-rpi-4 volumio[1018]: info: Starting Socket.io Server version 2.3.0 Feb 25 18:07:12 volumio-rpi-4 volumio[1018]: info: Loading plugin "radio_paradise"... Feb 25 18:07:12 volumio-rpi-4 volumio[1018]: Forking 3 albumart workers Feb 25 18:07:13 volumio-rpi-4 systemd[1]: systemd-timedated.service: Succeeded. Feb 25 18:07:13 volumio-rpi-4 volumio[1018]: info: Applying required configuration parameters for plugin radio_paradise Feb 25 18:07:13 volumio-rpi-4 volumio[1018]: info: [1772032033542] [RadioParadise] API delay: 5 Feb 25 18:07:13 volumio-rpi-4 volumio[1018]: info: Loading plugin "touch_display"... Feb 25 18:07:13 volumio-rpi-4 volumio[1018]: Starting albumart workers Feb 25 18:07:13 volumio-rpi-4 volumio[1018]: Starting albumart workers Feb 25 18:07:13 volumio-rpi-4 volumio[1018]: Starting albumart workers Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: Applying required configuration parameters for plugin touch_display Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: Loading i18n strings for locale ru Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: Updating browse sources language Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::initPlayerControls Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: Express server listening on port 3000 Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: [Metrics] WebUI: 14s 181.49ms Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::resetVolumioState Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::getcurrentVolume Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::getcurrentVolume Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: info: Volumio Network Manager: Network status updated: 1 Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Feb 25 18:07:14 volumio-rpi-4 volumio[1018]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.39 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.39 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: VolumeController:: Volume=undefined Mute =false Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::pushState Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CorePlayQueue::getTrack 0 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioPushState Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::updateTrackBlock Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CorePlayQueue::getTrackBlock Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: ---------------------------- USB Audio Device Attached Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::Close All Modals sent Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: Preparing to save Alsa Options, stopping services first Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioGetState Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CorePlayQueue::getTrack 0 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioPause Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::pause Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL M9 1.0.1","alsacard":"S101"},"i2s":false} Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: Setting mixer SMSL M9 1.0.1 for card SMSL M9 1.0.1 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL M9 1.0.1 Mixer: SMSL M9 1.0.1 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: Disabling external Volume Control Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: Preparing to generate the ALSA configuration file Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.39 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: MPD error: Connection refused Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: Reloading queue from file Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: Reading ALSA contributions from plugins. Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: VolumeController:: Volume=undefined Mute =false Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::pushState Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CorePlayQueue::getTrack 0 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioPushState Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::updateTrackBlock Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CorePlayQueue::getTrackBlock Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::setRepeat false single undefined Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::pushState Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CorePlayQueue::getTrack 0 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioPushState Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::setRandom null Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::pushState Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CorePlayQueue::getTrack 0 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioPushState Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: Setting Device type: Raspberry PI Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: VolumeController:: Volume=100 Mute =false Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::pushState Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CorePlayQueue::getTrack 0 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioPushState Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioGetVisibleSources Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioGetState Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CorePlayQueue::getTrack 0 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: Received Get System Info Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: Discovery: Getting this device information Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioGetState Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CorePlayQueue::getTrack 0 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioGetState Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CorePlayQueue::getTrack 0 Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: Listing playlists Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioGetQueue Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::getQueue Feb 25 18:07:15 volumio-rpi-4 volumio[1018]: info: CorePlayQueue::getQueue Feb 25 18:07:16 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Feb 25 18:07:16 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Feb 25 18:07:16 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Feb 25 18:07:16 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: VolumeController:: Volume=undefined Mute =false Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::pushState Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CorePlayQueue::getTrack 0 Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioPushState Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Feb 25 18:07:16 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 25 18:07:16 volumio-rpi-4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Feb 25 18:07:16 volumio-rpi-4 sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 18:07:16 volumio-rpi-4 sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:07:16 volumio-rpi-4 sudo[1204]: pam_unix(sudo:session): session closed for user root Feb 25 18:07:16 volumio-rpi-4 sudo[1206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 18:07:16 volumio-rpi-4 sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:07:16 volumio-rpi-4 sudo[1206]: pam_unix(sudo:session): session closed for user root Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: VolumeController:: Volume=100 Mute =false Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::pushState Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CorePlayQueue::getTrack 0 Feb 25 18:07:16 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioPushState Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: info: VolumeController::SetAlsaVolume0 Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: info: Preload queue cleared Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::ClearQueue Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::stop Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: info: CorePlayQueue::clearPlayQueue Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: info: CorePlayQueue::saveQueue Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: info: CoreCommandRouter::volumioPushQueue Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: info: CoreStateMachine::addQueueItems Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: info: CorePlayQueue::addQueueItems Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: info: Preload queue cleared Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: info: Adding Item to queue: music-library/USB/0D78-1A39/Fausto Mesolella 2014 - Live ad Alcatraz [SACD]/01_LIVE-AD-ALCATRAZ_Fone_SACD136_2ch.dsf Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: info: Exploding uri music-library/USB/0D78-1A39/Fausto Mesolella 2014 - Live ad Alcatraz [SACD]/01_LIVE-AD-ALCATRAZ_Fone_SACD136_2ch.dsf in service mpd Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: TypeError: Cannot read property 'then' of undefined Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2541:19) Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2339:21) Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:251:25) Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:511:25) Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: at Socket.emit (events.js:315:20) Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 25 18:07:17 volumio-rpi-4 volumio[1018]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 18:07:17 volumio-rpi-4 sudo[1219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-25 18:06 Feb 25 18:07:17 volumio-rpi-4 sudo[1219]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 18:07:17 volumio-rpi-4 wpa_supplicant[857]: wlan0: Failed to initiate sched scan PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"