-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Tue 2025-05-13 16:28:30 CEST. -- May 13 16:28:05 volumio systemd-timedated[1266]: Changed local time to Tue May 13 16:28:05 2025 May 13 16:28:05 volumio sudo[1260]: pam_unix(sudo:session): session closed for user root May 13 16:28:05 volumio systemd[1]: Starting Daily man-db regeneration... May 13 16:28:05 volumio systemd[1]: Starting Daily apt download activities... May 13 16:28:05 volumio volumio-time-update[589]: volumio-time-update-util: System time updated successfully. May 13 16:28:05 volumio systemd[1]: Started Volumio Time Update Utility. May 13 16:28:05 volumio systemd[1]: man-db.service: Succeeded. May 13 16:28:05 volumio systemd[1]: Started Daily man-db regeneration. May 13 16:28:05 volumio wireless.js[594]: trying... May 13 16:28:05 volumio sudo[1305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 13 16:28:05 volumio sudo[1305]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:05 volumio sudo[1305]: pam_unix(sudo:session): session closed for user root May 13 16:28:06 volumio systemd[1]: apt-daily.service: Succeeded. May 13 16:28:06 volumio systemd[1]: Started Daily apt download activities. May 13 16:28:06 volumio systemd[1]: Starting Daily apt upgrade and clean activities... May 13 16:28:06 volumio wireless.js[594]: trying... May 13 16:28:06 volumio sudo[1364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 13 16:28:06 volumio sudo[1364]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:06 volumio sudo[1364]: pam_unix(sudo:session): session closed for user root May 13 16:28:07 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. May 13 16:28:07 volumio systemd[1]: Started Daily apt upgrade and clean activities. May 13 16:28:07 volumio wireless.js[594]: trying... May 13 16:28:08 volumio sudo[1403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 13 16:28:08 volumio sudo[1403]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:08 volumio sudo[1403]: pam_unix(sudo:session): session closed for user root May 13 16:28:08 volumio wireless.js[594]: trying... May 13 16:28:08 volumio sudo[1413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 13 16:28:09 volumio sudo[1413]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:09 volumio sudo[1413]: pam_unix(sudo:session): session closed for user root May 13 16:28:09 volumio wireless.js[594]: trying... May 13 16:28:09 volumio sudo[1423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 13 16:28:10 volumio sudo[1423]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:10 volumio sudo[1423]: pam_unix(sudo:session): session closed for user root May 13 16:28:10 volumio wireless.js[594]: trying... May 13 16:28:10 volumio sudo[1433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 13 16:28:11 volumio sudo[1433]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:11 volumio sudo[1433]: pam_unix(sudo:session): session closed for user root May 13 16:28:11 volumio wpa_supplicant[992]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="FRITZ!Box 7590 LE" May 13 16:28:11 volumio wpa_supplicant[992]: wlan0: Trying to associate with d4:24:dd:0e:5f:47 (SSID='FRITZ!Box 7590 LE' freq=2412 MHz) May 13 16:28:11 volumio wpa_supplicant[992]: wlan0: Associated with d4:24:dd:0e:5f:47 May 13 16:28:11 volumio wpa_supplicant[992]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 13 16:28:11 volumio wpa_supplicant[992]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE May 13 16:28:11 volumio kernel: cfg80211: Regulatory domain changed to country: DE May 13 16:28:11 volumio kernel: cfg80211: DFS Master region: ETSI May 13 16:28:11 volumio kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) May 13 16:28:11 volumio kernel: cfg80211: (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) May 13 16:28:11 volumio kernel: cfg80211: (5150000 KHz - 5250000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (N/A) May 13 16:28:11 volumio kernel: cfg80211: (5250000 KHz - 5350000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (0 s) May 13 16:28:11 volumio kernel: cfg80211: (5470000 KHz - 5725000 KHz @ 160000 KHz), (N/A, 2698 mBm), (0 s) May 13 16:28:11 volumio kernel: cfg80211: (5725000 KHz - 5875000 KHz @ 80000 KHz), (N/A, 1397 mBm), (N/A) May 13 16:28:11 volumio kernel: cfg80211: (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A) May 13 16:28:11 volumio wireless.js[594]: trying... May 13 16:28:11 volumio sudo[1445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 13 16:28:11 volumio sudo[1445]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:11 volumio sudo[1445]: pam_unix(sudo:session): session closed for user root May 13 16:28:12 volumio wireless.js[594]: trying... May 13 16:28:13 volumio sudo[1455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 13 16:28:13 volumio sudo[1455]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:13 volumio sudo[1455]: pam_unix(sudo:session): session closed for user root May 13 16:28:13 volumio volumio-remote-updater[605]: [2025-05-13 16:28:13] [info] asio async_connect error: system:111 (Connection refused) May 13 16:28:13 volumio volumio-remote-updater[605]: [2025-05-13 16:28:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 13 16:28:13 volumio volumio-remote-updater[605]: [2025-05-13 16:28:13] [error] handle_connect error: Underlying Transport Error May 13 16:28:13 volumio wireless.js[594]: Overtime, starting plan B May 13 16:28:14 volumio iwconfig[1466]: Error for wireless request "Set Power Management" (8B2C) : May 13 16:28:14 volumio iwconfig[1466]: SET failed on device wlan0 ; Operation not permitted. May 13 16:28:14 volumio systemd[1]: Started Wireless Services. May 13 16:28:14 volumio systemd[1]: Started Volumio Backend Module. May 13 16:28:14 volumio systemd[1]: Started Start Volumio Kiosk. May 13 16:28:14 volumio systemd[1]: Started Volumio Cpu Tweaker. May 13 16:28:14 volumio systemd[1]: Reached target Multi-User System. May 13 16:28:14 volumio systemd[1]: Reached target Graphical Interface. May 13 16:28:14 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 13 16:28:14 volumio startkiosk.sh[1468]: Starting Volumio Kiosk with arguments for cursor display May 13 16:28:14 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 13 16:28:14 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 13 16:28:14 volumio systemd[1]: Startup finished in 15.657s (kernel) + 27.045s (userspace) = 42.703s. May 13 16:28:14 volumio volumio-cpu-tweak[1469]: Setting RT Priority for mpd May 13 16:28:14 volumio volumio-cpu-tweak[1469]: Setting MPD Affinity May 13 16:28:14 volumio volumio-cpu-tweak[1469]: pid 872's current affinity mask: f May 13 16:28:14 volumio volumio-cpu-tweak[1469]: pid 872's new affinity mask: 3 May 13 16:28:14 volumio volumio-cpu-tweak[1469]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 13 16:28:14 volumio volumio-cpu-tweak[1469]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 13 16:28:14 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. May 13 16:28:14 volumio startkiosk.sh[1468]: X.Org X Server 1.20.4 May 13 16:28:14 volumio startkiosk.sh[1468]: X Protocol Version 11, Revision 0 May 13 16:28:14 volumio startkiosk.sh[1468]: Build Operating System: Linux 4.19.0-18-armmp-lpae armv7l Debian May 13 16:28:14 volumio startkiosk.sh[1468]: Current Operating System: Linux volumio 4.4.71+ #23 SMP Sun Sep 20 12:23:26 CEST 2020 armv7l May 13 16:28:14 volumio startkiosk.sh[1468]: Kernel command line: earlyprintk quiet loglevel=0 splash plymouth.ignore-serial-consoles initramfs.clear console=tty1 console=ttyS3,115200n8 rw init=/sbin/init imgpart=UUID=e78aac5d-11ed-4be7-9acd-84b7c2dd1454 imgfile=/volumio_current.sqsh bootpart=UUID=6887-FC6C datapart=UUID=59827d2e-5652-41d6-87c5-d67aec9fc23a uuidconfig=/extlinux/extlinux.conf vt.global_cursor_default=0 bootdelay=5 uboot_version=2017.07-g879646d1d6 root=/dev/mmcblk1p2 May 13 16:28:14 volumio startkiosk.sh[1468]: Build Date: 18 December 2021 09:05:36AM May 13 16:28:14 volumio startkiosk.sh[1468]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) May 13 16:28:14 volumio startkiosk.sh[1468]: Current version of pixman: 0.36.0 May 13 16:28:14 volumio startkiosk.sh[1468]: Before reporting problems, check http://wiki.x.org May 13 16:28:14 volumio startkiosk.sh[1468]: to make sure that you have the latest version. May 13 16:28:14 volumio startkiosk.sh[1468]: Markers: (--) probed, (**) from config file, (==) default setting, May 13 16:28:14 volumio startkiosk.sh[1468]: (++) from command line, (!!) notice, (II) informational, May 13 16:28:14 volumio startkiosk.sh[1468]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. May 13 16:28:14 volumio startkiosk.sh[1468]: (==) Log file: "/var/log/Xorg.0.log", Time: Tue May 13 16:28:14 2025 May 13 16:28:14 volumio startkiosk.sh[1468]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" May 13 16:28:14 volumio startkiosk.sh[1468]: gbm: failed to open any driver (search paths /usr/lib/arm-linux-gnueabihf/dri:\$${ORIGIN}/dri:/usr/lib/dri) May 13 16:28:14 volumio startkiosk.sh[1468]: gbm: Last dlopen error: /usr/lib/dri/rockchip_dri.so: cannot open shared object file: No such file or directory May 13 16:28:14 volumio startkiosk.sh[1468]: failed to load driver: rockchip May 13 16:28:15 volumio startkiosk.sh[1468]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. May 13 16:28:15 volumio wpa_supplicant[992]: wlan0: CTRL-EVENT-DISCONNECTED bssid=d4:24:dd:0e:5f:47 reason=14 May 13 16:28:15 volumio wpa_supplicant[992]: wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect May 13 16:28:15 volumio wpa_supplicant[992]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="FRITZ!Box 7590 LE" auth_failures=2 duration=23 reason=WRONG_KEY May 13 16:28:15 volumio wpa_supplicant[992]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory May 13 16:28:15 volumio wpa_supplicant[992]: nl80211: Failed to set IPv4 unicast in multicast filter May 13 16:28:15 volumio wpa_supplicant[992]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 13 16:28:15 volumio kernel: cfg80211: World regulatory domain updated: May 13 16:28:15 volumio kernel: cfg80211: DFS Master region: unset May 13 16:28:15 volumio kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) May 13 16:28:15 volumio kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) May 13 16:28:15 volumio kernel: cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz, 92000 KHz AUTO), (N/A, 2000 mBm), (N/A) May 13 16:28:15 volumio kernel: cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A) May 13 16:28:15 volumio kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A) May 13 16:28:15 volumio kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s) May 13 16:28:15 volumio kernel: cfg80211: (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s) May 13 16:28:15 volumio kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A) May 13 16:28:15 volumio kernel: cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A) May 13 16:28:15 volumio wpa_supplicant[992]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE May 13 16:28:15 volumio kernel: cfg80211: Regulatory domain changed to country: DE May 13 16:28:15 volumio kernel: cfg80211: DFS Master region: ETSI May 13 16:28:15 volumio kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) May 13 16:28:15 volumio kernel: cfg80211: (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) May 13 16:28:15 volumio kernel: cfg80211: (5150000 KHz - 5250000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (N/A) May 13 16:28:15 volumio kernel: cfg80211: (5250000 KHz - 5350000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (0 s) May 13 16:28:15 volumio kernel: cfg80211: (5470000 KHz - 5725000 KHz @ 160000 KHz), (N/A, 2698 mBm), (0 s) May 13 16:28:15 volumio kernel: cfg80211: (5725000 KHz - 5875000 KHz @ 80000 KHz), (N/A, 1397 mBm), (N/A) May 13 16:28:15 volumio kernel: cfg80211: (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A) May 13 16:28:15 volumio volumio[1467]: info: ------------------------------------------- May 13 16:28:15 volumio volumio[1467]: info: ----- Volumio3 ---- May 13 16:28:15 volumio volumio[1467]: info: ------------------------------------------- May 13 16:28:15 volumio volumio[1467]: info: ----- System startup ---- May 13 16:28:15 volumio volumio[1467]: info: ------------------------------------------- May 13 16:28:16 volumio volumio[1467]: info: MYVOLUMIO Environment detected May 13 16:28:16 volumio volumio[1467]: info: Plugin folders cleanup May 13 16:28:16 volumio volumio[1467]: info: Scanning into folder /volumio/app/plugins/ May 13 16:28:16 volumio volumio[1467]: info: Scanning category audio_interface May 13 16:28:16 volumio volumio[1467]: info: Scanning category miscellanea May 13 16:28:16 volumio volumio[1467]: info: Scanning category music_service May 13 16:28:16 volumio volumio[1467]: info: Scanning category plugins.json May 13 16:28:16 volumio volumio[1467]: info: Scanning category system_controller May 13 16:28:16 volumio volumio[1467]: info: Scanning category user_interface May 13 16:28:16 volumio volumio[1467]: info: Scanning into folder /data/plugins/ May 13 16:28:16 volumio volumio[1467]: info: Scanning category audio_interface May 13 16:28:16 volumio volumio[1467]: info: Scanning category music_service May 13 16:28:16 volumio volumio[1467]: info: Plugin folders cleanup completed May 13 16:28:16 volumio volumio[1467]: info: ------------------------------------------- May 13 16:28:16 volumio volumio[1467]: info: ----- Core plugins startup ---- May 13 16:28:16 volumio volumio[1467]: info: ------------------------------------------- May 13 16:28:16 volumio volumio[1467]: info: Loading plugins from folder /volumio/app/plugins/ May 13 16:28:16 volumio volumio[1467]: info: Adding plugin upnp to MyMusic Plugins May 13 16:28:16 volumio volumio[1467]: info: Adding plugin airplay_emulation to MyMusic Plugins May 13 16:28:16 volumio volumio[1467]: info: Adding plugin upnp_browser to MyMusic Plugins May 13 16:28:16 volumio volumio[1467]: info: Loading plugins from folder /data/plugins/ May 13 16:28:16 volumio volumio[1467]: info: Loading plugin "system"... May 13 16:28:16 volumio volumio[1467]: info: Loading plugin "appearance"... May 13 16:28:17 volumio volumio[1467]: info: Loading plugin "network"... May 13 16:28:17 volumio volumio[1467]: info: Refreshing Cached IP Addresses May 13 16:28:17 volumio volumio[1467]: info: Loading plugin "services"... May 13 16:28:17 volumio sudo[1585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 13 16:28:17 volumio volumio[1467]: info: Loading plugin "alsa_controller"... May 13 16:28:17 volumio sudo[1587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 13 16:28:17 volumio sudo[1585]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:17 volumio sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 13 16:28:17 volumio sudo[1587]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:17 volumio sudo[1585]: pam_unix(sudo:session): session closed for user root May 13 16:28:17 volumio sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:17 volumio sudo[1587]: pam_unix(sudo:session): session closed for user root May 13 16:28:17 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 13 16:28:17 volumio volumio[1467]: info: Loading plugin "wizard"... May 13 16:28:17 volumio volumio[1467]: info: Loading plugin "networkfs"... May 13 16:28:17 volumio volumio[1467]: info: Starting Udev Watcher for removable devices May 13 16:28:17 volumio sudo[1635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //smb://macstudonjurgen.fritz.box/ /mnt/NAS/BigMaster May 13 16:28:17 volumio sudo[1635]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:17 volumio sudo[1635]: pam_unix(sudo:session): session closed for user root May 13 16:28:17 volumio volumio[1467]: info: Ignoring mount for partition: BOOT May 13 16:28:17 volumio volumio[1467]: info: Ignoring mount for partition: volumio May 13 16:28:17 volumio volumio[1467]: info: Ignoring mount for partition: volumio_data May 13 16:28:17 volumio volumio[1467]: info: Mounting Device EFI May 13 16:28:17 volumio sudo[1654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/EFI -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 13 16:28:18 volumio sudo[1654]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:18 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! May 13 16:28:18 volumio sudo[1654]: pam_unix(sudo:session): session closed for user root May 13 16:28:18 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. May 13 16:28:18 volumio volumio[1467]: info: Mounting Device Music_C May 13 16:28:18 volumio sudo[1667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/Music_C -o noatime May 13 16:28:18 volumio sudo[1667]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:18 volumio mount.exfat[1673]: volume was not unmounted cleanly May 13 16:28:18 volumio sudo[1667]: pam_unix(sudo:session): session closed for user root May 13 16:28:18 volumio volumio[1467]: WARN: volume was not unmounted cleanly. May 13 16:28:18 volumio volumio[1467]: error: Cannot associate FS Label, not mounting May 13 16:28:18 volumio volumio[1467]: info: Mounting Device Music-Copy May 13 16:28:18 volumio sudo[1682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/Music-Copy -o noatime May 13 16:28:18 volumio sudo[1682]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:18 volumio mount.exfat[1688]: volume was not unmounted cleanly May 13 16:28:19 volumio sudo[1682]: pam_unix(sudo:session): session closed for user root May 13 16:28:19 volumio volumio[1467]: WARN: volume was not unmounted cleanly. May 13 16:28:19 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 13 16:28:19 volumio volumio[1467]: info: Loading plugin "volumio_command_line_client"... May 13 16:28:19 volumio volumio[1467]: info: Loading plugin "upnp"... May 13 16:28:19 volumio volumio[1467]: info: [1747146499035] Starting Upmpd Daemon May 13 16:28:19 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 13 16:28:19 volumio volumio[1467]: info: Loading plugin "my_music"... May 13 16:28:19 volumio systemd[1]: systemd-fsckd.service: Succeeded. May 13 16:28:19 volumio volumio[1467]: info: Loading plugin "mpd"... May 13 16:28:19 volumio sudo[1595]: pam_unix(sudo:session): session closed for user root May 13 16:28:19 volumio volumio[1467]: info: Loading plugin "upnp_browser"... May 13 16:28:19 volumio volumio[1467]: info: Loading plugin "alarm-clock"... May 13 16:28:19 volumio volumio[1467]: info: Loading plugin "airplay_emulation"... May 13 16:28:19 volumio volumio[1467]: info: Starting Shairport Sync May 13 16:28:19 volumio volumio[1467]: info: Loading plugin "last_100"... May 13 16:28:19 volumio volumio[1467]: info: Loading plugin "webradio"... May 13 16:28:19 volumio volumio[1467]: info: Loading plugin "i2s_dacs"... May 13 16:28:19 volumio volumio[1467]: info: I2S DAC not set, start Auto-detection May 13 16:28:19 volumio volumio[1467]: info: Loading plugin "volumiodiscovery"... May 13 16:28:19 volumio volumio[1467]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 13 16:28:19 volumio node[1467]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 13 16:28:19 volumio volumio[1467]: *** WARNING *** Please fix your application to use the native API of Avahi! May 13 16:28:19 volumio node[1467]: *** WARNING *** Please fix your application to use the native API of Avahi! May 13 16:28:19 volumio volumio[1467]: *** WARNING *** For more information see May 13 16:28:19 volumio node[1467]: *** WARNING *** For more information see May 13 16:28:19 volumio volumio[1467]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 13 16:28:19 volumio node[1467]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 13 16:28:19 volumio volumio[1467]: *** WARNING *** Please fix your application to use the native API of Avahi! May 13 16:28:19 volumio node[1467]: *** WARNING *** Please fix your application to use the native API of Avahi! May 13 16:28:19 volumio volumio[1467]: *** WARNING *** For more information see May 13 16:28:19 volumio node[1467]: *** WARNING *** For more information see May 13 16:28:19 volumio volumio[1467]: info: Applying required configuration parameters for plugin volumiodiscovery May 13 16:28:19 volumio volumio[1467]: info: Discovery: Started advertising with name: Volumio May 13 16:28:19 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 13 16:28:19 volumio volumio[1467]: info: Loading plugin "outputs"... May 13 16:28:19 volumio volumio[1467]: info: Loading plugin "albumart"... May 13 16:28:19 volumio volumio[1467]: info: Plugin example_plugin is not enabled May 13 16:28:19 volumio volumio[1467]: info: Loading plugin "inputs"... May 13 16:28:19 volumio volumio[1467]: info: Loading plugin "updater_comm"... May 13 16:28:20 volumio volumio[1467]: info: Plugin mpdemulation is not enabled May 13 16:28:20 volumio volumio[1467]: info: Loading plugin "rest_api"... May 13 16:28:20 volumio volumio[1467]: info: Loading plugin "websocket"... May 13 16:28:20 volumio volumio[1467]: info: Starting Socket.io Server version 2.3.0 May 13 16:28:20 volumio volumio[1467]: info: Loading plugin "fusiondsp"... May 13 16:28:20 volumio volumio[1467]: Forking 3 albumart workers May 13 16:28:20 volumio volumio[1467]: info: Applying required configuration parameters for plugin fusiondsp May 13 16:28:20 volumio volumio[1467]: info: Loading plugin "lms"... May 13 16:28:20 volumio volumio[1467]: Starting albumart workers May 13 16:28:20 volumio volumio[1467]: Starting albumart workers May 13 16:28:21 volumio systemd[1]: systemd-hostnamed.service: Succeeded. May 13 16:28:21 volumio volumio[1467]: info: Loading i18n strings for locale de May 13 16:28:21 volumio volumio[1467]: Updating browse sources language May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 16:28:21 volumio volumio[1467]: Starting albumart workers May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::initPlayerControls May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 16:28:21 volumio volumio[1467]: Express server listening on port 3000 May 13 16:28:21 volumio volumio[1467]: [Metrics] WebUI: 6s 219.41ms May 13 16:28:21 volumio volumio[1467]: info: CoreStateMachine::resetVolumioState May 13 16:28:21 volumio volumio[1467]: info: CoreStateMachine::getcurrentVolume May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::volumioRetrievevolume May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 16:28:21 volumio volumio[1467]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 13 16:28:21 volumio volumio[1467]: info: Completed loading Core Plugins May 13 16:28:21 volumio volumio[1467]: info: Preparing to generate the ALSA configuration file May 13 16:28:21 volumio volumio[1467]: info: Volumio Network Manager: Network status updated: 1 May 13 16:28:21 volumio volumio[1467]: info: Cannot mount NAS BigMaster at system boot, trial number 1 ,retrying in 5 seconds May 13 16:28:21 volumio volumio[1467]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 13 16:28:21 volumio volumio[1467]: info: Reading ALSA contributions from plugins. May 13 16:28:21 volumio volumio[1467]: verbose: New Socket.io Connection to 192.168.178.21 from 192.168.178.93 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 May 13 16:28:21 volumio volumio[1467]: verbose: New Socket.io Connection to 192.168.178.21 from 192.168.178.93 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 May 13 16:28:21 volumio volumio[1467]: info: Scanning new location : "USB/Music_C" May 13 16:28:21 volumio volumio[1467]: info: Reloading queue from file May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 13 16:28:21 volumio volumio[1467]: info: Setting Device type: Tinkerboard May 13 16:28:21 volumio volumio[1467]: info: CoreStateMachine::setRepeat true single undefined May 13 16:28:21 volumio volumio[1467]: info: CoreStateMachine::pushState May 13 16:28:21 volumio volumio[1467]: info: CorePlayQueue::getTrack 0 May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::volumioPushState May 13 16:28:21 volumio volumio[1467]: info: CoreStateMachine::setRandom false May 13 16:28:21 volumio volumio[1467]: info: CoreStateMachine::pushState May 13 16:28:21 volumio volumio[1467]: info: CorePlayQueue::getTrack 0 May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::volumioPushState May 13 16:28:21 volumio volumio[1467]: info: VolumeController:: Volume=100 Mute =false May 13 16:28:21 volumio volumio[1467]: info: CoreStateMachine::pushState May 13 16:28:21 volumio volumio[1467]: info: CorePlayQueue::getTrack 0 May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::volumioPushState May 13 16:28:21 volumio volumio[1467]: info: CoreStateMachine::updateTrackBlock May 13 16:28:21 volumio volumio[1467]: info: CorePlayQueue::getTrackBlock May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::volumioRetrievevolume May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::volumioGetState May 13 16:28:21 volumio volumio[1467]: info: CorePlayQueue::getTrack 0 May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 13 16:28:21 volumio volumio[1467]: info: Listing playlists May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::volumioGetVisibleSources May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 16:28:21 volumio volumio[1467]: info: CoreCommandRouter::volumioGetQueue May 13 16:28:21 volumio volumio[1467]: info: CoreStateMachine::getQueue May 13 16:28:21 volumio volumio[1467]: info: CorePlayQueue::getQueue May 13 16:28:22 volumio volumio[1467]: info: VolumeController:: Volume=100 Mute =false May 13 16:28:22 volumio volumio[1467]: info: CoreStateMachine::pushState May 13 16:28:22 volumio volumio[1467]: info: CorePlayQueue::getTrack 0 May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::volumioPushState May 13 16:28:22 volumio volumio[1467]: info: Discovery: adding fe27e5b1-c736-452a-8156-9fea9a0f6e6f May 13 16:28:22 volumio volumio[1467]: info: Discovery: Found device Volumio May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::volumioGetState May 13 16:28:22 volumio volumio[1467]: info: CorePlayQueue::getTrack 0 May 13 16:28:22 volumio volumio[1467]: info: Asound.conf file written May 13 16:28:22 volumio sudo[1795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 13 16:28:22 volumio sudo[1795]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:22 volumio sudo[1795]: pam_unix(sudo:session): session closed for user root May 13 16:28:22 volumio volumio[1467]: info: Output device has changed, restarting MPD May 13 16:28:22 volumio volumio[1467]: info: Output device has changed, restarting Shairport Sync May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 16:28:22 volumio sudo[1811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 13 16:28:22 volumio sudo[1811]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:22 volumio sudo[1814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 13 16:28:22 volumio sudo[1811]: pam_unix(sudo:session): session closed for user root May 13 16:28:22 volumio sudo[1814]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:22 volumio volumio[1467]: info: ___________ START PLUGINS ___________ May 13 16:28:22 volumio systemd[1]: Stopping Music Player Daemon... May 13 16:28:22 volumio volumio[1467]: info: ControllerMpd::onStart: Initializing MPD May 13 16:28:22 volumio volumio[1467]: info: Creating MPD Configuration file May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 13 16:28:22 volumio volumio[1467]: info: [1747146502360] CoreMusicLibrary::Adding element Medienserver May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 16:28:22 volumio sudo[1833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 13 16:28:22 volumio sudo[1832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 16:28:22 volumio sudo[1833]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:22 volumio sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 16:28:22 volumio sudo[1832]: pam_unix(sudo:session): session closed for user root May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 13 16:28:22 volumio volumio[1467]: info: [1747146502468] CoreMusicLibrary::Adding element Last_100 May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 13 16:28:22 volumio volumio[1467]: info: [1747146502470] CoreMusicLibrary::Adding element Webradio May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 13 16:28:22 volumio volumio[1467]: info: Initializing BBC Radios May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: Loading i18n strings for locale de May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: FusionDsp - mixtype--------------------- Hardware May 13 16:28:22 volumio volumio[1467]: info: Volumio Calling Home May 13 16:28:22 volumio sudo[1864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver May 13 16:28:22 volumio sudo[1864]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:22 volumio volumio[1467]: info: Preparing to generate the ALSA configuration file May 13 16:28:22 volumio systemd[1]: Stopping Logitech Media Server Daemon... May 13 16:28:22 volumio squeezeboxserver[810]: [25-04-30 03:09:18.9488] main::init (390) Starting Logitech Media Server (v8.2.0, 1627922070, Tue Aug 3 11:39:20 CEST 2021) perl 5.028001 - arm-linux-gnueabihf-thread-multi-64int May 13 16:28:22 volumio squeezeboxserver[810]: [25-04-30 03:09:19.4061] Slim::Utils::IPDetect::_init (138) Warning: Couldn't call connect() - falling back to 127.0.0.1 May 13 16:28:22 volumio squeezeboxserver[810]: [25-04-30 03:09:27.4457] Slim::Formats::XML::gotErrorViaHTTP (343) Error: getting https://www.mysqueezebox.com/api/sounds/v1/opml May 13 16:28:22 volumio squeezeboxserver[810]: Couldn't resolve IP address for: www.mysqueezebox.com May 13 16:28:22 volumio squeezeboxserver[810]: [25-04-30 03:09:27.4466] Slim::Plugin::Sounds::Plugin::_gotSoundsError (82) Error: Unable to cache Sounds & Effects menu from SN: Couldn't resolve IP address for: www.mysqueezebox.com May 13 16:28:22 volumio squeezeboxserver[810]: [25-04-30 03:09:29.4418] Slim::Networking::SqueezeNetwork::_error (431) Unable to login to SN: Couldn't resolve IP address for: www.mysqueezebox.com May 13 16:28:22 volumio squeezeboxserver[810]: [25-04-30 03:09:29.4427] Slim::Networking::SqueezeNetwork::_init_error (156) Unable to login to mysqueezebox.com, sync is disabled: Couldn't resolve IP address for: www.mysqueezebox.com (https://www.mysqueezebox.com) May 13 16:28:22 volumio squeezeboxserver[810]: [25-04-30 03:09:29.4437] Slim::Networking::SqueezeNetwork::_init_error (172) mysqueezebox.com sync init failed: Couldn't resolve IP address for: www.mysqueezebox.com, will retry in 241200 (https://www.mysqueezebox.com) May 13 16:28:22 volumio systemd[1]: logitechmediaserver.service: Succeeded. May 13 16:28:22 volumio systemd[1]: Stopped Logitech Media Server Daemon. May 13 16:28:22 volumio volumio[1467]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 13 16:28:22 volumio volumio[1467]: info: Reading ALSA contributions from plugins. May 13 16:28:22 volumio systemd[1]: Starting Logitech Media Server Daemon... May 13 16:28:22 volumio volumio[1467]: info: Volumio called home May 13 16:28:22 volumio volumio[1467]: info: MPD Permissions set May 13 16:28:22 volumio volumio[1467]: info: MPD Permissions set May 13 16:28:22 volumio volumio[1467]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio systemd[1]: Started Logitech Media Server Daemon. May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 13 16:28:22 volumio sudo[1864]: pam_unix(sudo:session): session closed for user root May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 13 16:28:22 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:22 volumio volumio[1467]: info: logitechmediaserver started May 13 16:28:23 volumio volumio[1467]: info: Starting Shairport Sync May 13 16:28:23 volumio volumio[1467]: info: Starting Shairport Sync May 13 16:28:23 volumio volumio[1467]: info: CoreCommandRouter::volumioGetState May 13 16:28:23 volumio volumio[1467]: info: CorePlayQueue::getTrack 0 May 13 16:28:23 volumio volumio-remote-updater[605]: [2025-05-13 16:28:23] [connect] Successful connection May 13 16:28:23 volumio volumio[1467]: info: Starting Shairport Sync May 13 16:28:23 volumio sudo[1906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 13 16:28:23 volumio sudo[1910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 13 16:28:23 volumio volumio[1467]: info: Asound.conf file unchanged, so no further update is needed May 13 16:28:23 volumio volumio[1467]: info: Output device has changed, restarting MPD May 13 16:28:23 volumio sudo[1906]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:23 volumio sudo[1924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 13 16:28:23 volumio volumio[1467]: info: Output device has changed, restarting Shairport Sync May 13 16:28:23 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:23 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 16:28:23 volumio sudo[1910]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:23 volumio sudo[1924]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 13 16:28:23 volumio systemd[1]: shairport-sync.service: Succeeded. May 13 16:28:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 13 16:28:23 volumio sudo[1935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 13 16:28:23 volumio volumio-remote-updater[605]: [2025-05-13 16:28:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747146503 101 May 13 16:28:23 volumio volumio[1467]: 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: 4 May 13 16:28:23 volumio sudo[1930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 13 16:28:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 13 16:28:23 volumio sudo[1924]: pam_unix(sudo:session): session closed for user root May 13 16:28:23 volumio sudo[1906]: pam_unix(sudo:session): session closed for user root May 13 16:28:23 volumio sudo[1910]: pam_unix(sudo:session): session closed for user root May 13 16:28:23 volumio sudo[1930]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:23 volumio sudo[1935]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:23 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:23 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:23 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:23 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:23 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:23 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:23 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:23 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:23 volumio sudo[1930]: pam_unix(sudo:session): session closed for user root May 13 16:28:23 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 16:28:23 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 13 16:28:23 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:23 volumio volumio[1467]: info: Shairport-Sync Started May 13 16:28:23 volumio volumio[1467]: Error adding Membership: Error: addMembership EINVAL May 13 16:28:23 volumio volumio[1467]: info: Shairport-Sync Started May 13 16:28:23 volumio volumio[1467]: info: Shairport-Sync Started May 13 16:28:23 volumio volumio[1467]: info: MPD Permissions set May 13 16:28:23 volumio volumio[1467]: info: Starting Shairport Sync May 13 16:28:23 volumio sudo[1978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 13 16:28:23 volumio sudo[1978]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 13 16:28:23 volumio systemd[1]: shairport-sync.service: Succeeded. May 13 16:28:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 13 16:28:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 13 16:28:23 volumio sudo[1978]: pam_unix(sudo:session): session closed for user root May 13 16:28:23 volumio volumio[1467]: info: Shairport-Sync Started May 13 16:28:24 volumio volumio[1467]: info: camilladsp spawned new process with pid 2046, instance 1, run: true May 13 16:28:24 volumio volumio[1467]: info: camilladsp service started and running in background, instance 1 May 13 16:28:24 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 16:28:24 volumio volumio[1467]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA May 13 16:28:24 volumio volumio[1467]: info: FusionDsp loaded May 13 16:28:24 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 16:28:24 volumio volumio[1467]: info: FusionDsp - Reporting Fusion DSP Enabled May 13 16:28:24 volumio volumio[1467]: info: Adding Signal Path Element [object Object] May 13 16:28:24 volumio volumio[1467]: info: Adding fusiondspeq DSP Signal Path Element May 13 16:28:24 volumio volumio[1467]: info: FusionDsp - ---- installed callbackRead May 13 16:28:24 volumio volumio[1467]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 13 16:28:24 volumio volumio[1467]: info: FusionDsp - Nulleq applied May 13 16:28:24 volumio volumio[1467]: info: FusionDsp - Effects disabled May 13 16:28:24 volumio volumio[1467]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 13 16:28:24 volumio volumio[1467]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 13 16:28:24 volumio sudo[2056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 13 16:28:24 volumio sudo[2056]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:25 volumio systemd[1]: Started FusionDsp Daemon. May 13 16:28:25 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 13 16:28:25 volumio sudo[2056]: pam_unix(sudo:session): session closed for user root May 13 16:28:26 volumio sudo[2125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //smb://macstudonjurgen.fritz.box/ /mnt/NAS/BigMaster May 13 16:28:26 volumio sudo[2125]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:26 volumio sudo[2125]: pam_unix(sudo:session): session closed for user root May 13 16:28:26 volumio volumio[1467]: info: Cannot mount NAS BigMaster at system boot, trial number 2 ,retrying in 5 seconds May 13 16:28:29 volumio sudo[2155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 13 16:28:29 volumio sudo[2155]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:29 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 13 16:28:29 volumio sudo[2153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 13 16:28:29 volumio sudo[2155]: pam_unix(sudo:session): session closed for user root May 13 16:28:29 volumio sudo[2160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 13 16:28:29 volumio sudo[2160]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:29 volumio sudo[2153]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:29 volumio sudo[2153]: pam_unix(sudo:session): session closed for user root May 13 16:28:29 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 13 16:28:29 volumio sudo[2160]: pam_unix(sudo:session): session closed for user root May 13 16:28:29 volumio volumio[1467]: info: Upmpdcli Daemon Started May 13 16:28:29 volumio volumio[1467]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 6 May 13 16:28:30 volumio volumio[1467]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 7 May 13 16:28:30 volumio volumio[1467]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 13 16:28:30 volumio volumio[1467]: verbose: ControllerMpd::sendMpdCommand update May 13 16:28:30 volumio volumio[1467]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 16:28:30 volumio volumio[1467]: TypeError: Cannot read property 'then' of undefined May 13 16:28:30 volumio volumio[1467]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) May 13 16:28:30 volumio volumio[1467]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1899:15) May 13 16:28:30 volumio volumio[1467]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 13 16:28:30 volumio volumio[1467]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1091:26) May 13 16:28:30 volumio volumio[1467]: at Socket.emit (events.js:400:28) May 13 16:28:30 volumio volumio[1467]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 13 16:28:30 volumio volumio[1467]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 13 16:28:30 volumio volumio[1467]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 16:28:30 volumio sudo[2201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-13 16:27 May 13 16:28:30 volumio sudo[2201]: 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="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:53:51 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="53825f995260683aee752aed6b29f24e"