-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2025-07-03 11:07:46 CEST. -- Jul 03 11:06:00 volumio4hh systemd-timedated[1082]: Changed local time to Thu Jul 3 11:06:00 2025 Jul 03 11:06:00 volumio4hh sudo[1080]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:00 volumio4hh volumio-time-update[649]: volumio-time-update-util: System time updated successfully. Jul 03 11:06:00 volumio4hh systemd[1]: Started Volumio Time Update Utility. Jul 03 11:06:00 volumio4hh ntpd[969]: Soliciting pool server 178.63.67.56 Jul 03 11:06:00 volumio4hh ntpd[969]: Soliciting pool server 46.4.54.78 Jul 03 11:06:00 volumio4hh wireless.js[636]: trying... Jul 03 11:06:00 volumio4hh sudo[1087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 11:06:00 volumio4hh sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:00 volumio4hh sudo[1087]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:01 volumio4hh ntpd[969]: Soliciting pool server 78.47.93.191 Jul 03 11:06:01 volumio4hh ntpd[969]: Soliciting pool server 3.121.254.221 Jul 03 11:06:01 volumio4hh wireless.js[636]: trying... Jul 03 11:06:01 volumio4hh sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 11:06:01 volumio4hh sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:01 volumio4hh sudo[1090]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:02 volumio4hh ntpd[969]: Soliciting pool server 2a14:7c0:1002:1024:: Jul 03 11:06:02 volumio4hh wireless.js[636]: trying... Jul 03 11:06:02 volumio4hh sudo[1093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 11:06:02 volumio4hh sudo[1093]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:02 volumio4hh sudo[1093]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:03 volumio4hh dhcpcd[1026]: wlan0: leased 192.168.178.26 for 864000 seconds Jul 03 11:06:03 volumio4hh avahi-daemon[650]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.26. Jul 03 11:06:03 volumio4hh dhcpcd[1026]: wlan0: adding route to 192.168.178.0/24 Jul 03 11:06:03 volumio4hh dhcpcd[1026]: wlan0: adding default route via 192.168.178.1 Jul 03 11:06:03 volumio4hh avahi-daemon[650]: New relevant interface wlan0.IPv4 for mDNS. Jul 03 11:06:03 volumio4hh avahi-daemon[650]: Registering new address record for 192.168.178.26 on wlan0.IPv4. Jul 03 11:06:03 volumio4hh ntpd[969]: ntpd exiting on signal 15 (Terminated) Jul 03 11:06:03 volumio4hh systemd[1]: Stopping Network Time Service... Jul 03 11:06:03 volumio4hh ntpd[969]: 192.168.178.1 local addr 192.168.178.28 -> Jul 03 11:06:03 volumio4hh ntpd[969]: 148.251.235.164 local addr 192.168.178.28 -> Jul 03 11:06:03 volumio4hh ntpd[969]: 185.13.148.71 local addr 192.168.178.28 -> Jul 03 11:06:03 volumio4hh ntpd[969]: 144.91.126.59 local addr 192.168.178.28 -> Jul 03 11:06:03 volumio4hh ntpd[969]: 130.162.237.177 local addr 192.168.178.28 -> Jul 03 11:06:03 volumio4hh ntpd[969]: 144.76.59.37 local addr 192.168.178.28 -> Jul 03 11:06:03 volumio4hh ntpd[969]: 37.114.40.20 local addr 192.168.178.28 -> Jul 03 11:06:03 volumio4hh ntpd[969]: 188.174.253.188 local addr 192.168.178.28 -> Jul 03 11:06:03 volumio4hh ntpd[969]: 178.63.67.56 local addr 192.168.178.28 -> Jul 03 11:06:03 volumio4hh ntpd[969]: 46.4.54.78 local addr 192.168.178.28 -> Jul 03 11:06:03 volumio4hh ntpd[969]: 3.121.254.221 local addr 192.168.178.28 -> Jul 03 11:06:03 volumio4hh systemd[1]: ntp.service: Succeeded. Jul 03 11:06:03 volumio4hh systemd[1]: Stopped Network Time Service. Jul 03 11:06:03 volumio4hh systemd[1]: Starting Network Time Service... Jul 03 11:06:03 volumio4hh ntpd[1127]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 03 11:06:03 volumio4hh ntpd[1127]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Jul 03 11:06:03 volumio4hh systemd[1]: Started Network Time Service. Jul 03 11:06:03 volumio4hh ntpd[1133]: proto: precision = 0.815 usec (-20) Jul 03 11:06:03 volumio4hh ntpd[1133]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 03 11:06:03 volumio4hh ntpd[1133]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 03 11:06:03 volumio4hh ntpd[1133]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 188 days ago Jul 03 11:06:03 volumio4hh ntpd[1133]: Listen and drop on 0 v6wildcard [::]:123 Jul 03 11:06:03 volumio4hh ntpd[1133]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 03 11:06:03 volumio4hh ntpd[1133]: Listen normally on 2 lo 127.0.0.1:123 Jul 03 11:06:03 volumio4hh ntpd[1133]: Listen normally on 3 eth0 192.168.178.28:123 Jul 03 11:06:03 volumio4hh ntpd[1133]: Listen normally on 4 wlan0 192.168.178.26:123 Jul 03 11:06:03 volumio4hh ntpd[1133]: Listening on routing socket on fd #21 for interface updates Jul 03 11:06:03 volumio4hh ntpd[1133]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 03 11:06:03 volumio4hh ntpd[1133]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 03 11:06:03 volumio4hh wireless.js[636]: trying... Jul 03 11:06:03 volumio4hh sudo[1136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 11:06:03 volumio4hh sudo[1136]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:03 volumio4hh sudo[1136]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:03 volumio4hh wireless.js[636]: Connected to: ----Agitrah5024 Jul 03 11:06:03 volumio4hh wireless.js[636]: ---- Jul 03 11:06:03 volumio4hh sudo[1139]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 11:06:03 volumio4hh sudo[1139]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:03 volumio4hh sudo[1139]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:03 volumio4hh wireless.js[636]: ... joined AP, wlan0 IPv4 is 192.168.178.26, ipV6 is undefined Jul 03 11:06:03 volumio4hh wireless.js[636]: It's done! AP Jul 03 11:06:04 volumio4hh kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 03 11:06:04 volumio4hh systemd[1]: Started Wireless Services. Jul 03 11:06:04 volumio4hh systemd[1]: Started Volumio Backend Module. Jul 03 11:06:04 volumio4hh systemd[1]: Started Volumio Cpu Tweaker. Jul 03 11:06:04 volumio4hh systemd[1]: Reached target Multi-User System. Jul 03 11:06:04 volumio4hh systemd[1]: Reached target Graphical Interface. Jul 03 11:06:04 volumio4hh systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 03 11:06:04 volumio4hh systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 03 11:06:04 volumio4hh systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 03 11:06:04 volumio4hh systemd[1]: Startup finished in 13.359s (kernel) + 24.560s (userspace) = 37.919s. Jul 03 11:06:04 volumio4hh volumio-cpu-tweak[1145]: Setting RT Priority for mpd Jul 03 11:06:04 volumio4hh volumio-cpu-tweak[1145]: Setting MPD Affinity Jul 03 11:06:04 volumio4hh volumio-cpu-tweak[1145]: pid 820's current affinity mask: f Jul 03 11:06:04 volumio4hh volumio-cpu-tweak[1145]: pid 820's new affinity mask: 3 Jul 03 11:06:04 volumio4hh volumio-cpu-tweak[1145]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jul 03 11:06:04 volumio4hh volumio-cpu-tweak[1145]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jul 03 11:06:04 volumio4hh systemd[1]: volumio_cpu_tweak.service: Succeeded. Jul 03 11:06:04 volumio4hh ntpd[1133]: Soliciting pool server 148.251.235.164 Jul 03 11:06:05 volumio4hh ntpd[1133]: Soliciting pool server 185.13.148.71 Jul 03 11:06:05 volumio4hh ntpd[1133]: Soliciting pool server 185.13.148.71 Jul 03 11:06:05 volumio4hh volumio[1144]: info: ------------------------------------------- Jul 03 11:06:05 volumio4hh volumio[1144]: info: ----- Volumio3 ---- Jul 03 11:06:05 volumio4hh volumio[1144]: info: ------------------------------------------- Jul 03 11:06:05 volumio4hh volumio[1144]: info: ----- System startup ---- Jul 03 11:06:05 volumio4hh volumio[1144]: info: ------------------------------------------- Jul 03 11:06:06 volumio4hh volumio[1144]: info: MYVOLUMIO Environment detected Jul 03 11:06:06 volumio4hh ntpd[1133]: Soliciting pool server 144.91.126.59 Jul 03 11:06:06 volumio4hh ntpd[1133]: Soliciting pool server 130.162.237.177 Jul 03 11:06:06 volumio4hh volumio[1144]: info: Plugin folders cleanup Jul 03 11:06:06 volumio4hh volumio[1144]: info: Scanning into folder /volumio/app/plugins/ Jul 03 11:06:06 volumio4hh volumio[1144]: info: Scanning category audio_interface Jul 03 11:06:06 volumio4hh volumio[1144]: info: Scanning category miscellanea Jul 03 11:06:06 volumio4hh volumio[1144]: info: Scanning category music_service Jul 03 11:06:06 volumio4hh volumio[1144]: info: Scanning category plugins.json Jul 03 11:06:06 volumio4hh volumio[1144]: info: Scanning category system_controller Jul 03 11:06:06 volumio4hh volumio[1144]: info: Scanning category user_interface Jul 03 11:06:06 volumio4hh volumio[1144]: info: Scanning into folder /data/plugins/ Jul 03 11:06:06 volumio4hh volumio[1144]: info: Scanning category audio_interface Jul 03 11:06:06 volumio4hh volumio[1144]: info: Scanning category system_hardware Jul 03 11:06:06 volumio4hh volumio[1144]: info: Scanning category user_interface Jul 03 11:06:06 volumio4hh volumio[1144]: info: Plugin folders cleanup completed Jul 03 11:06:06 volumio4hh volumio[1144]: info: ------------------------------------------- Jul 03 11:06:06 volumio4hh volumio[1144]: info: ----- Core plugins startup ---- Jul 03 11:06:06 volumio4hh volumio[1144]: info: ------------------------------------------- Jul 03 11:06:06 volumio4hh volumio[1144]: info: Loading plugins from folder /volumio/app/plugins/ Jul 03 11:06:06 volumio4hh volumio[1144]: info: Adding plugin upnp to MyMusic Plugins Jul 03 11:06:06 volumio4hh volumio[1144]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 03 11:06:06 volumio4hh volumio[1144]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 03 11:06:06 volumio4hh volumio[1144]: info: Loading plugins from folder /data/plugins/ Jul 03 11:06:06 volumio4hh volumio[1144]: info: Loading plugin "system"... Jul 03 11:06:06 volumio4hh volumio[1144]: info: Loading plugin "appearance"... Jul 03 11:06:07 volumio4hh volumio-remote-updater[653]: [2025-07-03 11:06:07] [connect] Successful connection Jul 03 11:06:07 volumio4hh ntpd[1133]: Soliciting pool server 144.76.59.37 Jul 03 11:06:07 volumio4hh ntpd[1133]: Soliciting pool server 37.114.40.20 Jul 03 11:06:07 volumio4hh ntpd[1133]: Soliciting pool server 188.174.253.188 Jul 03 11:06:07 volumio4hh volumio[1144]: info: Loading plugin "network"... Jul 03 11:06:07 volumio4hh volumio[1144]: info: Refreshing Cached IP Addresses Jul 03 11:06:07 volumio4hh sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 11:06:07 volumio4hh sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:07 volumio4hh sudo[1176]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:07 volumio4hh sudo[1178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 11:06:07 volumio4hh sudo[1178]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:07 volumio4hh sudo[1178]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:07 volumio4hh volumio[1144]: info: Loading plugin "services"... Jul 03 11:06:07 volumio4hh volumio[1144]: info: Loading plugin "alsa_controller"... Jul 03 11:06:07 volumio4hh sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 03 11:06:07 volumio4hh sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:07 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 11:06:07 volumio4hh volumio[1144]: info: Loading plugin "wizard"... Jul 03 11:06:07 volumio4hh volumio[1144]: info: Loading plugin "networkfs"... Jul 03 11:06:07 volumio4hh volumio[1144]: info: Starting Udev Watcher for removable devices Jul 03 11:06:07 volumio4hh sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=HH_Musik,password=tigram52,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //fritz.box/fritz.nas\\ZNVMe2\\NAS_Alben /mnt/NAS/ZNVMe2 Jul 03 11:06:07 volumio4hh sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:07 volumio4hh volumio[1144]: info: Ignoring mount for partition: boot Jul 03 11:06:07 volumio4hh volumio[1144]: info: Ignoring mount for partition: volumio Jul 03 11:06:07 volumio4hh volumio[1144]: info: Ignoring mount for partition: volumio_data Jul 03 11:06:07 volumio4hh volumio[1144]: info: Mounting Device Volume Jul 03 11:06:08 volumio4hh sudo[1212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Volume -o noatime Jul 03 11:06:08 volumio4hh sudo[1212]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:08 volumio4hh kernel: Key type cifs.spnego registered Jul 03 11:06:08 volumio4hh kernel: Key type cifs.idmap registered Jul 03 11:06:08 volumio4hh 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. Jul 03 11:06:08 volumio4hh kernel: CIFS: Attempting to mount //fritz.box/fritz.nas/ZNVMe2/NAS_Alben Jul 03 11:06:08 volumio4hh sudo[1212]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:08 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 11:06:08 volumio4hh volumio[1144]: info: Loading plugin "volumio_command_line_client"... Jul 03 11:06:08 volumio4hh volumio[1144]: info: Loading plugin "upnp"... Jul 03 11:06:08 volumio4hh volumio[1144]: info: [1751533568148] Starting Upmpd Daemon Jul 03 11:06:08 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 11:06:08 volumio4hh volumio[1144]: info: Loading plugin "my_music"... Jul 03 11:06:08 volumio4hh volumio[1144]: info: Loading plugin "mpd"... Jul 03 11:06:08 volumio4hh kernel: cryptd: max_cpu_qlen set to 1000 Jul 03 11:06:08 volumio4hh volumio[1144]: info: Loading plugin "upnp_browser"... Jul 03 11:06:08 volumio4hh ntpd[1133]: Soliciting pool server 178.63.67.56 Jul 03 11:06:08 volumio4hh ntpd[1133]: Soliciting pool server 46.4.54.78 Jul 03 11:06:08 volumio4hh volumio[1144]: info: Loading plugin "alarm-clock"... Jul 03 11:06:08 volumio4hh sudo[1202]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:08 volumio4hh volumio[1144]: info: Loading plugin "airplay_emulation"... Jul 03 11:06:08 volumio4hh volumio[1144]: info: Starting Shairport Sync Jul 03 11:06:08 volumio4hh volumio[1144]: info: Loading plugin "last_100"... Jul 03 11:06:08 volumio4hh volumio[1144]: info: Loading plugin "webradio"... Jul 03 11:06:09 volumio4hh volumio[1144]: info: Loading plugin "i2s_dacs"... Jul 03 11:06:09 volumio4hh volumio[1144]: info: Loading plugin "volumiodiscovery"... Jul 03 11:06:09 volumio4hh volumio[1144]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 03 11:06:09 volumio4hh volumio[1144]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 11:06:09 volumio4hh volumio[1144]: *** WARNING *** For more information see Jul 03 11:06:09 volumio4hh volumio[1144]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 03 11:06:09 volumio4hh volumio[1144]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 11:06:09 volumio4hh volumio[1144]: *** WARNING *** For more information see Jul 03 11:06:09 volumio4hh node[1144]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 03 11:06:09 volumio4hh node[1144]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 11:06:09 volumio4hh node[1144]: *** WARNING *** For more information see Jul 03 11:06:09 volumio4hh node[1144]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 03 11:06:09 volumio4hh node[1144]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 11:06:09 volumio4hh node[1144]: *** WARNING *** For more information see Jul 03 11:06:09 volumio4hh volumio[1144]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 03 11:06:09 volumio4hh volumio[1144]: info: Discovery: Started advertising with name: volumio4hh Jul 03 11:06:09 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 11:06:09 volumio4hh volumio[1144]: info: Plugin peppy_screensaver is not enabled Jul 03 11:06:09 volumio4hh volumio[1144]: info: Loading plugin "outputs"... Jul 03 11:06:09 volumio4hh volumio[1144]: info: Loading plugin "albumart"... Jul 03 11:06:09 volumio4hh volumio[1144]: info: Plugin example_plugin is not enabled Jul 03 11:06:09 volumio4hh volumio[1144]: info: Loading plugin "inputs"... Jul 03 11:06:09 volumio4hh volumio[1144]: info: Loading plugin "updater_comm"... Jul 03 11:06:09 volumio4hh volumio[1144]: info: Plugin mpdemulation is not enabled Jul 03 11:06:09 volumio4hh volumio[1144]: info: Loading plugin "rest_api"... Jul 03 11:06:09 volumio4hh volumio[1144]: info: Loading plugin "websocket"... Jul 03 11:06:09 volumio4hh volumio[1144]: info: Starting Socket.io Server version 2.3.0 Jul 03 11:06:09 volumio4hh volumio[1144]: info: Plugin fusiondsp is not enabled Jul 03 11:06:09 volumio4hh volumio[1144]: info: Loading plugin "rotaryencoder2"... Jul 03 11:06:09 volumio4hh volumio[1144]: Forking 3 albumart workers Jul 03 11:06:09 volumio4hh ntpd[1133]: Soliciting pool server 78.47.93.191 Jul 03 11:06:09 volumio4hh ntpd[1133]: Soliciting pool server 3.121.254.221 Jul 03 11:06:09 volumio4hh volumio[1144]: Starting albumart workers Jul 03 11:06:09 volumio4hh volumio[1144]: Starting albumart workers Jul 03 11:06:09 volumio4hh volumio[1144]: Starting albumart workers Jul 03 11:06:10 volumio4hh volumio[1144]: info: Loading plugin "touch_display"... Jul 03 11:06:10 volumio4hh volumio[1144]: info: Applying required configuration parameters for plugin touch_display Jul 03 11:06:10 volumio4hh ntpd[1133]: Soliciting pool server 5.75.181.179 Jul 03 11:06:10 volumio4hh ntpd[1133]: Soliciting pool server 2a14:7c0:1002:1024:: Jul 03 11:06:10 volumio4hh volumio[1144]: info: Loading i18n strings for locale de Jul 03 11:06:10 volumio4hh volumio[1144]: Updating browse sources language Jul 03 11:06:10 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 11:06:10 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 11:06:10 volumio4hh volumio[1144]: info: CoreCommandRouter::initPlayerControls Jul 03 11:06:10 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:10 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:10 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:10 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:10 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:10 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:10 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:10 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:10 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 11:06:10 volumio4hh volumio[1144]: Express server listening on port 3000 Jul 03 11:06:10 volumio4hh volumio[1144]: [Metrics] WebUI: 5s 803.06ms Jul 03 11:06:10 volumio4hh volumio[1144]: info: CoreStateMachine::resetVolumioState Jul 03 11:06:10 volumio4hh volumio[1144]: info: CoreStateMachine::getcurrentVolume Jul 03 11:06:10 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioRetrievevolume Jul 03 11:06:11 volumio4hh volumio[1144]: info: Volumio Network Manager: Network status updated: 3 Jul 03 11:06:11 volumio4hh volumio-remote-updater[653]: [2025-07-03 11:06:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751533567 101 Jul 03 11:06:11 volumio4hh volumio[1144]: 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: 1 Jul 03 11:06:11 volumio4hh volumio[1144]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreStateMachine::pushState Jul 03 11:06:11 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioPushState Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreStateMachine::updateTrackBlock Jul 03 11:06:11 volumio4hh volumio[1144]: info: CorePlayQueue::getTrackBlock Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioRetrievevolume Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:11 volumio4hh volumio[1144]: info: Reloading queue from file Jul 03 11:06:11 volumio4hh volumio[1144]: info: Setting Device type: Raspberry PI Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreStateMachine::setRepeat false single undefined Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreStateMachine::pushState Jul 03 11:06:11 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioPushState Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreStateMachine::setRandom false Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreStateMachine::pushState Jul 03 11:06:11 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioPushState Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreStateMachine::pushState Jul 03 11:06:11 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioPushState Jul 03 11:06:11 volumio4hh volumio[1144]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 03 11:06:11 volumio4hh volumio[1144]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Jul 03 11:06:11 volumio4hh volumio[1144]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 03 11:06:11 volumio4hh volumio[1144]: info: Completed loading Core Plugins Jul 03 11:06:11 volumio4hh volumio[1144]: info: Preparing to generate the ALSA configuration file Jul 03 11:06:11 volumio4hh volumio[1144]: info: Asound.conf file written Jul 03 11:06:11 volumio4hh sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 03 11:06:11 volumio4hh sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:11 volumio4hh sudo[1327]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:11 volumio4hh volumio[1144]: info: Output device has changed, restarting MPD Jul 03 11:06:11 volumio4hh volumio[1144]: info: Output device has changed, restarting Shairport Sync Jul 03 11:06:11 volumio4hh sudo[1332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:11 volumio4hh sudo[1332]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:11 volumio4hh sudo[1187]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:11 volumio4hh sudo[1332]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:11 volumio4hh sudo[1334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 11:06:11 volumio4hh sudo[1334]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:11 volumio4hh systemd[1]: Stopping Music Player Daemon... Jul 03 11:06:11 volumio4hh volumio[1144]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 11:06:11 volumio4hh volumio[1144]: info: ___________ START PLUGINS ___________ Jul 03 11:06:11 volumio4hh volumio[1144]: info: ControllerMpd::onStart: Initializing MPD Jul 03 11:06:11 volumio4hh volumio[1144]: info: Creating MPD Configuration file Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 11:06:11 volumio4hh volumio[1144]: info: [1751533571463] CoreMusicLibrary::Adding element Medienserver Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 11:06:11 volumio4hh sudo[1341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 11:06:11 volumio4hh sudo[1343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 11:06:11 volumio4hh sudo[1343]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:11 volumio4hh sudo[1341]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:11 volumio4hh sudo[1341]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:11 volumio4hh volumio[1144]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:11 volumio4hh volumio[1144]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 11:06:11 volumio4hh volumio[1144]: info: [1751533571552] CoreMusicLibrary::Adding element Last_100 Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 11:06:11 volumio4hh systemd[1]: mpd.service: Succeeded. Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 11:06:11 volumio4hh volumio[1144]: info: [1751533571554] CoreMusicLibrary::Adding element Webradio Jul 03 11:06:11 volumio4hh systemd[1]: Stopped Music Player Daemon. Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 11:06:11 volumio4hh volumio[1144]: info: Initializing BBC Radios Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:11 volumio4hh volumio[1144]: info: Loading i18n strings for locale de Jul 03 11:06:11 volumio4hh systemd[1]: Starting Music Player Daemon... Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 11:06:11 volumio4hh volumio[1144]: info: Volumio Calling Home Jul 03 11:06:11 volumio4hh sudo[1352]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 03 11:06:11 volumio4hh sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:11 volumio4hh sudo[1352]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:11 volumio4hh sudo[1367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=22 pin_b=6 relative_axis=true steps-per-period=1 Jul 03 11:06:11 volumio4hh sudo[1367]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:11 volumio4hh sudo[1371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jul 03 11:06:11 volumio4hh sudo[1371]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:11 volumio4hh sudo[1374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jul 03 11:06:11 volumio4hh sudo[1374]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:11 volumio4hh sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 03 11:06:11 volumio4hh sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:11 volumio4hh systemd[1]: Reloading. Jul 03 11:06:11 volumio4hh volumio[1144]: info: Discovery: adding fd63f74b-084f-44a5-8278-126b22465393 Jul 03 11:06:11 volumio4hh volumio[1144]: info: Discovery: Found device volumio4hh Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:11 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:11 volumio4hh volumio[1144]: info: Discovery: this is already registered, fd63f74b-084f-44a5-8278-126b22465393 Jul 03 11:06:11 volumio4hh volumio[1144]: info: Discovery: Found device volumio4hh Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:11 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:11 volumio4hh volumio[1144]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Jul 03 11:06:11 volumio4hh volumio[1144]: info: MPD Permissions set Jul 03 11:06:11 volumio4hh volumio[1144]: info: MPD Permissions set Jul 03 11:06:11 volumio4hh sudo[1367]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:11 volumio4hh kernel: rotary-encoder rotary@16: gray Jul 03 11:06:11 volumio4hh kernel: input: rotary@16 as /devices/platform/rotary@16/input/input2 Jul 03 11:06:11 volumio4hh volumio[1144]: info: Volumio called home Jul 03 11:06:11 volumio4hh volumio[1144]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 03 11:06:11 volumio4hh volumio[1144]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:11 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:12 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:12 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:12 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:12 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:12 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:12 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:12 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:12 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:12 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:12 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:12 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:12 volumio4hh systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 03 11:06:12 volumio4hh systemd-udevd[1388]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 03 11:06:12 volumio4hh volumio[1144]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jul 03 11:06:12 volumio4hh sudo[1413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jul 03 11:06:12 volumio4hh volumio[1144]: info: Starting Shairport Sync Jul 03 11:06:12 volumio4hh sudo[1413]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:12 volumio4hh sudo[1413]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:12 volumio4hh volumio[1144]: info: Starting Shairport Sync Jul 03 11:06:12 volumio4hh sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 11:06:12 volumio4hh sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:12 volumio4hh volumio[1144]: info: Starting Shairport Sync Jul 03 11:06:12 volumio4hh sudo[1418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 11:06:12 volumio4hh sudo[1418]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:12 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:12 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:12 volumio4hh sudo[1421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 11:06:12 volumio4hh sudo[1421]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:12 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:12 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:12 volumio4hh volumio[1144]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jul 03 11:06:12 volumio4hh sudo[1378]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:12 volumio4hh volumio[1144]: info: touch_display: systemctl daemon-reload succeeded. Jul 03 11:06:12 volumio4hh systemd[1]: Reloading. Jul 03 11:06:12 volumio4hh sudo[1371]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:12 volumio4hh sudo[1426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jul 03 11:06:12 volumio4hh volumio[1144]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jul 03 11:06:12 volumio4hh sudo[1426]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:12 volumio4hh mpd[1369]: Jul 03 11:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 03 11:06:13 volumio4hh wpa_supplicant[903]: RRM: Ignoring radio measurement request: Not RRM network Jul 03 11:06:13 volumio4hh systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 03 11:06:14 volumio4hh sudo[1444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=13 active_low=0 gpio_pull=up keycode=20 Jul 03 11:06:14 volumio4hh sudo[1444]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:14 volumio4hh sudo[1444]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:14 volumio4hh kernel: input: button@d as /devices/platform/button@d/input/input3 Jul 03 11:06:14 volumio4hh systemd[1]: Started Music Player Daemon. Jul 03 11:06:14 volumio4hh sudo[1374]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:14 volumio4hh sudo[1334]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:14 volumio4hh sudo[1343]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:14 volumio4hh volumio[1144]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jul 03 11:06:14 volumio4hh systemd[1]: Started Volumio Kiosk. Jul 03 11:06:14 volumio4hh sudo[1426]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:14 volumio4hh systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 03 11:06:14 volumio4hh systemd[1]: shairport-sync.service: Succeeded. Jul 03 11:06:14 volumio4hh systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 03 11:06:14 volumio4hh systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 03 11:06:14 volumio4hh sudo[1418]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:14 volumio4hh sudo[1421]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:14 volumio4hh volumio[1144]: error: MPD error: The expression evaluated to a falsy value: Jul 03 11:06:14 volumio4hh volumio[1144]: assert.ok(self.idling) Jul 03 11:06:14 volumio4hh volumio[1144]: error: The expression evaluated to a falsy value: Jul 03 11:06:14 volumio4hh volumio[1144]: assert.ok(self.idling) Jul 03 11:06:14 volumio4hh sudo[1416]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:14 volumio4hh volumio[1144]: info: Shairport-Sync Started Jul 03 11:06:14 volumio4hh volumio[1144]: Error adding Membership: Error: addMembership EINVAL Jul 03 11:06:14 volumio4hh volumio[1144]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jul 03 11:06:14 volumio4hh volumio[1144]: info: touch_display: Volumio Kiosk started. Jul 03 11:06:14 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:14 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:14 volumio4hh systemd-udevd[1388]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 03 11:06:14 volumio4hh volumio[1144]: info: MPD running with PID1369 Jul 03 11:06:14 volumio4hh volumio[1144]: ,establishing connection Jul 03 11:06:14 volumio4hh volumio[1144]: error: updateQueue error: null Jul 03 11:06:14 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:14 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:14 volumio4hh volumio[1144]: info: Shairport-Sync Started Jul 03 11:06:14 volumio4hh volumio[1144]: info: Shairport-Sync Started Jul 03 11:06:14 volumio4hh volumio[1144]: error: updateQueue error: null Jul 03 11:06:14 volumio4hh volumio[1144]: info: touch_display: X display number found: Jul 03 11:06:14 volumio4hh startx[1457]: X.Org X Server 1.20.4 Jul 03 11:06:14 volumio4hh startx[1457]: X Protocol Version 11, Revision 0 Jul 03 11:06:14 volumio4hh startx[1457]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jul 03 11:06:14 volumio4hh startx[1457]: Current Operating System: Linux volumio4hh 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l Jul 03 11:06:14 volumio4hh startx[1457]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=E4:5F:01:AE:12:29 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=9b6a8fd4-d1e0-41c3-90e3-655c7141a81c imgfile=/volumio_current.sqsh bootpart=UUID=7883-9333 datapart=UUID=f0f21e6a-0d4c-4d61-b970-25cec5bd08b3 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Jul 03 11:06:14 volumio4hh startx[1457]: Build Date: 04 April 2023 07:50:56AM Jul 03 11:06:14 volumio4hh startx[1457]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jul 03 11:06:14 volumio4hh startx[1457]: Current version of pixman: 0.36.0 Jul 03 11:06:14 volumio4hh startx[1457]: Before reporting problems, check http://wiki.x.org Jul 03 11:06:14 volumio4hh startx[1457]: to make sure that you have the latest version. Jul 03 11:06:14 volumio4hh startx[1457]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 03 11:06:14 volumio4hh startx[1457]: (++) from command line, (!!) notice, (II) informational, Jul 03 11:06:14 volumio4hh startx[1457]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 03 11:06:14 volumio4hh startx[1457]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Jul 3 11:06:14 2025 Jul 03 11:06:14 volumio4hh startx[1457]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jul 03 11:06:14 volumio4hh startx[1457]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 03 11:06:14 volumio4hh volumio[1144]: info: touch_display: X display number found: 0 Jul 03 11:06:14 volumio4hh volumio[1144]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 03 11:06:14 volumio4hh volumio[1144]: info: touch_display: X display number found: 0 Jul 03 11:06:14 volumio4hh systemd[1]: systemd-fsckd.service: Succeeded. Jul 03 11:06:15 volumio4hh sudo[1492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=26 pin_b=19 relative_axis=true steps-per-period=1 Jul 03 11:06:15 volumio4hh sudo[1492]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:15 volumio4hh sudo[1492]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:15 volumio4hh kernel: rotary-encoder rotary@1a: gray Jul 03 11:06:15 volumio4hh kernel: input: rotary@1a as /devices/platform/rotary@1a/input/input4 Jul 03 11:06:15 volumio4hh systemd-udevd[1388]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 03 11:06:15 volumio4hh wpa_supplicant[903]: RRM: Ignoring radio measurement request: Not RRM network Jul 03 11:06:15 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:15 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:16 volumio4hh volumio[1144]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 03 11:06:16 volumio4hh sudo[1535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=16 active_low=0 gpio_pull=up keycode=20 Jul 03 11:06:16 volumio4hh sudo[1535]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:16 volumio4hh kernel: input: button@10 as /devices/platform/button@10/input/input5 Jul 03 11:06:16 volumio4hh sudo[1535]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:16 volumio4hh systemd-udevd[1388]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 03 11:06:17 volumio4hh sudo[1575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jul 03 11:06:17 volumio4hh sudo[1575]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:17 volumio4hh sudo[1575]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:17 volumio4hh sudo[1579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jul 03 11:06:17 volumio4hh sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:17 volumio4hh sudo[1579]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:17 volumio4hh sudo[1583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jul 03 11:06:17 volumio4hh sudo[1583]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:17 volumio4hh sudo[1583]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:17 volumio4hh systemd[1]: systemd-hostnamed.service: Succeeded. Jul 03 11:06:17 volumio4hh sudo[1587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jul 03 11:06:17 volumio4hh sudo[1587]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:17 volumio4hh sudo[1587]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:17 volumio4hh volumio[1144]: info: Completed starting Core Plugins Jul 03 11:06:17 volumio4hh volumio[1144]: info: ------------------------------------------- Jul 03 11:06:17 volumio4hh volumio[1144]: info: ----- MyVolumio plugins startup ---- Jul 03 11:06:17 volumio4hh volumio[1144]: info: ------------------------------------------- Jul 03 11:06:17 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 03 11:06:17 volumio4hh wpa_supplicant[903]: RRM: Ignoring radio measurement request: Not RRM network Jul 03 11:06:19 volumio4hh sudo[1631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 11:06:19 volumio4hh sudo[1631]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:19 volumio4hh sudo[1631]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:19 volumio4hh sudo[1633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 11:06:19 volumio4hh sudo[1633]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:19 volumio4hh sudo[1633]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:19 volumio4hh sudo[1639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 03 11:06:19 volumio4hh sudo[1639]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:19 volumio4hh systemd[1]: Started UPnP Renderer front-end to MPD. Jul 03 11:06:19 volumio4hh sudo[1639]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:19 volumio4hh volumio[1144]: info: Upmpdcli Daemon Started Jul 03 11:06:19 volumio4hh wpa_supplicant[903]: RRM: Ignoring radio measurement request: Not RRM network Jul 03 11:06:20 volumio4hh volumio[1643]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 03 11:06:20 volumio4hh dhcpcd[1026]: wlan0: carrier lost Jul 03 11:06:20 volumio4hh wpa_supplicant[903]: wlan0: Associated with b4:fc:7d:ab:59:ee Jul 03 11:06:20 volumio4hh wpa_supplicant[903]: wlan0: CTRL-EVENT-CONNECTED - Connection to b4:fc:7d:ab:59:ee completed [id=0 id_str=] Jul 03 11:06:20 volumio4hh wpa_supplicant[903]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 03 11:06:20 volumio4hh avahi-daemon[650]: Withdrawing address record for 192.168.178.26 on wlan0. Jul 03 11:06:20 volumio4hh avahi-daemon[650]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.26. Jul 03 11:06:20 volumio4hh dhcpcd[1026]: wlan0: deleting route to 192.168.178.0/24 Jul 03 11:06:20 volumio4hh avahi-daemon[650]: Interface wlan0.IPv4 no longer relevant for mDNS. Jul 03 11:06:20 volumio4hh dhcpcd[1026]: wlan0: deleting default route via 192.168.178.1 Jul 03 11:06:20 volumio4hh volumio[1144]: info: Discovery: A device disappeared from network Jul 03 11:06:20 volumio4hh volumio[1144]: info: Discovery: Device volumio4hh disappeared from network Jul 03 11:06:20 volumio4hh ntpd[1133]: ntpd exiting on signal 15 (Terminated) Jul 03 11:06:20 volumio4hh ntpd[1133]: 192.168.178.1 local addr 192.168.178.28 -> Jul 03 11:06:20 volumio4hh ntpd[1133]: 148.251.235.164 local addr 192.168.178.28 -> Jul 03 11:06:20 volumio4hh systemd[1]: Stopping Network Time Service... Jul 03 11:06:20 volumio4hh ntpd[1133]: 185.13.148.71 local addr 192.168.178.28 -> Jul 03 11:06:20 volumio4hh ntpd[1133]: 130.162.237.177 local addr 192.168.178.28 -> Jul 03 11:06:20 volumio4hh systemd[1]: ntp.service: Succeeded. Jul 03 11:06:20 volumio4hh ntpd[1133]: 144.91.126.59 local addr 192.168.178.28 -> Jul 03 11:06:20 volumio4hh ntpd[1133]: 144.76.59.37 local addr 192.168.178.28 -> Jul 03 11:06:20 volumio4hh ntpd[1133]: 37.114.40.20 local addr 192.168.178.28 -> Jul 03 11:06:20 volumio4hh ntpd[1133]: 188.174.253.188 local addr 192.168.178.28 -> Jul 03 11:06:20 volumio4hh ntpd[1133]: 178.63.67.56 local addr 192.168.178.28 -> Jul 03 11:06:20 volumio4hh ntpd[1133]: 46.4.54.78 local addr 192.168.178.28 -> Jul 03 11:06:20 volumio4hh ntpd[1133]: 78.47.93.191 local addr 192.168.178.28 -> Jul 03 11:06:20 volumio4hh systemd[1]: Stopped Network Time Service. Jul 03 11:06:20 volumio4hh ntpd[1133]: 3.121.254.221 local addr 192.168.178.28 -> Jul 03 11:06:20 volumio4hh ntpd[1133]: 5.75.181.179 local addr 192.168.178.28 -> Jul 03 11:06:20 volumio4hh systemd[1]: Starting Network Time Service... Jul 03 11:06:20 volumio4hh ntpd[1727]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 03 11:06:20 volumio4hh ntpd[1727]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Jul 03 11:06:20 volumio4hh systemd[1]: Started Network Time Service. Jul 03 11:06:20 volumio4hh ntpd[1733]: proto: precision = 0.833 usec (-20) Jul 03 11:06:20 volumio4hh ntpd[1733]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 03 11:06:20 volumio4hh ntpd[1733]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 03 11:06:20 volumio4hh ntpd[1733]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 188 days ago Jul 03 11:06:20 volumio4hh dhcpcd[1026]: wlan0: carrier acquired Jul 03 11:06:20 volumio4hh ntpd[1733]: Listen and drop on 0 v6wildcard [::]:123 Jul 03 11:06:20 volumio4hh ntpd[1733]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 03 11:06:20 volumio4hh ntpd[1733]: Listen normally on 2 lo 127.0.0.1:123 Jul 03 11:06:20 volumio4hh ntpd[1733]: Listen normally on 3 eth0 192.168.178.28:123 Jul 03 11:06:20 volumio4hh ntpd[1733]: Listening on routing socket on fd #20 for interface updates Jul 03 11:06:20 volumio4hh ntpd[1733]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 03 11:06:20 volumio4hh ntpd[1733]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 03 11:06:20 volumio4hh dhcpcd[1026]: wlan0: IAID 01:ae:12:2a Jul 03 11:06:21 volumio4hh dhcpcd[1026]: wlan0: soliciting an IPv6 router Jul 03 11:06:21 volumio4hh dhcpcd[1026]: wlan0: rebinding lease of 192.168.178.26 Jul 03 11:06:21 volumio4hh dhcpcd[1026]: wlan0: probing address 192.168.178.26/24 Jul 03 11:06:21 volumio4hh ntpd[1733]: Soliciting pool server 148.251.235.164 Jul 03 11:06:22 volumio4hh ntpd[1733]: Soliciting pool server 185.13.148.71 Jul 03 11:06:22 volumio4hh ntpd[1733]: Soliciting pool server 185.13.148.71 Jul 03 11:06:23 volumio4hh ntpd[1733]: Soliciting pool server 144.91.126.59 Jul 03 11:06:23 volumio4hh ntpd[1733]: Soliciting pool server 130.162.237.177 Jul 03 11:06:24 volumio4hh ntpd[1733]: Soliciting pool server 144.76.59.37 Jul 03 11:06:24 volumio4hh ntpd[1733]: Soliciting pool server 37.114.40.20 Jul 03 11:06:24 volumio4hh ntpd[1733]: Soliciting pool server 188.174.253.188 Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 03 11:06:25 volumio4hh volumio[1144]: info: Adding plugin bluetooth to MyMusic Plugins Jul 03 11:06:25 volumio4hh volumio[1144]: info: Adding plugin multiroom to MyMusic Plugins Jul 03 11:06:25 volumio4hh volumio[1144]: info: Adding plugin metavolumio to MyMusic Plugins Jul 03 11:06:25 volumio4hh volumio[1144]: info: Adding plugin cd_controller to MyMusic Plugins Jul 03 11:06:25 volumio4hh volumio[1144]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 03 11:06:25 volumio4hh volumio[1144]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 03 11:06:25 volumio4hh volumio[1144]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 03 11:06:25 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 03 11:06:25 volumio4hh ntpd[1733]: Soliciting pool server 178.63.67.56 Jul 03 11:06:25 volumio4hh ntpd[1733]: Soliciting pool server 46.4.54.78 Jul 03 11:06:26 volumio4hh dhcpcd[1026]: wlan0: leased 192.168.178.26 for 864000 seconds Jul 03 11:06:26 volumio4hh avahi-daemon[650]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.26. Jul 03 11:06:26 volumio4hh avahi-daemon[650]: New relevant interface wlan0.IPv4 for mDNS. Jul 03 11:06:26 volumio4hh dhcpcd[1026]: wlan0: adding route to 192.168.178.0/24 Jul 03 11:06:26 volumio4hh dhcpcd[1026]: wlan0: adding default route via 192.168.178.1 Jul 03 11:06:26 volumio4hh avahi-daemon[650]: Registering new address record for 192.168.178.26 on wlan0.IPv4. Jul 03 11:06:26 volumio4hh ntpd[1733]: ntpd exiting on signal 15 (Terminated) Jul 03 11:06:26 volumio4hh ntpd[1733]: 192.168.178.1 local addr 192.168.178.28 -> Jul 03 11:06:26 volumio4hh ntpd[1733]: 148.251.235.164 local addr 192.168.178.28 -> Jul 03 11:06:26 volumio4hh ntpd[1733]: 185.13.148.71 local addr 192.168.178.28 -> Jul 03 11:06:26 volumio4hh systemd[1]: Stopping Network Time Service... Jul 03 11:06:26 volumio4hh ntpd[1733]: 144.91.126.59 local addr 192.168.178.28 -> Jul 03 11:06:26 volumio4hh ntpd[1733]: 130.162.237.177 local addr 192.168.178.28 -> Jul 03 11:06:26 volumio4hh ntpd[1733]: 188.174.253.188 local addr 192.168.178.28 -> Jul 03 11:06:26 volumio4hh ntpd[1733]: 144.76.59.37 local addr 192.168.178.28 -> Jul 03 11:06:26 volumio4hh ntpd[1733]: 37.114.40.20 local addr 192.168.178.28 -> Jul 03 11:06:26 volumio4hh ntpd[1733]: 178.63.67.56 local addr 192.168.178.28 -> Jul 03 11:06:26 volumio4hh systemd[1]: ntp.service: Succeeded. Jul 03 11:06:26 volumio4hh ntpd[1733]: 46.4.54.78 local addr 192.168.178.28 -> Jul 03 11:06:26 volumio4hh systemd[1]: Stopped Network Time Service. Jul 03 11:06:26 volumio4hh systemd[1]: Starting Network Time Service... Jul 03 11:06:26 volumio4hh ntpd[1856]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 03 11:06:26 volumio4hh ntpd[1856]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Jul 03 11:06:26 volumio4hh systemd[1]: Started Network Time Service. Jul 03 11:06:26 volumio4hh ntpd[1862]: proto: precision = 0.796 usec (-20) Jul 03 11:06:26 volumio4hh ntpd[1862]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 03 11:06:26 volumio4hh ntpd[1862]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 03 11:06:26 volumio4hh ntpd[1862]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 188 days ago Jul 03 11:06:26 volumio4hh ntpd[1862]: Listen and drop on 0 v6wildcard [::]:123 Jul 03 11:06:26 volumio4hh ntpd[1862]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 03 11:06:26 volumio4hh ntpd[1862]: Listen normally on 2 lo 127.0.0.1:123 Jul 03 11:06:26 volumio4hh ntpd[1862]: Listen normally on 3 eth0 192.168.178.28:123 Jul 03 11:06:26 volumio4hh ntpd[1862]: Listen normally on 4 wlan0 192.168.178.26:123 Jul 03 11:06:26 volumio4hh ntpd[1862]: Listening on routing socket on fd #21 for interface updates Jul 03 11:06:26 volumio4hh ntpd[1862]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 03 11:06:26 volumio4hh ntpd[1862]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 03 11:06:26 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 03 11:06:26 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 03 11:06:26 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:26 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:26 volumio4hh volumio[1144]: info: Starting MyVolumio Remote Streaming Endpoints Jul 03 11:06:26 volumio4hh volumio[1144]: info: MyVolumio login type: Token Jul 03 11:06:26 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 03 11:06:26 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 03 11:06:27 volumio4hh ntpd[1862]: Soliciting pool server 148.251.235.164 Jul 03 11:06:27 volumio4hh volumio[1643]: ................................................................................++++ Jul 03 11:06:27 volumio4hh volumio[1144]: info: Starting Streaming Service Transparent Proxy Jul 03 11:06:27 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 03 11:06:27 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 03 11:06:27 volumio4hh volumio[1144]: info: Streaming services startup Jul 03 11:06:27 volumio4hh volumio[1144]: info: Starting Streaming Daemon Jul 03 11:06:27 volumio4hh sudo[1871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 03 11:06:27 volumio4hh sudo[1871]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:27 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 03 11:06:27 volumio4hh sudo[1871]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:27 volumio4hh volumio[1144]: error: Cannot start Volumio Streaming Daemon Jul 03 11:06:27 volumio4hh volumio[1144]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 03 11:06:27 volumio4hh volumio[1144]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 03 11:06:27 volumio4hh volumio[1144]: info: Discovery: adding fd63f74b-084f-44a5-8278-126b22465393 Jul 03 11:06:27 volumio4hh volumio[1144]: info: Discovery: Found device volumio4hh Jul 03 11:06:27 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:27 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:28 volumio4hh volumio[1144]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 03 11:06:28 volumio4hh volumio[1144]: STREAMING PROXY: Starting server on port 3245 Jul 03 11:06:28 volumio4hh volumio[1144]: Node JS runtime: 14 Jul 03 11:06:28 volumio4hh ntpd[1862]: Soliciting pool server 185.13.148.71 Jul 03 11:06:28 volumio4hh ntpd[1862]: Soliciting pool server 185.13.148.71 Jul 03 11:06:28 volumio4hh volumio[1144]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jul 03 11:06:29 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 03 11:06:29 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 03 11:06:29 volumio4hh ntpd[1862]: Soliciting pool server 144.91.126.59 Jul 03 11:06:29 volumio4hh ntpd[1862]: Soliciting pool server 130.162.237.177 Jul 03 11:06:29 volumio4hh volumio[1144]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jul 03 11:06:30 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 03 11:06:30 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 03 11:06:30 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetVisibleSources Jul 03 11:06:30 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 11:06:30 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:30 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:30 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 03 11:06:30 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 03 11:06:30 volumio4hh volumio[1144]: info: Received Get System Info Jul 03 11:06:30 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 11:06:30 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 11:06:30 volumio4hh volumio[1144]: info: Discovery: Getting this device information Jul 03 11:06:30 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:30 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:30 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 11:06:30 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:30 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:30 volumio4hh volumio[1144]: info: Listing playlists Jul 03 11:06:30 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 03 11:06:30 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 11:06:30 volumio4hh volumio[1144]: info: MyVolumio token set successfully Jul 03 11:06:30 volumio4hh volumio[1144]: info: MYVOLUMIO: Adding device Jul 03 11:06:30 volumio4hh volumio[1144]: info: MYVOLUMIO: Evaluating Server Jul 03 11:06:30 volumio4hh ntpd[1862]: Soliciting pool server 144.76.59.37 Jul 03 11:06:30 volumio4hh ntpd[1862]: Soliciting pool server 37.114.40.20 Jul 03 11:06:30 volumio4hh ntpd[1862]: Soliciting pool server 188.174.253.188 Jul 03 11:06:30 volumio4hh volumio[1144]: info: MyVolumio Plan changed: premium Jul 03 11:06:30 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jul 03 11:06:30 volumio4hh volumio[1144]: info: Removing browser output: myVolumio user plan is not superstar Jul 03 11:06:30 volumio4hh volumio[1144]: info: Removing audio output: Jul 03 11:06:30 volumio4hh volumio[1144]: info: MYVOLUMIO: Adding device Jul 03 11:06:30 volumio4hh volumio[1144]: info: MYVOLUMIO: Evaluating Server Jul 03 11:06:30 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:30 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:30 volumio4hh volumio[1144]: info: Remote config written successfully Jul 03 11:06:30 volumio4hh volumio[1144]: info: Starting Tunnel 1 Jul 03 11:06:30 volumio4hh volumio[1144]: info: Starting Tunnel Connection Checker Jul 03 11:06:30 volumio4hh volumio[1144]: info: Completed starting MyVolumio Plugin Jul 03 11:06:31 volumio4hh systemd[1]: systemd-timedated.service: Succeeded. Jul 03 11:06:31 volumio4hh volumio[1144]: info: MYVolumio Device enabled Jul 03 11:06:31 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jul 03 11:06:31 volumio4hh volumio[1144]: info: MyVolumio status changed Jul 03 11:06:31 volumio4hh volumio[1144]: info: Streaming services startup Jul 03 11:06:31 volumio4hh volumio[1144]: info: Starting Streaming Daemon Jul 03 11:06:31 volumio4hh volumio[1144]: info: Setting Geolocation for MyVolumio to eu6 Jul 03 11:06:31 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:31 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:31 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:31 volumio4hh sudo[1932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 03 11:06:31 volumio4hh sudo[1932]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:31 volumio4hh sudo[1932]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:31 volumio4hh volumio[1144]: error: Cannot start Volumio Streaming Daemon Jul 03 11:06:31 volumio4hh volumio[1144]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 03 11:06:31 volumio4hh volumio[1144]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 03 11:06:31 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jul 03 11:06:31 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jul 03 11:06:31 volumio4hh volumio[1144]: info: [1751533591415] Starting BluetoothController Jul 03 11:06:31 volumio4hh ntpd[1862]: Soliciting pool server 178.63.67.56 Jul 03 11:06:31 volumio4hh ntpd[1862]: Soliciting pool server 46.4.54.78 Jul 03 11:06:31 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jul 03 11:06:32 volumio4hh ntpd[1862]: Soliciting pool server 78.47.93.191 Jul 03 11:06:32 volumio4hh ntpd[1862]: Soliciting pool server 3.121.254.221 Jul 03 11:06:32 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jul 03 11:06:32 volumio4hh sudo[1935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jul 03 11:06:32 volumio4hh sudo[1935]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:32 volumio4hh sudo[1935]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:32 volumio4hh volumio[1144]: info: MRS: MultiRoom plugin initialized Jul 03 11:06:32 volumio4hh volumio[1144]: info: MRS: STOPPING SNAPCLIENT Jul 03 11:06:32 volumio4hh volumio[1144]: info: MRS: Snap server stop Jul 03 11:06:32 volumio4hh sudo[1951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jul 03 11:06:32 volumio4hh sudo[1951]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:32 volumio4hh volumio[1144]: info: MRS: STOPPING volumioStreaming Jul 03 11:06:32 volumio4hh sudo[1954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jul 03 11:06:32 volumio4hh sudo[1954]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:32 volumio4hh sudo[1957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jul 03 11:06:32 volumio4hh sudo[1957]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:32 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jul 03 11:06:32 volumio4hh sudo[1951]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:32 volumio4hh sudo[1960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jul 03 11:06:32 volumio4hh sudo[1960]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:32 volumio4hh sudo[1957]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:32 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jul 03 11:06:32 volumio4hh sudo[1960]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:32 volumio4hh sudo[1954]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:32 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jul 03 11:06:32 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jul 03 11:06:32 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jul 03 11:06:32 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jul 03 11:06:32 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jul 03 11:06:32 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jul 03 11:06:33 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jul 03 11:06:33 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Jul 03 11:06:33 volumio4hh volumio[1144]: info: Preparing to generate the ALSA configuration file Jul 03 11:06:33 volumio4hh volumio[1144]: info: Updating MyVolumio device info Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:33 volumio4hh volumio[1144]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 03 11:06:33 volumio4hh volumio[1144]: info: Reading ALSA contributions from plugins. Jul 03 11:06:33 volumio4hh volumio[1144]: info: Setting Geolocation for MyVolumio to eu6 Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 11:06:33 volumio4hh volumio[1144]: info: Received Get System Info Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 11:06:33 volumio4hh volumio[1144]: info: Discovery: Getting this device information Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:33 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 03 11:06:33 volumio4hh volumio[1144]: info: MRS: Removed streaming files Jul 03 11:06:33 volumio4hh volumio[1144]: info: MRS: volumioStreaming STOPPED Jul 03 11:06:33 volumio4hh volumio[1144]: info: MRS: SNAPSERVER STOPPED Jul 03 11:06:33 volumio4hh volumio[1144]: info: MRS: SNAPCLIENT STOPPED Jul 03 11:06:33 volumio4hh volumio[1144]: info: Asound.conf file written Jul 03 11:06:33 volumio4hh sudo[1963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 03 11:06:33 volumio4hh sudo[1963]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:33 volumio4hh sudo[1963]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:33 volumio4hh volumio[1144]: info: Output device has changed, restarting MPD Jul 03 11:06:33 volumio4hh volumio[1144]: info: Output device has changed, restarting Shairport Sync Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:33 volumio4hh sudo[1968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 11:06:33 volumio4hh sudo[1968]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:33 volumio4hh sudo[1968]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:33 volumio4hh sudo[1970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 11:06:33 volumio4hh sudo[1970]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:33 volumio4hh systemd[1]: Stopping Music Player Daemon... Jul 03 11:06:33 volumio4hh volumio[1144]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 11:06:33 volumio4hh volumio[1144]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:33 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 11:06:33 volumio4hh systemd[1]: mpd.service: Succeeded. Jul 03 11:06:33 volumio4hh systemd[1]: Stopped Music Player Daemon. Jul 03 11:06:33 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jul 03 11:06:33 volumio4hh volumio[1144]: info: MRS: Pushing multiroomSync output for this device Jul 03 11:06:33 volumio4hh volumio[1144]: info: MRS: Pushing multiroomSync output Jul 03 11:06:33 volumio4hh volumio[1144]: info: Adding audio output: Jul 03 11:06:33 volumio4hh volumio[1144]: info: Adding audio output: Jul 03 11:06:33 volumio4hh sudo[1980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name volumio4hh Jul 03 11:06:33 volumio4hh sudo[1980]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:33 volumio4hh systemd[1]: Starting Music Player Daemon... Jul 03 11:06:33 volumio4hh volumio[1144]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:33 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jul 03 11:06:33 volumio4hh volumio[1144]: info: Adding METAVOLUMIO REST API Endpoints Jul 03 11:06:33 volumio4hh volumio[1144]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jul 03 11:06:33 volumio4hh volumio[1144]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jul 03 11:06:33 volumio4hh volumio[1144]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jul 03 11:06:33 volumio4hh volumio[1144]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jul 03 11:06:33 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jul 03 11:06:33 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jul 03 11:06:33 volumio4hh volumio[1144]: info: Preparing CD Folders Jul 03 11:06:33 volumio4hh volumio[1144]: info: Adding CD REST API Endpoints Jul 03 11:06:33 volumio4hh volumio[1144]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jul 03 11:06:33 volumio4hh volumio[1144]: info: Starting UDEV Watcher for CD Jul 03 11:06:33 volumio4hh volumio[1144]: info: Detecting CD presence with UDEV Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jul 03 11:06:33 volumio4hh sudo[1980]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:33 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jul 03 11:06:33 volumio4hh volumio[1144]: info: Adding inputs REST Endpoints Jul 03 11:06:33 volumio4hh volumio[1144]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jul 03 11:06:33 volumio4hh volumio[1144]: info: Scanning Audio Inputs Jul 03 11:06:33 volumio4hh volumio[1144]: info: Checking against Known Cards name Jul 03 11:06:33 volumio4hh volumio[1144]: info: Adding Server instance for streaming Jul 03 11:06:33 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jul 03 11:06:33 volumio4hh volumio[1144]: error: Hi Res Audio Failed Login: Missing Login Data Jul 03 11:06:33 volumio4hh volumio[1144]: info: Adding HIGHRESAUDIO REST API Endpoints Jul 03 11:06:33 volumio4hh volumio[1144]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jul 03 11:06:33 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jul 03 11:06:33 volumio4hh volumio[1144]: info: Refreshing TIDAL token Jul 03 11:06:33 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jul 03 11:06:33 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jul 03 11:06:33 volumio4hh sudo[1979]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 03 11:06:33 volumio4hh sudo[1979]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:33 volumio4hh sudo[1979]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:33 volumio4hh volumio[1144]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jul 03 11:06:33 volumio4hh volumio[1144]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jul 03 11:06:33 volumio4hh ntpd[1862]: Soliciting pool server 5.75.181.179 Jul 03 11:06:33 volumio4hh ntpd[1862]: Soliciting pool server 2a14:7c0:1002:1024:: Jul 03 11:06:33 volumio4hh sudo[1990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 03 11:06:33 volumio4hh sudo[1990]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:33 volumio4hh volumio[1144]: info: QobuzConnect: Starting Qobuz Connect socket and service Jul 03 11:06:33 volumio4hh sudo[1990]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:33 volumio4hh sudo[1997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jul 03 11:06:33 volumio4hh volumio[1144]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jul 03 11:06:33 volumio4hh volumio[1144]: info: Stopping AccessToken refresher cron for QOBUZ Jul 03 11:06:33 volumio4hh sudo[1997]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:33 volumio4hh sudo[1997]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:33 volumio4hh volumio[1144]: info: AccessToken refresher cron started for QOBUZ Jul 03 11:06:33 volumio4hh volumio[1144]: info: Adding QOBUZ REST API Endpoints Jul 03 11:06:33 volumio4hh volumio[1144]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jul 03 11:06:33 volumio4hh sudo[1999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jul 03 11:06:33 volumio4hh sudo[1999]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:33 volumio4hh volumio[1144]: info: Successfully Added MyVolumio device Jul 03 11:06:33 volumio4hh volumio[1144]: info: Bluetooth name changed to volumio4hh Jul 03 11:06:33 volumio4hh volumio[1144]: info: MPD Permissions set Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:33 volumio4hh systemd[1]: Started Volumio Qobuz Connect Service. Jul 03 11:06:33 volumio4hh sudo[1999]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:33 volumio4hh volumio[1144]: info: Executing endpoint qc_getconfig Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jul 03 11:06:33 volumio4hh qobuz-connect[2001]: 20250703 11:06:33.906 [2001.2001] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jul 03 11:06:33 volumio4hh volumio[1144]: info: Starting Shairport Sync Jul 03 11:06:33 volumio4hh volumio[1144]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jul 03 11:06:33 volumio4hh volumio[1144]: info: QobuzConnect: QOBUZ Connect daemon connected Jul 03 11:06:33 volumio4hh sudo[2008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 11:06:33 volumio4hh sudo[2008]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:33 volumio4hh volumio[1144]: info: Successfully Updated MyVolumio device Jul 03 11:06:33 volumio4hh volumio[1144]: info: Executing endpoint metavolumio Jul 03 11:06:33 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 03 11:06:34 volumio4hh systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 03 11:06:34 volumio4hh systemd[1]: shairport-sync.service: Succeeded. Jul 03 11:06:34 volumio4hh systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 03 11:06:34 volumio4hh volumio[1144]: info: Executing endpoint metavolumio Jul 03 11:06:34 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 03 11:06:34 volumio4hh volumio[1144]: info: Executing endpoint metavolumio Jul 03 11:06:34 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 03 11:06:34 volumio4hh qobuz-connect[2001]: 20250703 11:06:34.040 [2001.2001] INFO VolumeManager: [0x158b910]: Setting new playback volume: 75 Jul 03 11:06:34 volumio4hh qobuz-connect[2001]: 20250703 11:06:34.040 [2001.2001] INFO VolumeManager: [0x158b910]: Setting new mute state: 0 Jul 03 11:06:34 volumio4hh qobuz-connect[2001]: 20250703 11:06:34.040 [2001.2001] INFO QobuzConnect: [0x158c2e0]: Client initialized! Jul 03 11:06:34 volumio4hh qobuz-connect[2001]: 20250703 11:06:34.040 [2001.2001] INFO SampleApp: Starting Avahi advertising, name: volumio4hh, service name: _qobuz-connect._tcp Jul 03 11:06:34 volumio4hh volumio[1144]: info: Access Token successfully retrieved Jul 03 11:06:34 volumio4hh qobuz-connect[2001]: 20250703 11:06:34.052 [2001.2001] INFO LocalConfigManager: [0x158b1f0]: Starting Local Configuration server Jul 03 11:06:34 volumio4hh qobuz-connect[2001]: 20250703 11:06:34.052 [2001.2001] INFO SampleApp: Starting Local configuration server Jul 03 11:06:34 volumio4hh qobuz-connect[2001]: 20250703 11:06:34.053 [2001.2001] INFO SampleApp: Connected to UNIX socket client 0x1580f60 Jul 03 11:06:34 volumio4hh qobuz-connect[2001]: 20250703 11:06:34.057 [2001.2001] INFO SampleApp: Playback volume changed: 75 Jul 03 11:06:34 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:34 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:34 volumio4hh systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 03 11:06:34 volumio4hh sudo[2008]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:34 volumio4hh volumio[1144]: info: Shairport-Sync Started Jul 03 11:06:34 volumio4hh volumio[1144]: info: Updating MyVolumio device info Jul 03 11:06:34 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:34 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:34 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:34 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 11:06:34 volumio4hh volumio[1144]: info: Received Get System Info Jul 03 11:06:34 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 11:06:34 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 11:06:34 volumio4hh volumio[1144]: info: Discovery: Getting this device information Jul 03 11:06:34 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:34 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:34 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 11:06:34 volumio4hh volumio[1144]: info: Successfully retrieved User Session From TIDAL Jul 03 11:06:34 volumio4hh mpd[1989]: Jul 03 11:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 03 11:06:34 volumio4hh systemd[1]: Started Music Player Daemon. Jul 03 11:06:34 volumio4hh sudo[1970]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:34 volumio4hh volumio[1144]: info: Successfully Added MyVolumio device Jul 03 11:06:34 volumio4hh volumio[1144]: error: updateQueue error: null Jul 03 11:06:34 volumio4hh volumio[1144]: info: Successfully retrieved User Subscription From TIDAL Jul 03 11:06:34 volumio4hh volumio[1144]: info: Adding TIDAL to Browse Sources Jul 03 11:06:34 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 11:06:34 volumio4hh volumio[1144]: info: [1751533594461] CoreMusicLibrary::Adding element TIDAL Jul 03 11:06:34 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 11:06:34 volumio4hh volumio[1144]: Cannot find translation for source TIDAL Jul 03 11:06:34 volumio4hh volumio[1144]: info: Stopping AccessToken refresher cron Jul 03 11:06:34 volumio4hh volumio[1144]: info: AccessToken refresher cron started Jul 03 11:06:34 volumio4hh volumio[1144]: info: Adding TIDAL REST API Endpoints Jul 03 11:06:34 volumio4hh volumio[1144]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jul 03 11:06:34 volumio4hh volumio[1144]: info: Successfully Updated MyVolumio device Jul 03 11:06:35 volumio4hh volumio[1643]: .....................................................................................++++ Jul 03 11:06:35 volumio4hh volumio[1643]: e is 65537 (0x010001) Jul 03 11:06:35 volumio4hh volumio[1643]: writing RSA key Jul 03 11:06:36 volumio4hh sudo[2212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jul 03 11:06:36 volumio4hh sudo[2212]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:36 volumio4hh systemd[1]: Started Volumio Bluetooth Module. Jul 03 11:06:36 volumio4hh sudo[2212]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:36 volumio4hh volumio[1144]: info: Volumio BT Module successfully started Jul 03 11:06:36 volumio4hh volumio[1144]: info: TidalConnect service stoped! Jul 03 11:06:36 volumio4hh sudo[2216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jul 03 11:06:36 volumio4hh sudo[2216]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:36 volumio4hh volumiobt[2214]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jul 03 11:06:36 volumio4hh sudo[2216]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:36 volumio4hh volumio[1144]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 03 11:06:36 volumio4hh volumio[1144]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 03 11:06:36 volumio4hh sudo[2227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 03 11:06:36 volumio4hh sudo[2227]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:36 volumio4hh systemd[1]: Started Volumio Tidal Connect Service. Jul 03 11:06:36 volumio4hh sudo[2227]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:36 volumio4hh sudo[2233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jul 03 11:06:36 volumio4hh sudo[2233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:36 volumio4hh systemd[1]: Started MyVolumio SSH Tunnel. Jul 03 11:06:36 volumio4hh sudo[2233]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:36 volumio4hh volumio[1144]: info: Remote SSH Started Jul 03 11:06:36 volumio4hh autossh[2236]: port set to 0, monitoring disabled Jul 03 11:06:36 volumio4hh autossh[2236]: starting ssh (count 1) Jul 03 11:06:36 volumio4hh autossh[2236]: ssh child pid is 2239 Jul 03 11:06:37 volumio4hh volumio[1144]: info: Executing endpoint tc_getconfig Jul 03 11:06:37 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 03 11:06:37 volumio4hh vtcs[2229]: STARTING TidalConnect services, version: 1.5.1-beta1 Jul 03 11:06:37 volumio4hh volumio[1144]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 03 11:06:37 volumio4hh vtcs[2229]: STARTED TidalConnect services. Jul 03 11:06:37 volumio4hh volumio[1144]: info: Executing endpoint tc_connect Jul 03 11:06:37 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 03 11:06:37 volumio4hh volumio[1144]: info: Connecting to TidalConnect Jul 03 11:06:37 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:37 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:37 volumio4hh volumio[1144]: info: CoreCommandRouter::servicePushState Jul 03 11:06:37 volumio4hh volumio[1144]: info: CoreStateMachine::pushState Jul 03 11:06:37 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:37 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioPushState Jul 03 11:06:37 volumio4hh volumio[1144]: info: MRS: Pushing multiroomSync output update for this device Jul 03 11:06:37 volumio4hh volumio[1144]: info: MRS: Pushing multiroomSync output Jul 03 11:06:37 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:37 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:37 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:37 volumio4hh volumio[1144]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jul 03 11:06:37 volumio4hh volumio[1144]: info: CoreCommandRouter::servicePushState Jul 03 11:06:37 volumio4hh volumio[1144]: info: CoreStateMachine::pushState Jul 03 11:06:37 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:37 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioPushState Jul 03 11:06:37 volumio4hh volumio[1144]: info: MRS: Pushing multiroomSync output update for this device Jul 03 11:06:37 volumio4hh volumio[1144]: info: MRS: Pushing multiroomSync output Jul 03 11:06:37 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:37 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:37 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:37 volumio4hh volumio[1144]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jul 03 11:06:37 volumio4hh volumiossh-tunnel[2235]: Warning: Permanently added '[eu6.myvolumio.org]:2222,[207.154.233.230]:2222' (RSA) to the list of known hosts. Jul 03 11:06:37 volumio4hh sudo[2256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jul 03 11:06:37 volumio4hh sudo[2256]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:37 volumio4hh volumiobt[2214]: pulseaudio: no process found Jul 03 11:06:37 volumio4hh sudo[2256]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:37 volumio4hh dbus-daemon[604]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.30' (uid=1000 pid=2260 comm="/usr/bin/pulseaudio --high-priority=true ") Jul 03 11:06:37 volumio4hh systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jul 03 11:06:37 volumio4hh volumio[1144]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 03 11:06:37 volumio4hh dbus-daemon[604]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jul 03 11:06:37 volumio4hh systemd[1]: Started RealtimeKit Scheduling Policy Service. Jul 03 11:06:37 volumio4hh rtkit-daemon[2261]: Successfully called chroot. Jul 03 11:06:37 volumio4hh rtkit-daemon[2261]: Successfully dropped privileges. Jul 03 11:06:37 volumio4hh rtkit-daemon[2261]: Successfully limited resources. Jul 03 11:06:37 volumio4hh rtkit-daemon[2261]: Running. Jul 03 11:06:37 volumio4hh rtkit-daemon[2261]: Canary thread running. Jul 03 11:06:37 volumio4hh rtkit-daemon[2261]: Watchdog thread running. Jul 03 11:06:37 volumio4hh dbus-daemon[604]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.31' (uid=0 pid=2261 comm="/usr/lib/rtkit/rtkit-daemon ") Jul 03 11:06:37 volumio4hh systemd[1]: Starting Authorization Manager... Jul 03 11:06:37 volumio4hh volumio[1144]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 03 11:06:37 volumio4hh polkitd[2265]: started daemon version 0.105 using authority implementation `local' version `0.105' Jul 03 11:06:37 volumio4hh dbus-daemon[604]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jul 03 11:06:37 volumio4hh systemd[1]: Started Authorization Manager. Jul 03 11:06:38 volumio4hh pulseaudio[2260]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 03 11:06:38 volumio4hh pulseaudio[2260]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 03 11:06:38 volumio4hh bluetoothd[771]: Endpoint registered: sender=:1.51 path=/MediaEndpoint/A2DPSource Jul 03 11:06:38 volumio4hh bluetoothd[771]: Endpoint registered: sender=:1.51 path=/MediaEndpoint/A2DPSink Jul 03 11:06:38 volumio4hh volumiobt[2214]: Applying permissions Jul 03 11:06:38 volumio4hh volumiobt[2214]: Setting BT discoverable and pairable Jul 03 11:06:38 volumio4hh pulseaudio[2260]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jul 03 11:06:38 volumio4hh kernel: Bluetooth: RFCOMM TTY layer initialized Jul 03 11:06:38 volumio4hh kernel: Bluetooth: RFCOMM socket layer initialized Jul 03 11:06:38 volumio4hh kernel: Bluetooth: RFCOMM ver 1.11 Jul 03 11:06:38 volumio4hh volumiobt[2214]: [CHG] Controller E4:5F:01:AE:12:2B UUIDs: 00001112-0000-1000-8000-00805f9b34fb Jul 03 11:06:38 volumio4hh volumiobt[2214]: [117B blob data] Jul 03 11:06:38 volumio4hh volumiobt[2214]: [117B blob data] Jul 03 11:06:38 volumio4hh volumiobt[2214]: [117B blob data] Jul 03 11:06:38 volumio4hh volumiobt[2214]: [117B blob data] Jul 03 11:06:38 volumio4hh volumiobt[2214]: [117B blob data] Jul 03 11:06:38 volumio4hh volumiobt[2214]: [117B blob data] Jul 03 11:06:38 volumio4hh volumiobt[2214]: [117B blob data] Jul 03 11:06:38 volumio4hh volumiobt[2214]: [117B blob data] Jul 03 11:06:38 volumio4hh volumiobt[2214]: [117B blob data] Jul 03 11:06:38 volumio4hh volumiobt[2214]: [117B blob data] Jul 03 11:06:38 volumio4hh volumiobt[2214]: [117B blob data] Jul 03 11:06:38 volumio4hh volumiobt[2214]: [117B blob data] Jul 03 11:06:38 volumio4hh volumiobt[2214]: [117B blob data] Jul 03 11:06:38 volumio4hh volumiobt[2214]: [117B blob data] Jul 03 11:06:38 volumio4hh volumiobt[2214]: [117B blob data] Jul 03 11:06:38 volumio4hh volumiobt[2214]: [117B blob data] Jul 03 11:06:38 volumio4hh volumiobt[2214]: [117B blob data] Jul 03 11:06:38 volumio4hh volumiobt[2214]: [55B blob data] Jul 03 11:06:38 volumio4hh volumiobt[2214]: [bluetooth]# -e power on Jul 03 11:06:38 volumio4hh volumiobt[2214]: Invalid command in menu main: -e Jul 03 11:06:38 volumio4hh volumiobt[2214]: Jul 03 11:06:38 volumio4hh volumiobt[2214]: Use "help" for a list of available commands in a menu. Jul 03 11:06:38 volumio4hh volumiobt[2214]: Use "menu " if you want to enter any submenu. Jul 03 11:06:38 volumio4hh volumiobt[2214]: Use "back" if you want to return to menu main. Jul 03 11:06:38 volumio4hh volumiobt[2214]: [bluetooth]# agent on Jul 03 11:06:38 volumio4hh volumiobt[2214]: Agent is already registered Jul 03 11:06:38 volumio4hh volumiobt[2214]: [bluetooth]# discoverable on Jul 03 11:06:38 volumio4hh volumiobt[2214]: [bluetooth]# pairable on Jul 03 11:06:38 volumio4hh volumiobt[2214]: [bluetooth]# agent NoInputNoOutput Jul 03 11:06:38 volumio4hh volumiobt[2214]: Agent is already registered Jul 03 11:06:38 volumio4hh volumiobt[2214]: [bluetooth]# default-agent Jul 03 11:06:38 volumio4hh volumiobt[2214]: [bluetooth]# quit Jul 03 11:06:38 volumio4hh volumiobt[2214]: [59B blob data] Jul 03 11:06:38 volumio4hh volumio[1144]: info: MRS: Getting audio outputs on start Jul 03 11:06:38 volumio4hh volumio[1144]: info: MRS: Requesting all other devices output Jul 03 11:06:39 volumio4hh volumio[1144]: error: Failed request for metavolumio API Jul 03 11:06:39 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 03 11:06:39 volumio4hh volumio[1144]: info: TidalConnect service started! Jul 03 11:06:39 volumio4hh volumio[1144]: [Metrics] CommandRouter: 32s 853.12ms Jul 03 11:06:39 volumio4hh volumio[1144]: info: CoreCommandRouter::volumiosetStartupVolume Jul 03 11:06:39 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:39 volumio4hh volumio[1144]: info: VolumeController:: Setting startup Volume 100 Jul 03 11:06:39 volumio4hh volumio[1144]: info: VolumeController::SetAlsaVolume100 Jul 03 11:06:39 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:39 volumio4hh volumio[1144]: info: CoreCommandRouter::Close All Modals sent Jul 03 11:06:39 volumio4hh volumio[1144]: info: CoreCommandRouter::Close All Modals sent Jul 03 11:06:39 volumio4hh volumio[1144]: info: CoreStateMachine::pushState Jul 03 11:06:39 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:39 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 11:06:39 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioPushState Jul 03 11:06:39 volumio4hh volumio[1144]: info: MRS: Pushing multiroomSync output update for this device Jul 03 11:06:39 volumio4hh volumio[1144]: info: MRS: Pushing multiroomSync output Jul 03 11:06:39 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:39 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:40 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 03 11:06:40 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 03 11:06:40 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 03 11:06:41 volumio4hh sudo[2295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 11:06:41 volumio4hh sudo[2295]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:41 volumio4hh volumio[1144]: error: Failed request for metavolumio API Jul 03 11:06:41 volumio4hh sudo[2295]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:41 volumio4hh sudo[2298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 11:06:41 volumio4hh sudo[2298]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:06:41 volumio4hh sudo[2298]: pam_unix(sudo:session): session closed for user root Jul 03 11:06:41 volumio4hh volumio[1144]: verbose: New Socket.io Connection to 192.168.178.28 from 192.168.178.22 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 8 Jul 03 11:06:41 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 03 11:06:41 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetVisibleSources Jul 03 11:06:41 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 11:06:41 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:41 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:41 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 03 11:06:41 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 03 11:06:41 volumio4hh volumio[1144]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 03 11:06:41 volumio4hh volumio[1144]: info: Received Get System Info Jul 03 11:06:41 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 11:06:41 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 11:06:41 volumio4hh volumio[1144]: info: Discovery: Getting this device information Jul 03 11:06:41 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:41 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:41 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 11:06:41 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:41 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:41 volumio4hh volumio[1144]: info: Listing playlists Jul 03 11:06:41 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 03 11:06:41 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 11:06:41 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 03 11:06:41 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 11:06:41 volumio4hh volumio[1144]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jul 03 11:06:41 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:41 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:42 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:42 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:42 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 11:06:42 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 11:06:42 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 03 11:06:42 volumio4hh volumio-remote-updater[653]: No test mode Jul 03 11:06:42 volumio4hh volumio-remote-updater[653]: No alpha test mode Jul 03 11:06:42 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 03 11:06:42 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 03 11:06:42 volumio4hh volumio[1144]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 03 11:06:42 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 03 11:06:43 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 11:06:43 volumio4hh volumio[1144]: info: Received Get System Info Jul 03 11:06:43 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 11:06:43 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 11:06:43 volumio4hh volumio[1144]: info: Discovery: Getting this device information Jul 03 11:06:43 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:43 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:43 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 11:06:43 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 11:06:43 volumio4hh volumio[1144]: info: Received Get System Info Jul 03 11:06:43 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 11:06:43 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 11:06:43 volumio4hh volumio[1144]: info: Discovery: Getting this device information Jul 03 11:06:43 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:43 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:43 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 11:06:44 volumio4hh volumio[1144]: info: Executing endpoint metavolumio Jul 03 11:06:44 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 03 11:06:44 volumio4hh volumio[1144]: info: Executing endpoint metavolumio Jul 03 11:06:44 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 03 11:06:44 volumio4hh volumio[1144]: info: Executing endpoint metavolumio Jul 03 11:06:44 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 03 11:06:44 volumio4hh kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 3 ep 1 Jul 03 11:06:44 volumio4hh kernel: xhci_hcd 0000:01:00.0: @000000042951a830 00000000 00000000 0e000000 03028000 Jul 03 11:06:46 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetQueue Jul 03 11:06:46 volumio4hh volumio[1144]: info: CoreStateMachine::getQueue Jul 03 11:06:46 volumio4hh volumio[1144]: info: CorePlayQueue::getQueue Jul 03 11:06:46 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:46 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 11:06:46 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 03 11:06:46 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 03 11:06:46 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 03 11:06:46 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetBrowseSources Jul 03 11:06:46 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 11:06:46 volumio4hh volumio[1144]: info: BOOT COMPLETED Jul 03 11:06:47 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 03 11:06:47 volumio4hh volumio[1144]: info: Preload queue cleared Jul 03 11:06:47 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 11:06:47 volumio4hh volumio[1144]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 03 11:06:47 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 03 11:06:47 volumio4hh volumio[1144]: info: Received Get System Version Jul 03 11:06:47 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 11:06:47 volumio4hh volumio[1144]: info: Received Get System Info Jul 03 11:06:47 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 11:06:47 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 11:06:47 volumio4hh volumio[1144]: info: Discovery: Getting this device information Jul 03 11:06:47 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:47 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:47 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 11:06:49 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 03 11:06:50 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 03 11:06:50 volumio4hh volumio[1144]: info: Preload queue cleared Jul 03 11:06:50 volumio4hh volumio[1144]: info: Preload queue cleared Jul 03 11:06:51 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 03 11:06:56 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 03 11:06:56 volumio4hh volumio[1144]: info: CURURI: music-library Jul 03 11:06:56 volumio4hh volumio[1144]: info: Preload queue cleared Jul 03 11:06:59 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 11:06:59 volumio4hh volumio[1144]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 03 11:06:59 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 03 11:06:59 volumio4hh volumio[1144]: info: Received Get System Version Jul 03 11:06:59 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 11:06:59 volumio4hh volumio[1144]: info: Received Get System Info Jul 03 11:06:59 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 11:06:59 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 11:06:59 volumio4hh volumio[1144]: info: Discovery: Getting this device information Jul 03 11:06:59 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:06:59 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:06:59 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 11:07:22 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:07:22 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:07:24 volumio4hh volumio[1144]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 11:07:24 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 03 11:07:24 volumio4hh volumio[1144]: Disk list : [] Jul 03 11:07:24 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 03 11:07:24 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 03 11:07:24 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jul 03 11:07:24 volumio4hh volumio[1144]: info: Received Get System Version Jul 03 11:07:24 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 11:07:24 volumio4hh volumio[1144]: info: Executing endpoint metavolumio Jul 03 11:07:24 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 03 11:07:24 volumio4hh volumio[1144]: info: Executing endpoint metavolumio Jul 03 11:07:24 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 03 11:07:24 volumio4hh volumio[1144]: info: Executing endpoint metavolumio Jul 03 11:07:24 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 03 11:07:35 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetState Jul 03 11:07:35 volumio4hh volumio[1144]: info: CorePlayQueue::getTrack 0 Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 11:07:37 volumio4hh volumio[1144]: info: Retrieving Cloud Streaming UI Jul 03 11:07:37 volumio4hh volumio[1144]: info: Getting Tidal Cloud Configuration Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 11:07:37 volumio4hh volumio[1144]: info: Getting Qobuz Cloud Configuration Jul 03 11:07:37 volumio4hh volumio[1144]: info: Asking plugin for UI Config Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 11:07:37 volumio4hh volumio[1144]: info: Getting Spotify Cloud Configuration Jul 03 11:07:37 volumio4hh volumio[1144]: info: Asking plugin for UI Config Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 11:07:37 volumio4hh volumio[1144]: info: Saving Spotify Acccount Jul 03 11:07:37 volumio4hh volumio[1144]: info: Got it Jul 03 11:07:37 volumio4hh volumio[1144]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 03 11:07:37 volumio4hh volumio[1144]: info: Got Tidal Cloud Configuration Jul 03 11:07:37 volumio4hh volumio[1144]: info: Got it Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetBrowseSources Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetBrowseSources Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::volumioGetBrowseSources Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 03 11:07:37 volumio4hh volumio[1144]: info: Executing endpoint metavolumio Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 03 11:07:37 volumio4hh volumio[1144]: info: Executing endpoint metavolumio Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 03 11:07:37 volumio4hh volumio[1144]: info: Executing endpoint metavolumio Jul 03 11:07:37 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 03 11:07:41 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 03 11:07:43 volumio4hh volumio[1144]: error: Failed request for metavolumio API Jul 03 11:07:44 volumio4hh volumio[1144]: info: Disabling MyMusic plugin upnp Jul 03 11:07:44 volumio4hh sudo[2457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jul 03 11:07:44 volumio4hh sudo[2457]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 11:07:44 volumio4hh systemd[1]: Stopping UPnP Renderer front-end to MPD... Jul 03 11:07:44 volumio4hh volumio[1144]: error: Upnp client error: Error: This socket has been ended by the other party Jul 03 11:07:44 volumio4hh volumio[1144]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 03 11:07:45 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 03 11:07:45 volumio4hh volumio[1144]: info: Enabling MyMusic plugin upnp Jul 03 11:07:45 volumio4hh volumio[1144]: info: Enabling plugin upnp Jul 03 11:07:45 volumio4hh volumio[1144]: info: Loading plugin "upnp"... Jul 03 11:07:45 volumio4hh volumio[1144]: info: [1751533665664] Starting Upmpd Daemon Jul 03 11:07:45 volumio4hh volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 11:07:45 volumio4hh volumio[1144]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 11:07:45 volumio4hh volumio[1144]: Error: listen EADDRINUSE: address already in use :::6599 Jul 03 11:07:45 volumio4hh volumio[1144]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Jul 03 11:07:45 volumio4hh volumio[1144]: at listenInCluster (net.js:1366:12) Jul 03 11:07:45 volumio4hh volumio[1144]: at Server.listen (net.js:1452:7) Jul 03 11:07:45 volumio4hh volumio[1144]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jul 03 11:07:45 volumio4hh volumio[1144]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jul 03 11:07:45 volumio4hh volumio[1144]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jul 03 11:07:45 volumio4hh volumio[1144]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 03 11:07:45 volumio4hh volumio[1144]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jul 03 11:07:45 volumio4hh volumio[1144]: code: 'EADDRINUSE', Jul 03 11:07:45 volumio4hh volumio[1144]: errno: -98, Jul 03 11:07:45 volumio4hh volumio[1144]: syscall: 'listen', Jul 03 11:07:45 volumio4hh volumio[1144]: address: '::', Jul 03 11:07:45 volumio4hh volumio[1144]: port: 6599 Jul 03 11:07:45 volumio4hh volumio[1144]: } Jul 03 11:07:45 volumio4hh volumio[1144]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 11:07:46 volumio4hh sudo[2509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-03 11:06 Jul 03 11:07:46 volumio4hh sudo[2509]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"