-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Mon 2025-04-28 10:41:25 +07. -- Apr 28 10:40:16 volumio systemd-timedated[1013]: Changed local time to Mon Apr 28 10:40:16 2025 Apr 28 10:40:16 volumio sudo[1011]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:16 volumio volumio-time-update[648]: volumio-time-update-util: System time updated successfully. Apr 28 10:40:16 volumio systemd[1]: Started Volumio Time Update Utility. Apr 28 10:40:16 volumio winbindd[1015]: [2025/04/28 10:40:16.096411, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 28 10:40:16 volumio winbindd[1015]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 28 10:40:16 volumio winbindd[1015]: [2025/04/28 10:40:16.112255, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 28 10:40:16 volumio winbindd[1015]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 28 10:40:16 volumio systemd[1]: Started Samba Winbind Daemon. Apr 28 10:40:16 volumio systemd[1]: Starting Samba SMB Daemon... Apr 28 10:40:16 volumio wireless.js[647]: trying... Apr 28 10:40:16 volumio sudo[1021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 10:40:16 volumio sudo[1021]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:16 volumio sudo[1021]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:16 volumio smbd[1019]: [2025/04/28 10:40:16.501089, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 28 10:40:16 volumio smbd[1019]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 28 10:40:16 volumio systemd[1]: Started Samba SMB Daemon. Apr 28 10:40:16 volumio volumio-remote-updater[649]: [2025-04-28 10:40:16] [info] asio async_connect error: system:111 (Connection refused) Apr 28 10:40:16 volumio volumio-remote-updater[649]: [2025-04-28 10:40:16] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 28 10:40:16 volumio volumio-remote-updater[649]: [2025-04-28 10:40:16] [error] handle_connect error: Underlying Transport Error Apr 28 10:40:17 volumio wpa_supplicant[971]: wlan0: Trying to associate with SSID 'Tien Dung 43H2' Apr 28 10:40:17 volumio wireless.js[647]: trying... Apr 28 10:40:17 volumio sudo[1031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 10:40:17 volumio sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:17 volumio sudo[1031]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:17 volumio wpa_supplicant[971]: wlan0: Associated with 18:a6:f7:8b:24:70 Apr 28 10:40:17 volumio wpa_supplicant[971]: wlan0: CTRL-EVENT-CONNECTED - Connection to 18:a6:f7:8b:24:70 completed [id=0 id_str=] Apr 28 10:40:17 volumio dhcpcd[711]: wlan0: carrier acquired Apr 28 10:40:17 volumio wpa_supplicant[971]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 28 10:40:17 volumio dhcpcd[711]: wlan0: connected to Access Point `Tien Dung 43H2' Apr 28 10:40:17 volumio wpa_supplicant[971]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Apr 28 10:40:17 volumio dhcpcd[711]: wlan0: IAID 01:45:8e:c9 Apr 28 10:40:17 volumio dhcpcd[711]: wlan0: adding address fe80::e65f:1ff:fe45:8ec9 Apr 28 10:40:17 volumio dhcpcd[711]: ipv6_addaddr1: Permission denied Apr 28 10:40:17 volumio dhcpcd[711]: wlan0: rebinding lease of 192.168.1.218 Apr 28 10:40:18 volumio dhcpcd[711]: wlan0: soliciting an IPv6 router Apr 28 10:40:18 volumio wireless.js[647]: trying... Apr 28 10:40:18 volumio sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 10:40:18 volumio sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:18 volumio sudo[1035]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:19 volumio wireless.js[647]: trying... Apr 28 10:40:19 volumio sudo[1038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 10:40:19 volumio sudo[1038]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:19 volumio sudo[1038]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:20 volumio wireless.js[647]: trying... Apr 28 10:40:20 volumio sudo[1041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 10:40:20 volumio sudo[1041]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:20 volumio sudo[1041]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:21 volumio wireless.js[647]: trying... Apr 28 10:40:21 volumio sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 10:40:21 volumio sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:21 volumio sudo[1044]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:22 volumio dhcpcd[711]: wlan0: probing address 192.168.1.218/24 Apr 28 10:40:22 volumio wireless.js[647]: trying... Apr 28 10:40:22 volumio sudo[1047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 10:40:22 volumio sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:22 volumio sudo[1047]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:23 volumio wireless.js[647]: trying... Apr 28 10:40:23 volumio sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 10:40:23 volumio sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:23 volumio sudo[1050]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:24 volumio wireless.js[647]: trying... Apr 28 10:40:24 volumio sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 10:40:24 volumio sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:24 volumio sudo[1053]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:25 volumio wireless.js[647]: trying... Apr 28 10:40:25 volumio sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 10:40:25 volumio sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:25 volumio sudo[1056]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:26 volumio wireless.js[647]: trying... Apr 28 10:40:26 volumio sudo[1059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 10:40:26 volumio sudo[1059]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:26 volumio sudo[1059]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:26 volumio volumio-remote-updater[649]: [2025-04-28 10:40:26] [info] asio async_connect error: system:111 (Connection refused) Apr 28 10:40:26 volumio volumio-remote-updater[649]: [2025-04-28 10:40:26] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 28 10:40:26 volumio volumio-remote-updater[649]: [2025-04-28 10:40:26] [error] handle_connect error: Underlying Transport Error Apr 28 10:40:27 volumio wireless.js[647]: trying... Apr 28 10:40:27 volumio sudo[1062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 10:40:27 volumio sudo[1062]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:27 volumio sudo[1062]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:27 volumio dhcpcd[711]: wlan0: leased 192.168.1.218 for 10800 seconds Apr 28 10:40:27 volumio avahi-daemon[631]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.218. Apr 28 10:40:28 volumio avahi-daemon[631]: New relevant interface wlan0.IPv4 for mDNS. Apr 28 10:40:28 volumio dhcpcd[711]: wlan0: adding route to 192.168.1.0/24 Apr 28 10:40:28 volumio dhcpcd[711]: wlan0: adding default route via 192.168.1.1 Apr 28 10:40:28 volumio avahi-daemon[631]: Registering new address record for 192.168.1.218 on wlan0.IPv4. Apr 28 10:40:28 volumio wireless.js[647]: trying... Apr 28 10:40:28 volumio sudo[1087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 10:40:28 volumio sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:28 volumio sudo[1087]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:28 volumio wireless.js[647]: Connected to: ----Tien Dung 43H2 Apr 28 10:40:28 volumio wireless.js[647]: ---- Apr 28 10:40:28 volumio sudo[1090]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 10:40:28 volumio sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:28 volumio sudo[1090]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:28 volumio wireless.js[647]: ... joined AP, wlan0 IPv4 is 192.168.1.218, ipV6 is undefined Apr 28 10:40:28 volumio wireless.js[647]: It's done! AP Apr 28 10:40:28 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 28 10:40:28 volumio systemd[1]: Started Wireless Services. Apr 28 10:40:28 volumio systemd[1]: Started Volumio Backend Module. Apr 28 10:40:28 volumio systemd[1]: Started Volumio Cpu Tweaker. Apr 28 10:40:28 volumio systemd[1]: Reached target Multi-User System. Apr 28 10:40:28 volumio systemd[1]: Reached target Graphical Interface. Apr 28 10:40:28 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 28 10:40:28 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 28 10:40:28 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 28 10:40:28 volumio systemd[1]: Startup finished in 12.254s (kernel) + 28.105s (userspace) = 40.360s. Apr 28 10:40:28 volumio volumio-cpu-tweak[1096]: Setting RT Priority for mpd Apr 28 10:40:28 volumio volumio-cpu-tweak[1096]: Setting MPD Affinity Apr 28 10:40:28 volumio volumio-cpu-tweak[1096]: pid 872's current affinity mask: f Apr 28 10:40:28 volumio volumio-cpu-tweak[1096]: pid 872's new affinity mask: 3 Apr 28 10:40:28 volumio volumio-cpu-tweak[1096]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 28 10:40:28 volumio volumio-cpu-tweak[1096]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 28 10:40:28 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 28 10:40:29 volumio ntpd[869]: Listen normally on 4 wlan0 192.168.1.218:123 Apr 28 10:40:29 volumio ntpd[869]: new interface(s) found: waking up resolver Apr 28 10:40:30 volumio volumio[1095]: info: ------------------------------------------- Apr 28 10:40:30 volumio volumio[1095]: info: ----- Volumio3 ---- Apr 28 10:40:30 volumio volumio[1095]: info: ------------------------------------------- Apr 28 10:40:30 volumio volumio[1095]: info: ----- System startup ---- Apr 28 10:40:30 volumio volumio[1095]: info: ------------------------------------------- Apr 28 10:40:30 volumio volumio[1095]: info: MYVOLUMIO Environment detected Apr 28 10:40:30 volumio volumio[1095]: info: Plugin folders cleanup Apr 28 10:40:30 volumio volumio[1095]: info: Scanning into folder /volumio/app/plugins/ Apr 28 10:40:30 volumio volumio[1095]: info: Scanning category audio_interface Apr 28 10:40:30 volumio volumio[1095]: info: Scanning category miscellanea Apr 28 10:40:30 volumio volumio[1095]: info: Scanning category music_service Apr 28 10:40:30 volumio volumio[1095]: info: Scanning category plugins.json Apr 28 10:40:30 volumio volumio[1095]: info: Scanning category system_controller Apr 28 10:40:30 volumio volumio[1095]: info: Scanning category user_interface Apr 28 10:40:30 volumio volumio[1095]: info: Scanning into folder /data/plugins/ Apr 28 10:40:30 volumio volumio[1095]: info: Scanning category music_service Apr 28 10:40:30 volumio volumio[1095]: info: Scanning category system_hardware Apr 28 10:40:30 volumio volumio[1095]: info: Scanning category user_interface Apr 28 10:40:30 volumio volumio[1095]: info: Plugin folders cleanup completed Apr 28 10:40:30 volumio volumio[1095]: info: ------------------------------------------- Apr 28 10:40:30 volumio volumio[1095]: info: ----- Core plugins startup ---- Apr 28 10:40:30 volumio volumio[1095]: info: ------------------------------------------- Apr 28 10:40:30 volumio volumio[1095]: info: Loading plugins from folder /volumio/app/plugins/ Apr 28 10:40:30 volumio volumio[1095]: info: Adding plugin upnp to MyMusic Plugins Apr 28 10:40:30 volumio volumio[1095]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 28 10:40:30 volumio volumio[1095]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 28 10:40:30 volumio volumio[1095]: info: Loading plugins from folder /data/plugins/ Apr 28 10:40:30 volumio volumio[1095]: info: Loading plugin "system"... Apr 28 10:40:30 volumio volumio[1095]: info: Loading plugin "appearance"... Apr 28 10:40:31 volumio volumio[1095]: info: Loading plugin "network"... Apr 28 10:40:31 volumio volumio[1095]: info: Refreshing Cached IP Addresses Apr 28 10:40:31 volumio sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 10:40:31 volumio sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:31 volumio sudo[1127]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:31 volumio sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 10:40:31 volumio sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:31 volumio sudo[1129]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:31 volumio volumio[1095]: info: Loading plugin "services"... Apr 28 10:40:31 volumio volumio[1095]: info: Loading plugin "alsa_controller"... Apr 28 10:40:31 volumio sudo[1138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 28 10:40:31 volumio sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:32 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 10:40:32 volumio volumio[1095]: info: Loading plugin "wizard"... Apr 28 10:40:32 volumio volumio[1095]: info: Loading plugin "networkfs"... Apr 28 10:40:32 volumio volumio[1095]: info: Starting Udev Watcher for removable devices Apr 28 10:40:32 volumio sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Hoang,password=tuaN151082,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.243/music\\ /mnt/NAS/NAS Apr 28 10:40:32 volumio sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:32 volumio volumio[1095]: info: Ignoring mount for partition: boot Apr 28 10:40:32 volumio volumio[1095]: info: Ignoring mount for partition: volumio Apr 28 10:40:32 volumio volumio[1095]: info: Ignoring mount for partition: volumio_data Apr 28 10:40:32 volumio volumio[1095]: info: Mounting Device SSD-240GB Apr 28 10:40:32 volumio sudo[1166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/SSD-240GB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 28 10:40:32 volumio sudo[1166]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:32 volumio kernel: Key type cifs.spnego registered Apr 28 10:40:32 volumio kernel: Key type cifs.idmap registered Apr 28 10:40:32 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Apr 28 10:40:32 volumio kernel: CIFS: Attempting to mount //192.168.1.243/music Apr 28 10:40:32 volumio kernel: cryptd: max_cpu_qlen set to 1000 Apr 28 10:40:32 volumio sudo[1155]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:32 volumio ntfs-3g[1200]: Version 2017.3.23AR.3 integrated FUSE 28 Apr 28 10:40:32 volumio ntfs-3g[1200]: Mounted /dev/sda1 (Read-Write, label "SSD-240GB", NTFS 3.1) Apr 28 10:40:32 volumio ntfs-3g[1200]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 28 10:40:32 volumio ntfs-3g[1200]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Apr 28 10:40:32 volumio ntfs-3g[1200]: Global ownership and permissions enforced, configuration type 7 Apr 28 10:40:32 volumio sudo[1166]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:32 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 10:40:32 volumio volumio[1095]: info: Loading plugin "volumio_command_line_client"... Apr 28 10:40:32 volumio volumio[1095]: info: Loading plugin "upnp"... Apr 28 10:40:32 volumio volumio[1095]: info: [1745811632709] Starting Upmpd Daemon Apr 28 10:40:32 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 10:40:32 volumio volumio[1095]: info: Loading plugin "my_music"... Apr 28 10:40:32 volumio volumio[1095]: info: Loading plugin "mpd"... Apr 28 10:40:33 volumio volumio[1095]: info: Loading plugin "upnp_browser"... Apr 28 10:40:33 volumio volumio[1095]: info: Loading plugin "alarm-clock"... Apr 28 10:40:33 volumio volumio[1095]: info: Loading plugin "airplay_emulation"... Apr 28 10:40:33 volumio volumio[1095]: info: Starting Shairport Sync Apr 28 10:40:33 volumio volumio[1095]: info: Loading plugin "last_100"... Apr 28 10:40:33 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 28 10:40:33 volumio volumio[1095]: info: Loading plugin "webradio"... Apr 28 10:40:33 volumio volumio[1095]: info: Loading plugin "i2s_dacs"... Apr 28 10:40:33 volumio volumio[1095]: info: I2S DAC not set, start Auto-detection Apr 28 10:40:33 volumio volumio[1095]: info: Loading plugin "volumiodiscovery"... Apr 28 10:40:33 volumio volumio[1095]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 10:40:33 volumio volumio[1095]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 10:40:33 volumio volumio[1095]: *** WARNING *** For more information see Apr 28 10:40:33 volumio node[1095]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 10:40:33 volumio volumio[1095]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 10:40:33 volumio volumio[1095]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 10:40:33 volumio volumio[1095]: *** WARNING *** For more information see Apr 28 10:40:33 volumio node[1095]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 10:40:33 volumio node[1095]: *** WARNING *** For more information see Apr 28 10:40:33 volumio node[1095]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 10:40:33 volumio node[1095]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 10:40:33 volumio node[1095]: *** WARNING *** For more information see Apr 28 10:40:33 volumio volumio[1095]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 28 10:40:33 volumio volumio[1095]: info: Discovery: Started advertising with name: Volumio Apr 28 10:40:33 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 10:40:33 volumio volumio[1095]: info: Loading plugin "spop"... Apr 28 10:40:34 volumio volumio[1095]: info: Loading plugin "youtube2"... Apr 28 10:40:35 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Apr 28 10:40:36 volumio sudo[1138]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:36 volumio volumio-remote-updater[649]: [2025-04-28 10:40:36] [connect] Successful connection Apr 28 10:40:37 volumio volumio[1095]: info: Loading plugin "outputs"... Apr 28 10:40:37 volumio volumio[1095]: info: Loading plugin "albumart"... Apr 28 10:40:37 volumio volumio[1095]: info: Plugin example_plugin is not enabled Apr 28 10:40:37 volumio volumio[1095]: info: Loading plugin "inputs"... Apr 28 10:40:37 volumio volumio[1095]: info: Loading plugin "updater_comm"... Apr 28 10:40:37 volumio volumio[1095]: info: Plugin mpdemulation is not enabled Apr 28 10:40:37 volumio volumio[1095]: info: Loading plugin "rest_api"... Apr 28 10:40:37 volumio volumio[1095]: info: Loading plugin "websocket"... Apr 28 10:40:37 volumio volumio[1095]: info: Starting Socket.io Server version 2.3.0 Apr 28 10:40:37 volumio volumio[1095]: info: Plugin RoonBridge is not enabled Apr 28 10:40:37 volumio volumio[1095]: info: Plugin mpd_oled is not enabled Apr 28 10:40:37 volumio volumio[1095]: info: Loading plugin "Systeminfo"... Apr 28 10:40:37 volumio volumio[1095]: Forking 3 albumart workers Apr 28 10:40:37 volumio volumio[1095]: info: Loading i18n strings for locale vi Apr 28 10:40:37 volumio volumio[1095]: Updating browse sources language Apr 28 10:40:37 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::initPlayerControls Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:38 volumio volumio[1095]: Starting albumart workers Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 10:40:38 volumio volumio[1095]: Starting albumart workers Apr 28 10:40:38 volumio volumio[1095]: Express server listening on port 3000 Apr 28 10:40:38 volumio volumio[1095]: [Metrics] WebUI: 8s 548.23ms Apr 28 10:40:38 volumio volumio[1095]: Starting albumart workers Apr 28 10:40:38 volumio volumio[1095]: info: CoreStateMachine::resetVolumioState Apr 28 10:40:38 volumio volumio[1095]: info: CoreStateMachine::getcurrentVolume Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:38 volumio volumio[1095]: info: Volumio Network Manager: Network status updated: 3 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.242 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.242 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.242 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.242 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.242 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 8 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.242 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 10 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 11 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 12 Apr 28 10:40:38 volumio volumio-remote-updater[649]: [2025-04-28 10:40:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745811636 101 Apr 28 10:40:38 volumio volumio[1095]: 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: 13 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.242 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 14 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.242 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 15 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 16 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.242 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 17 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.242 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 18 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 19 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 20 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.242 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 21 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 22 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 23 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 24 Apr 28 10:40:38 volumio volumio[1095]: info: VolumeController:: Volume=100 Mute =false Apr 28 10:40:38 volumio volumio[1095]: info: CoreStateMachine::pushState Apr 28 10:40:38 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Apr 28 10:40:38 volumio volumio[1095]: info: CoreStateMachine::updateTrackBlock Apr 28 10:40:38 volumio volumio[1095]: info: CorePlayQueue::getTrackBlock Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 10:40:38 volumio volumio[1095]: info: Reloading queue from file Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 25 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 26 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 27 Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Apr 28 10:40:38 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 28 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 29 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 30 Apr 28 10:40:38 volumio volumio[1095]: info: CoreStateMachine::setRepeat null single undefined Apr 28 10:40:38 volumio volumio[1095]: info: CoreStateMachine::pushState Apr 28 10:40:38 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Apr 28 10:40:38 volumio volumio[1095]: info: CoreStateMachine::setRandom null Apr 28 10:40:38 volumio volumio[1095]: info: CoreStateMachine::pushState Apr 28 10:40:38 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Apr 28 10:40:38 volumio volumio[1095]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 28 10:40:38 volumio volumio[1095]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03114 Apr 28 10:40:38 volumio volumio[1095]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 28 10:40:38 volumio volumio[1095]: info: Setting Device type: Raspberry PI Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 31 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 32 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 33 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 34 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 35 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 36 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 37 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 38 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 39 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 40 Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::volumioGetVisibleSources Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 10:40:38 volumio volumio[1095]: info: Listing playlists Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::volumioGetQueue Apr 28 10:40:38 volumio volumio[1095]: info: CoreStateMachine::getQueue Apr 28 10:40:38 volumio volumio[1095]: info: CorePlayQueue::getQueue Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 41 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 42 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 43 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 44 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 45 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 46 Apr 28 10:40:38 volumio volumio[1095]: info: VolumeController:: Volume=100 Mute =false Apr 28 10:40:38 volumio volumio[1095]: info: CoreStateMachine::pushState Apr 28 10:40:38 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 47 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 48 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 49 Apr 28 10:40:38 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.218 from 192.168.1.245 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 50 Apr 28 10:40:38 volumio volumio[1095]: info: Discovery: adding bf576934-0839-4396-ae24-95c31f5ec5b1 Apr 28 10:40:38 volumio volumio[1095]: info: Discovery: Found device Volumio Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Apr 28 10:40:38 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:40:38 volumio volumio[1095]: info: Discovery: this is already registered, bf576934-0839-4396-ae24-95c31f5ec5b1 Apr 28 10:40:38 volumio volumio[1095]: info: Discovery: Found device Volumio Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Apr 28 10:40:38 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:40:38 volumio volumio[1095]: info: Completed loading Core Plugins Apr 28 10:40:38 volumio volumio[1095]: info: Preparing to generate the ALSA configuration file Apr 28 10:40:38 volumio volumio[1095]: info: Asound.conf file unchanged, so no further update is needed Apr 28 10:40:38 volumio volumio[1095]: info: Output device has changed, restarting MPD Apr 28 10:40:38 volumio sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 10:40:38 volumio sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:38 volumio volumio[1095]: info: Output device has changed, restarting Shairport Sync Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:38 volumio sudo[1271]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:38 volumio sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 10:40:38 volumio sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:38 volumio systemd[1]: Stopping Music Player Daemon... Apr 28 10:40:39 volumio volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 28 10:40:39 volumio volumio[1095]: info: ___________ START PLUGINS ___________ Apr 28 10:40:39 volumio volumio[1095]: info: ControllerMpd::onStart: Initializing MPD Apr 28 10:40:39 volumio volumio[1095]: info: Creating MPD Configuration file Apr 28 10:40:39 volumio sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 10:40:39 volumio sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 10:40:39 volumio sudo[1280]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 10:40:39 volumio volumio[1095]: info: [1745811639057] CoreMusicLibrary::Adding element Máy chủ Media Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 10:40:39 volumio sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 10:40:39 volumio sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:39 volumio systemd[1]: mpd.service: Succeeded. Apr 28 10:40:39 volumio systemd[1]: Stopped Music Player Daemon. Apr 28 10:40:39 volumio volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:39 volumio systemd[1]: Starting Music Player Daemon... Apr 28 10:40:39 volumio volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 10:40:39 volumio volumio[1095]: info: [1745811639161] CoreMusicLibrary::Adding element Last_100 Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 10:40:39 volumio volumio[1095]: info: [1745811639164] CoreMusicLibrary::Adding element Webradio Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 10:40:39 volumio volumio[1095]: info: Initializing BBC Radios Apr 28 10:40:39 volumio sudo[1288]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 28 10:40:39 volumio sudo[1288]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:39 volumio sudo[1288]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:39 volumio volumio[1095]: info: Creating Spotify config file Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 10:40:39 volumio volumio[1095]: info: [1745811639305] CoreMusicLibrary::Adding element YouTube2 Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 10:40:39 volumio volumio[1095]: Cannot find translation for source YouTube2 Apr 28 10:40:39 volumio volumio[1095]: info: Volumio Calling Home Apr 28 10:40:39 volumio volumio[1095]: info: MPD Permissions set Apr 28 10:40:39 volumio volumio[1095]: info: MPD Permissions set Apr 28 10:40:39 volumio volumio[1095]: info: Spotify config file written Apr 28 10:40:39 volumio volumio[1095]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 51 Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:39 volumio sudo[1308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 28 10:40:39 volumio sudo[1308]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:39 volumio volumio[1095]: info: No need to fix Spotify hosts Apr 28 10:40:39 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Apr 28 10:40:39 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:40:39 volumio volumio[1095]: info: Starting Shairport Sync Apr 28 10:40:39 volumio systemd[1]: Started go-librespot Daemon. Apr 28 10:40:39 volumio go-librespot[1317]: go-librespot daemon starting... Apr 28 10:40:39 volumio sudo[1308]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:39 volumio volumio[1095]: info: Starting Shairport Sync Apr 28 10:40:39 volumio sudo[1321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 28 10:40:39 volumio sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:39 volumio volumio[1095]: info: Starting Shairport Sync Apr 28 10:40:39 volumio sudo[1323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 28 10:40:39 volumio sudo[1323]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 28 10:40:39 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 28 10:40:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 28 10:40:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 28 10:40:39 volumio sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 28 10:40:39 volumio sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:39 volumio sudo[1321]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 28 10:40:39 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 28 10:40:39 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 28 10:40:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 28 10:40:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 28 10:40:39 volumio sudo[1323]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 28 10:40:39 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 28 10:40:39 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 28 10:40:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 28 10:40:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 28 10:40:39 volumio sudo[1327]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:39 volumio volumio[1095]: info: Shairport-Sync Started Apr 28 10:40:39 volumio volumio[1095]: Error adding Membership: Error: addMembership EINVAL Apr 28 10:40:39 volumio volumio[1095]: info: Shairport-Sync Started Apr 28 10:40:39 volumio volumio[1095]: info: Shairport-Sync Started Apr 28 10:40:39 volumio volumio[1095]: info: Volumio called home Apr 28 10:40:39 volumio volumio[1095]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 28 10:40:39 volumio volumio[1095]: SPOTIFY: BQBeYCi2_afSIJesaMZZSuOA2fKx3u4mnSRU2qHG4cmRR-Q-pDNELEw47XD0mFDa8BWQwVlXcqKjJIFKF-uZulTAYtbLrKKZL22cVxwvDqPfdBj6nhFnNpiPGZ_vCEgQr8iusExJfUUrKns597JWIYkUNlidDr6L9h_CzCXjODnKqRHzj1TfmwyoxNj811igL_ZYcTX94apFwhbS_OC6VSnwNDbLMUaC_0uTtN1m3614m3Mlv1JfYdHhrcRaR4Rsm2UoT7o2n4iGaTNSdxkHmkuHjtoavOP7nAUGCU_FLaU Apr 28 10:40:39 volumio volumio[1095]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 28 10:40:39 volumio volumio[1095]: info: New Spotify access token = BQBeYCi2_afSIJesaMZZSuOA2fKx3u4mnSRU2qHG4cmRR-Q-pDNELEw47XD0mFDa8BWQwVlXcqKjJIFKF-uZulTAYtbLrKKZL22cVxwvDqPfdBj6nhFnNpiPGZ_vCEgQr8iusExJfUUrKns597JWIYkUNlidDr6L9h_CzCXjODnKqRHzj1TfmwyoxNj811igL_ZYcTX94apFwhbS_OC6VSnwNDbLMUaC_0uTtN1m3614m3Mlv1JfYdHhrcRaR4Rsm2UoT7o2n4iGaTNSdxkHmkuHjtoavOP7nAUGCU_FLaU Apr 28 10:40:39 volumio volumio[1095]: info: Spotify credentials grant success - running version from March 24, 2019 Apr 28 10:40:39 volumio go-librespot[1317]: time="2025-04-28T10:40:39+07:00" level=info msg="running go-librespot 0.2.0" Apr 28 10:40:39 volumio go-librespot[1317]: time="2025-04-28T10:40:39+07:00" level=debug msg="app state loaded" Apr 28 10:40:39 volumio go-librespot[1317]: time="2025-04-28T10:40:39+07:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 28 10:40:40 volumio mpd[1303]: Apr 28 10:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 28 10:40:40 volumio volumio[1095]: SPOTIFY: User informations: {"country":"VN","display_name":"Vuthaonguyen","email":"vuthaonguyen2010@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/aqc9qybohwvbqbwzj5w7bova6"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/aqc9qybohwvbqbwzj5w7bova6","id":"aqc9qybohwvbqbwzj5w7bova6","images":[],"product":"premium","type":"user","uri":"spotify:user:aqc9qybohwvbqbwzj5w7bova6"} Apr 28 10:40:40 volumio volumio[1095]: info: Spotify Successfully logged in Apr 28 10:40:40 volumio volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 10:40:40 volumio volumio[1095]: info: [1745811640073] CoreMusicLibrary::Adding element Spotify Apr 28 10:40:40 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 10:40:40 volumio volumio[1095]: Cannot find translation for source YouTube2 Apr 28 10:40:40 volumio volumio[1095]: Cannot find translation for source Spotify Apr 28 10:40:40 volumio go-librespot[1317]: time="2025-04-28T10:40:40+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 28 10:40:40 volumio go-librespot[1317]: time="2025-04-28T10:40:40+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Apr 28 10:40:40 volumio go-librespot[1317]: time="2025-04-28T10:40:40+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Apr 28 10:40:40 volumio go-librespot[1317]: time="2025-04-28T10:40:40+07:00" level=info msg="zeroconf server listening on port 36779" Apr 28 10:40:40 volumio systemd[1]: Started Music Player Daemon. Apr 28 10:40:40 volumio sudo[1283]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:40 volumio sudo[1274]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:40 volumio volumio[1095]: info: Completed starting Core Plugins Apr 28 10:40:40 volumio volumio[1095]: info: ------------------------------------------- Apr 28 10:40:40 volumio volumio[1095]: info: ----- MyVolumio plugins startup ---- Apr 28 10:40:40 volumio volumio[1095]: info: ------------------------------------------- Apr 28 10:40:40 volumio volumio[1095]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 28 10:40:40 volumio volumio[1095]: error: MPD error: The expression evaluated to a falsy value: Apr 28 10:40:40 volumio volumio[1095]: assert.ok(self.idling) Apr 28 10:40:40 volumio volumio[1095]: error: The expression evaluated to a falsy value: Apr 28 10:40:40 volumio volumio[1095]: assert.ok(self.idling) Apr 28 10:40:40 volumio volumio[1095]: info: MPD running with PID1303 Apr 28 10:40:40 volumio volumio[1095]: ,establishing connection Apr 28 10:40:40 volumio volumio[1095]: error: MPD error: The expression evaluated to a falsy value: Apr 28 10:40:40 volumio volumio[1095]: assert.ok(self.idling) Apr 28 10:40:40 volumio volumio[1095]: error: The expression evaluated to a falsy value: Apr 28 10:40:40 volumio volumio[1095]: assert.ok(self.idling) Apr 28 10:40:40 volumio volumio[1095]: error: updateQueue error: null Apr 28 10:40:40 volumio go-librespot[1317]: time="2025-04-28T10:40:40+07:00" level=debug msg="obtained new client token: AAA7aOjxugdNdtW7DtTo4tZgm+hZ6FY+IkhD5wdsh7OsUTlJtxnVnRtIT0TyAM3CfI6EhzEUNYPQxGeyQxz1qz/wEt9HQlSHYqWRnw5+cZafF60G5t+H2vYTL0hfsQhxPEYbg7vLVCiHYqhIoGMra5kh6Qqf54zdgmDI0TSUVYj7ceLjKNhmQRhQL7/qEms7GcKsVa+fmeRMHfM1Ar+4n20QSubusdqvNohpfGmp+/c2rcbv5iB5lc73lw==" Apr 28 10:40:40 volumio go-librespot[1317]: time="2025-04-28T10:40:40+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Apr 28 10:40:40 volumio go-librespot[1317]: time="2025-04-28T10:40:40+07:00" level=debug msg="completed keyexchange" Apr 28 10:40:40 volumio go-librespot[1317]: time="2025-04-28T10:40:40+07:00" level=debug msg="completed challenge" Apr 28 10:40:40 volumio go-librespot[1317]: time="2025-04-28T10:40:40+07:00" level=info msg="authenticated AP as aqc9qybohwvbqbwzj5w7bova6" Apr 28 10:40:40 volumio go-librespot[1317]: time="2025-04-28T10:40:40+07:00" level=info msg="authenticated Login5 as aqc9qybohwvbqbwzj5w7bova6" Apr 28 10:40:40 volumio go-librespot[1317]: time="2025-04-28T10:40:40+07:00" level=debug msg="initializing zeroconf session, username: aqc9qybohwvbqbwzj5w7bova6" Apr 28 10:40:41 volumio go-librespot[1317]: time="2025-04-28T10:40:41+07:00" level=debug msg="dealer connection opened" Apr 28 10:40:41 volumio go-librespot[1317]: time="2025-04-28T10:40:41+07:00" level=trace msg="starting accesspoint recv loop" Apr 28 10:40:41 volumio go-librespot[1317]: time="2025-04-28T10:40:41+07:00" level=trace msg="starting dealer recv loop" Apr 28 10:40:41 volumio go-librespot[1317]: time="2025-04-28T10:40:41+07:00" level=trace msg="received accesspoint ping" Apr 28 10:40:41 volumio go-librespot[1317]: time="2025-04-28T10:40:41+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 28 10:40:41 volumio go-librespot[1317]: time="2025-04-28T10:40:41+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 28 10:40:41 volumio go-librespot[1317]: time="2025-04-28T10:40:41+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 28 10:40:41 volumio go-librespot[1317]: time="2025-04-28T10:40:41+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 28 10:40:41 volumio go-librespot[1317]: time="2025-04-28T10:40:41+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 28 10:40:41 volumio go-librespot[1317]: time="2025-04-28T10:40:41+07:00" level=debug msg="received connection id: NmI5ODVkODYtMjQ3My00OTVmLWI3ODYtZTdhMTk1NjlkNThjK2RlYWxlcit0Y3A6Ly8wYWM5NDgyOS5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArN0FBNzk4OUIyMDMxRkM0MTkxRjkxMEVDNEEwRTg5NjRFQjgyMUZGRjRDRTY0MEY3RjU5RDJGODg2NjA1NzY5RA==" Apr 28 10:40:41 volumio go-librespot[1317]: time="2025-04-28T10:40:41+07:00" level=trace msg="received accesspoint pong ack" Apr 28 10:40:41 volumio go-librespot[1317]: time="2025-04-28T10:40:41+07:00" level=debug msg="put connect state because NEW_DEVICE" Apr 28 10:40:42 volumio sudo[1361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 10:40:42 volumio sudo[1361]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:42 volumio volumio[1095]: info: go-librespot daemon successfully initialized Apr 28 10:40:42 volumio sudo[1361]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:42 volumio sudo[1363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 10:40:42 volumio sudo[1363]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:42 volumio sudo[1363]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:42 volumio sudo[1367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 28 10:40:42 volumio sudo[1367]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:42 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 28 10:40:42 volumio sudo[1367]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:42 volumio volumio[1095]: info: Upmpdcli Daemon Started Apr 28 10:40:42 volumio volumio[1369]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 28 10:40:45 volumio volumio[1095]: info: Initializing connection to go-librespot Websocket Apr 28 10:40:45 volumio go-librespot[1317]: time="2025-04-28T10:40:45+07:00" level=debug msg="new websocket client" Apr 28 10:40:45 volumio volumio[1095]: info: Connection to go-librespot Websocket established Apr 28 10:40:46 volumio systemd[1]: systemd-timedated.service: Succeeded. Apr 28 10:40:48 volumio volumio[1095]: info: Getting Spotify volume Apr 28 10:40:49 volumio volumio[1095]: info: Spotify volume: 100 Apr 28 10:40:49 volumio volumio[1095]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 52 Apr 28 10:40:50 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Apr 28 10:40:50 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 28 10:40:50 volumio volumio[1095]: info: Adding plugin bluetooth to MyMusic Plugins Apr 28 10:40:50 volumio volumio[1095]: info: Adding plugin multiroom to MyMusic Plugins Apr 28 10:40:50 volumio volumio[1095]: info: Adding plugin metavolumio to MyMusic Plugins Apr 28 10:40:50 volumio volumio[1095]: info: Adding plugin cd_controller to MyMusic Plugins Apr 28 10:40:50 volumio volumio[1095]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 28 10:40:50 volumio volumio[1095]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 28 10:40:50 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 28 10:40:51 volumio volumio[1095]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 28 10:40:51 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 28 10:40:51 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:51 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:51 volumio volumio[1095]: info: Starting MyVolumio Remote Streaming Endpoints Apr 28 10:40:51 volumio volumio[1095]: info: MyVolumio login type: Token Apr 28 10:40:51 volumio volumio[1095]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 28 10:40:51 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 28 10:40:52 volumio volumio[1095]: info: Starting Streaming Service Transparent Proxy Apr 28 10:40:52 volumio volumio[1095]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 28 10:40:52 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 28 10:40:52 volumio volumio[1095]: info: Streaming services startup Apr 28 10:40:52 volumio volumio[1095]: info: Starting Streaming Daemon Apr 28 10:40:52 volumio sudo[1411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 28 10:40:52 volumio sudo[1411]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:52 volumio sudo[1411]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:52 volumio volumio[1095]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 28 10:40:52 volumio volumio[1095]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 10:40:52 volumio volumio[1095]: error: Cannot start Volumio Streaming Daemon Apr 28 10:40:52 volumio volumio[1095]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 28 10:40:52 volumio volumio[1095]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 28 10:40:53 volumio volumio[1095]: STREAMING PROXY: Starting server on port 3245 Apr 28 10:40:53 volumio volumio[1095]: Node JS runtime: 14 Apr 28 10:40:53 volumio volumio[1095]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 28 10:40:54 volumio volumio[1095]: info: MyVolumio token set successfully Apr 28 10:40:54 volumio volumio[1095]: info: MYVOLUMIO: Adding device Apr 28 10:40:54 volumio volumio[1095]: info: MYVOLUMIO: Evaluating Server Apr 28 10:40:54 volumio volumio[1369]: ...............................................................................................................................++++ Apr 28 10:40:54 volumio volumio[1095]: info: MyVolumio Plan changed: premium Apr 28 10:40:54 volumio volumio[1095]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Apr 28 10:40:54 volumio volumio[1095]: info: Removing browser output: myVolumio user plan is not superstar Apr 28 10:40:54 volumio volumio[1095]: info: Removing audio output: Apr 28 10:40:54 volumio volumio[1095]: info: MYVOLUMIO: Adding device Apr 28 10:40:54 volumio volumio[1095]: info: MYVOLUMIO: Evaluating Server Apr 28 10:40:54 volumio volumio[1095]: info: Remote config written successfully Apr 28 10:40:54 volumio volumio[1095]: info: Starting Tunnel 1 Apr 28 10:40:54 volumio volumio[1095]: info: Starting Tunnel Connection Checker Apr 28 10:40:54 volumio volumio[1095]: info: Completed starting MyVolumio Plugin Apr 28 10:40:55 volumio volumio[1095]: info: MYVolumio Device enabled Apr 28 10:40:55 volumio volumio[1095]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 28 10:40:55 volumio volumio[1095]: info: MyVolumio status changed Apr 28 10:40:55 volumio volumio[1095]: info: Streaming services startup Apr 28 10:40:55 volumio volumio[1095]: info: Starting Streaming Daemon Apr 28 10:40:55 volumio sudo[1451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 28 10:40:55 volumio sudo[1451]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:55 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 28 10:40:55 volumio sudo[1451]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:55 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Apr 28 10:40:55 volumio volumio[1095]: info: [1745811655381] Starting BluetoothController Apr 28 10:40:55 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Apr 28 10:40:56 volumio volumio[1095]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Apr 28 10:40:56 volumio sudo[1454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 28 10:40:56 volumio sudo[1454]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:56 volumio sudo[1454]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:56 volumio volumio[1095]: info: MRS: MultiRoom plugin initialized Apr 28 10:40:56 volumio volumio[1095]: info: MRS: STOPPING SNAPCLIENT Apr 28 10:40:56 volumio volumio[1095]: info: MRS: Snap server stop Apr 28 10:40:56 volumio sudo[1470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 28 10:40:56 volumio sudo[1470]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:56 volumio volumio[1095]: info: MRS: STOPPING volumioStreaming Apr 28 10:40:56 volumio sudo[1473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 28 10:40:56 volumio sudo[1473]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:56 volumio sudo[1470]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:56 volumio sudo[1476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 28 10:40:56 volumio sudo[1476]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:56 volumio sudo[1473]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:56 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Apr 28 10:40:56 volumio sudo[1479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 28 10:40:56 volumio sudo[1479]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:56 volumio sudo[1476]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:56 volumio sudo[1479]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:56 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Apr 28 10:40:56 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Apr 28 10:40:56 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Apr 28 10:40:56 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Apr 28 10:40:56 volumio volumio[1095]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Apr 28 10:40:56 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Apr 28 10:40:56 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Apr 28 10:40:56 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Apr 28 10:40:56 volumio volumio[1095]: info: Preparing to generate the ALSA configuration file Apr 28 10:40:56 volumio volumio[1095]: info: Setting Geolocation for MyVolumio to as1 Apr 28 10:40:56 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:56 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:56 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:56 volumio volumio[1095]: info: Setting Geolocation for MyVolumio to as1 Apr 28 10:40:56 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:56 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:56 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:56 volumio volumio[1095]: info: MRS: Removed streaming files Apr 28 10:40:56 volumio volumio[1095]: info: MRS: volumioStreaming STOPPED Apr 28 10:40:56 volumio volumio[1095]: info: MRS: SNAPSERVER STOPPED Apr 28 10:40:56 volumio volumio[1095]: info: MRS: SNAPCLIENT STOPPED Apr 28 10:40:56 volumio volumio[1095]: error: Cannot start Volumio Streaming Daemon Apr 28 10:40:56 volumio volumio[1095]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 28 10:40:56 volumio volumio[1095]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 28 10:40:56 volumio volumio[1095]: info: Asound.conf file unchanged, so no further update is needed Apr 28 10:40:56 volumio volumio[1095]: info: Output device has changed, restarting MPD Apr 28 10:40:57 volumio sudo[1482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 10:40:57 volumio sudo[1482]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:57 volumio sudo[1482]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:57 volumio volumio[1095]: info: Output device has changed, restarting Shairport Sync Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:57 volumio sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 10:40:57 volumio sudo[1485]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:57 volumio systemd[1]: Stopping Music Player Daemon... Apr 28 10:40:57 volumio volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 28 10:40:57 volumio volumio[1095]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 28 10:40:57 volumio systemd[1]: mpd.service: Succeeded. Apr 28 10:40:57 volumio systemd[1]: Stopped Music Player Daemon. Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Apr 28 10:40:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Apr 28 10:40:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Apr 28 10:40:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Apr 28 10:40:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Apr 28 10:40:57 volumio volumio[1095]: info: Adding METAVOLUMIO REST API Endpoints Apr 28 10:40:57 volumio volumio[1095]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 28 10:40:57 volumio volumio[1095]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 28 10:40:57 volumio volumio[1095]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 28 10:40:57 volumio volumio[1095]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 28 10:40:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Apr 28 10:40:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Apr 28 10:40:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Apr 28 10:40:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Apr 28 10:40:57 volumio volumio[1095]: info: Adding inputs REST Endpoints Apr 28 10:40:57 volumio volumio[1095]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 28 10:40:57 volumio volumio[1095]: info: Scanning Audio Inputs Apr 28 10:40:57 volumio systemd[1]: Starting Music Player Daemon... Apr 28 10:40:57 volumio volumio[1095]: info: Checking against Known Cards name Apr 28 10:40:57 volumio volumio[1095]: info: Adding Server instance for streaming Apr 28 10:40:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Apr 28 10:40:57 volumio volumio[1095]: error: Hi Res Audio Failed Login: Missing Login Data Apr 28 10:40:57 volumio volumio[1095]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 28 10:40:57 volumio volumio[1095]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 28 10:40:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Apr 28 10:40:57 volumio volumio[1095]: info: Refreshing TIDAL token Apr 28 10:40:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Apr 28 10:40:57 volumio volumio[1095]: info: Refreshing QOBUZ token Apr 28 10:40:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Apr 28 10:40:57 volumio sudo[1490]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 28 10:40:57 volumio sudo[1490]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:57 volumio sudo[1490]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:57 volumio sudo[1496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 28 10:40:57 volumio sudo[1496]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:57 volumio sudo[1496]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:57 volumio volumio[1095]: info: MPD Permissions set Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:40:57 volumio volumio[1095]: info: Starting Shairport Sync Apr 28 10:40:57 volumio sudo[1506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 28 10:40:57 volumio sudo[1506]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:57 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 28 10:40:57 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 28 10:40:57 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 28 10:40:57 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 28 10:40:57 volumio sudo[1506]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:57 volumio volumio[1095]: info: Shairport-Sync Started Apr 28 10:40:57 volumio volumio[1095]: info: Access Token successfully retrieved Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 10:40:57 volumio volumio[1095]: info: [1745811657552] CoreMusicLibrary::Adding element QOBUZ Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 10:40:57 volumio volumio[1095]: Cannot find translation for source YouTube2 Apr 28 10:40:57 volumio volumio[1095]: Cannot find translation for source Spotify Apr 28 10:40:57 volumio volumio[1095]: Cannot find translation for source QOBUZ Apr 28 10:40:57 volumio volumio[1095]: info: Stopping AccessToken refresher cron for QOBUZ Apr 28 10:40:57 volumio volumio[1095]: info: AccessToken refresher cron started for QOBUZ Apr 28 10:40:57 volumio volumio[1095]: info: Adding QOBUZ REST API Endpoints Apr 28 10:40:57 volumio volumio[1095]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Apr 28 10:40:57 volumio volumio[1095]: info: Successfully Added MyVolumio device Apr 28 10:40:57 volumio volumio[1095]: info: Successfully Added MyVolumio device Apr 28 10:40:57 volumio volumio[1095]: info: Updating MyVolumio device info Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:57 volumio mpd[1498]: Apr 28 10:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 28 10:40:57 volumio volumio[1095]: info: Updating MyVolumio device info Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:57 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:40:58 volumio volumio[1095]: info: Access Token successfully retrieved Apr 28 10:40:58 volumio systemd[1]: Started Music Player Daemon. Apr 28 10:40:58 volumio sudo[1485]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:58 volumio volumio[1095]: error: updateQueue error: null Apr 28 10:40:58 volumio volumio[1095]: info: Successfully retrieved User Session From TIDAL Apr 28 10:40:58 volumio volumio[1095]: info: Successfully Updated MyVolumio device Apr 28 10:40:58 volumio volumio[1095]: info: Successfully retrieved User Subscription From TIDAL Apr 28 10:40:58 volumio volumio[1095]: info: Adding TIDAL to Browse Sources Apr 28 10:40:58 volumio volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 10:40:58 volumio volumio[1095]: info: [1745811658759] CoreMusicLibrary::Adding element TIDAL Apr 28 10:40:58 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 10:40:58 volumio volumio[1095]: Cannot find translation for source YouTube2 Apr 28 10:40:58 volumio volumio[1095]: Cannot find translation for source Spotify Apr 28 10:40:58 volumio volumio[1095]: Cannot find translation for source QOBUZ Apr 28 10:40:58 volumio volumio[1095]: Cannot find translation for source TIDAL Apr 28 10:40:58 volumio volumio[1095]: info: Stopping AccessToken refresher cron Apr 28 10:40:58 volumio volumio[1095]: info: AccessToken refresher cron started Apr 28 10:40:58 volumio volumio[1095]: info: Adding TIDAL REST API Endpoints Apr 28 10:40:58 volumio volumio[1095]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Apr 28 10:40:59 volumio volumio[1095]: info: Successfully Updated MyVolumio device Apr 28 10:40:59 volumio sudo[1518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 28 10:40:59 volumio sudo[1518]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:40:59 volumio systemd[1]: Started MyVolumio SSH Tunnel. Apr 28 10:40:59 volumio sudo[1518]: pam_unix(sudo:session): session closed for user root Apr 28 10:40:59 volumio volumio[1095]: info: Remote SSH Started Apr 28 10:40:59 volumio autossh[1521]: port set to 0, monitoring disabled Apr 28 10:40:59 volumio autossh[1521]: starting ssh (count 1) Apr 28 10:40:59 volumio autossh[1521]: ssh child pid is 1524 Apr 28 10:40:59 volumio volumio[1095]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 53 Apr 28 10:40:59 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Apr 28 10:40:59 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:41:00 volumio volumiossh-tunnel[1520]: Warning: Permanently added '[as1.myvolumio.org]:2222,[128.199.166.162]:2222' (RSA) to the list of known hosts. Apr 28 10:41:00 volumio volumio[1095]: info: TidalConnect service stoped! Apr 28 10:41:00 volumio volumio[1095]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 28 10:41:00 volumio volumio[1095]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 28 10:41:00 volumio sudo[1534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 28 10:41:00 volumio sudo[1534]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:41:00 volumio systemd[1]: Started Volumio Tidal Connect Service. Apr 28 10:41:00 volumio sudo[1534]: pam_unix(sudo:session): session closed for user root Apr 28 10:41:00 volumio volumio[1095]: info: Executing endpoint tc_getconfig Apr 28 10:41:00 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 28 10:41:00 volumio vtcs[1536]: STARTING TidalConnect services, version: 1.5.0.45 Apr 28 10:41:00 volumio vtcs[1536]: STARTED TidalConnect services. Apr 28 10:41:00 volumio volumio[1095]: info: Executing endpoint tc_connect Apr 28 10:41:00 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 28 10:41:00 volumio volumio[1095]: info: Connecting to TidalConnect Apr 28 10:41:00 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Apr 28 10:41:00 volumio volumio[1095]: info: CoreStateMachine::pushState Apr 28 10:41:00 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:41:00 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Apr 28 10:41:00 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:41:00 volumio volumio[1095]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 28 10:41:00 volumio volumio[1095]: verbose: CURRENT POSITION 0 Apr 28 10:41:00 volumio volumio[1095]: info: CoreStateMachine::syncState stateService stop Apr 28 10:41:00 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus stop Apr 28 10:41:00 volumio volumio[1095]: info: CoreStateMachine::pushState Apr 28 10:41:00 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:41:00 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 10:41:00 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Apr 28 10:41:00 volumio volumio[1095]: info: No code Apr 28 10:41:00 volumio volumio[1095]: info: CoreStateMachine::pushState Apr 28 10:41:00 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:41:00 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Apr 28 10:41:00 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Apr 28 10:41:00 volumio volumio[1095]: info: CoreStateMachine::pushState Apr 28 10:41:00 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:41:00 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Apr 28 10:41:00 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:41:00 volumio volumio[1095]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 28 10:41:00 volumio volumio[1095]: verbose: CURRENT POSITION 0 Apr 28 10:41:00 volumio volumio[1095]: info: CoreStateMachine::syncState stateService stop Apr 28 10:41:00 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus stop Apr 28 10:41:00 volumio volumio[1095]: info: CoreStateMachine::pushState Apr 28 10:41:00 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:41:00 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Apr 28 10:41:00 volumio volumio[1095]: info: No code Apr 28 10:41:00 volumio volumio[1095]: info: CoreStateMachine::pushState Apr 28 10:41:00 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:41:00 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Apr 28 10:41:00 volumio volumio[1095]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 10:41:00 volumio volumio[1095]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 10:41:00 volumio volumio[1095]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 10:41:00 volumio volumio[1095]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 10:41:00 volumio volumio[1095]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 10:41:00 volumio volumio[1095]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 10:41:03 volumio volumio[1095]: info: TidalConnect service started! Apr 28 10:41:03 volumio volumio[1095]: [Metrics] CommandRouter: 33s 385.58ms Apr 28 10:41:03 volumio volumio[1095]: info: CoreCommandRouter::volumiosetStartupVolume Apr 28 10:41:03 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:41:03 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:41:03 volumio volumio[1095]: info: CoreCommandRouter::Close All Modals sent Apr 28 10:41:04 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 28 10:41:04 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 28 10:41:04 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 28 10:41:06 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 28 10:41:06 volumio volumio[1095]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 54 Apr 28 10:41:06 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Apr 28 10:41:06 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Apr 28 10:41:09 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 10:41:09 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 10:41:09 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 28 10:41:09 volumio volumio-remote-updater[649]: No test mode Apr 28 10:41:09 volumio volumio-remote-updater[649]: No alpha test mode Apr 28 10:41:09 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 28 10:41:10 volumio ntpd[869]: Soliciting pool server 103.199.19.135 Apr 28 10:41:10 volumio volumio[1095]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 28 10:41:10 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 28 10:41:10 volumio volumio[1095]: info: BOOT COMPLETED Apr 28 10:41:10 volumio ntpd[869]: Soliciting pool server 27.71.27.209 Apr 28 10:41:11 volumio go-librespot[1317]: time="2025-04-28T10:41:11+07:00" level=trace msg="sent dealer ping" Apr 28 10:41:11 volumio go-librespot[1317]: time="2025-04-28T10:41:11+07:00" level=trace msg="received dealer pong" Apr 28 10:41:11 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:41:11 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 10:41:11 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 28 10:41:11 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 28 10:41:11 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 28 10:41:11 volumio volumio[1095]: info: CoreCommandRouter::volumioGetBrowseSources Apr 28 10:41:11 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 10:41:11 volumio volumio[1369]: ...............................................................................................................................................................................................++++ Apr 28 10:41:11 volumio volumio[1369]: e is 65537 (0x010001) Apr 28 10:41:11 volumio volumio[1369]: writing RSA key Apr 28 10:41:11 volumio ntpd[869]: Soliciting pool server 103.70.115.65 Apr 28 10:41:12 volumio ntpd[869]: Soliciting pool server 115.165.161.155 Apr 28 10:41:14 volumio ntpd[869]: Soliciting pool server 103.184.124.254 Apr 28 10:41:15 volumio ntpd[869]: Soliciting pool server 2404:fbc0:0:2046::c Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 10:41:16 volumio volumio[1095]: info: Retrieving Cloud Streaming UI Apr 28 10:41:16 volumio volumio[1095]: info: Getting Tidal Cloud Configuration Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 10:41:16 volumio volumio[1095]: info: Getting Qobuz Cloud Configuration Apr 28 10:41:16 volumio volumio[1095]: info: Asking plugin for UI Config Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 10:41:16 volumio volumio[1095]: info: Getting Spotify Cloud Configuration Apr 28 10:41:16 volumio volumio[1095]: info: Asking plugin for UI Config Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 10:41:16 volumio volumio[1095]: info: Saving Spotify Acccount Apr 28 10:41:16 volumio volumio[1095]: info: Got it Apr 28 10:41:16 volumio volumio[1095]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 28 10:41:16 volumio volumio[1095]: info: Got Tidal Cloud Configuration Apr 28 10:41:16 volumio volumio[1095]: info: Got it Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::volumioGetBrowseSources Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::volumioGetBrowseSources Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::volumioGetBrowseSources Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 10:41:16 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 28 10:41:19 volumio volumio[1095]: info: Disabling MyMusic plugin upnp Apr 28 10:41:19 volumio sudo[1650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Apr 28 10:41:19 volumio sudo[1650]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 10:41:19 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Apr 28 10:41:19 volumio volumio[1095]: error: Upnp client error: Error: This socket has been ended by the other party Apr 28 10:41:20 volumio ntpd[869]: receive: Unexpected origin timestamp 0xebb9775f.f1f9d430 does not match aorg 0000000000.00000000 from server@103.70.115.65 xmt 0xebb97760.c8554d50 Apr 28 10:41:20 volumio ntpd[869]: receive: Unexpected origin timestamp 0xebb9775f.f1fae21e does not match aorg 0000000000.00000000 from server@103.199.19.135 xmt 0xebb97760.c7748d83 Apr 28 10:41:21 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 10:41:24 volumio volumio[1095]: info: Enabling MyMusic plugin upnp Apr 28 10:41:24 volumio volumio[1095]: info: Enabling plugin upnp Apr 28 10:41:24 volumio volumio[1095]: info: Loading plugin "upnp"... Apr 28 10:41:24 volumio volumio[1095]: info: [1745811684756] Starting Upmpd Daemon Apr 28 10:41:24 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 10:41:24 volumio volumio[1095]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 10:41:24 volumio volumio[1095]: Error: listen EADDRINUSE: address already in use :::6599 Apr 28 10:41:24 volumio volumio[1095]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Apr 28 10:41:24 volumio volumio[1095]: at listenInCluster (net.js:1366:12) Apr 28 10:41:24 volumio volumio[1095]: at Server.listen (net.js:1452:7) Apr 28 10:41:24 volumio volumio[1095]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Apr 28 10:41:24 volumio volumio[1095]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Apr 28 10:41:24 volumio volumio[1095]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Apr 28 10:41:24 volumio volumio[1095]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 28 10:41:24 volumio volumio[1095]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Apr 28 10:41:24 volumio volumio[1095]: code: 'EADDRINUSE', Apr 28 10:41:24 volumio volumio[1095]: errno: -98, Apr 28 10:41:24 volumio volumio[1095]: syscall: 'listen', Apr 28 10:41:24 volumio volumio[1095]: address: '::', Apr 28 10:41:24 volumio volumio[1095]: port: 6599 Apr 28 10:41:24 volumio volumio[1095]: } Apr 28 10:41:24 volumio volumio[1095]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 10:41:25 volumio sudo[1675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-28 10:40 Apr 28 10:41:25 volumio sudo[1675]: pam_unix(sudo:session): session opened for user root by (uid=0) 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"