-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2026-01-12 09:45:19 CET. -- Jan 12 09:44:12 volumio systemd-timedated[1117]: Changed local time to Mon Jan 12 09:44:12 2026 Jan 12 09:44:12 volumio sudo[1080]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:12 volumio volumio-time-update[635]: volumio-time-update-util: System time updated successfully. Jan 12 09:44:12 volumio systemd[1]: Started OLED Display Service for EVO SABRE. Jan 12 09:44:12 volumio sudo[1113]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 12 09:44:12 volumio sudo[1108]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:12 volumio sudo[1133]: root : TTY=unknown ; PWD=/data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled ; USER=root ; COMMAND=/usr/bin/node /data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled/index.js volumio Jan 12 09:44:12 volumio sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:12 volumio systemd[1]: Started Volumio Time Update Utility. Jan 12 09:44:12 volumio volumio[858]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success Jan 12 09:44:12 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 12 09:44:12 volumio systemd[1]: mpd.service: Succeeded. Jan 12 09:44:12 volumio systemd[1]: Stopped Music Player Daemon. Jan 12 09:44:12 volumio sudo[1104]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:12 volumio sudo[1106]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:12 volumio volumio[858]: info: Shairport-Sync Started Jan 12 09:44:12 volumio volumio[858]: Error adding Membership: Error: addMembership EINVAL Jan 12 09:44:12 volumio volumio[858]: info: Shairport-Sync Started Jan 12 09:44:12 volumio volumio[858]: info: Shairport-Sync Started Jan 12 09:44:12 volumio sudo[1130]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:12 volumio systemd[1]: Starting Music Player Daemon... Jan 12 09:44:12 volumio sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_remote.service Jan 12 09:44:12 volumio sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:12 volumio volumio[858]: info: Shairport-Sync Started Jan 12 09:44:12 volumio systemd[1]: Starting lircd(8) initialization helper tool... Jan 12 09:44:12 volumio sudo[1144]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 12 09:44:12 volumio sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:12 volumio sudo[1144]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 12 09:44:12 volumio sudo[1144]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:12 volumio systemd[1]: Started Samba NMB Daemon. Jan 12 09:44:12 volumio nmbd[752]: [2026/01/12 09:44:12.303662, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 12 09:44:12 volumio nmbd[752]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 12 09:44:12 volumio systemd[1]: Starting Samba Winbind Daemon... Jan 12 09:44:12 volumio winbindd[1151]: [2026/01/12 09:44:12.785748, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 12 09:44:12 volumio winbindd[1151]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 12 09:44:12 volumio winbindd[1151]: [2026/01/12 09:44:12.984544, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 12 09:44:12 volumio systemd[1]: Started Samba Winbind Daemon. Jan 12 09:44:12 volumio winbindd[1151]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 12 09:44:13 volumio volumio[858]: info: camilladsp spawned new process with pid 1162, instance 1, run: true Jan 12 09:44:13 volumio volumio[858]: info: camilladsp service started and running in background, instance 1 Jan 12 09:44:13 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:44:13 volumio systemd[1]: Starting Samba SMB Daemon... Jan 12 09:44:13 volumio volumio[858]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Jan 12 09:44:13 volumio volumio[858]: info: FusionDsp loaded Jan 12 09:44:13 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 09:44:13 volumio sudo[1167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 12 09:44:13 volumio sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:13 volumio volumio[858]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 12 09:44:13 volumio volumio[858]: info: Adding Signal Path Element [object Object] Jan 12 09:44:13 volumio volumio[858]: info: Adding fusiondspeq DSP Signal Path Element Jan 12 09:44:13 volumio volumio[858]: info: FusionDsp - ---- installed callbackRead Jan 12 09:44:13 volumio volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 12 09:44:13 volumio volumio[858]: info: FusionDsp - Effects disabled Jan 12 09:44:13 volumio volumio[858]: error: FusionDsp - WebSocket error: [object Object] Jan 12 09:44:13 volumio volumio[858]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 12 09:44:13 volumio systemd[1]: Started FusionDsp Daemon. Jan 12 09:44:13 volumio lircd-setup[1148]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Jan 12 09:44:13 volumio lircd-setup[1148]: parser = configparser.SafeConfigParser() Jan 12 09:44:13 volumio sudo[1167]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:13 volumio systemd[1]: lircd-setup.service: Succeeded. Jan 12 09:44:13 volumio systemd[1]: Started lircd(8) initialization helper tool. Jan 12 09:44:13 volumio systemd[1]: Started evo_remote.service. Jan 12 09:44:13 volumio sudo[1143]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:13 volumio volumio[858]: info: EVO SABRE : systemd cmd restart evo_remote.service : success Jan 12 09:44:13 volumio sudo[1178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_irexec.service Jan 12 09:44:13 volumio sudo[1178]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:13 volumio systemd[1]: Starting lircd(8) initialization helper tool... Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Info: lircd: Opening log, level: Info Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Version: lircd 0.10.1 Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: System info: Linux volumio 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l GNU/Linux Jan 12 09:44:13 volumio lircd-setup[1180]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Jan 12 09:44:13 volumio lircd-setup[1180]: parser = configparser.SafeConfigParser() Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Info: Initial device: /dev/lirc0 Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Info: Initial device: /dev/lirc0 Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Info: [lirc] protocol is enabled Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Options: driver: default Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Options: output: /var/run/lirc/lircd Jan 12 09:44:13 volumio lircd[1176]: lircd-0.10.1[1176]: Info: lircd: Opening log, level: Info Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Options: nodaemon: 1 Jan 12 09:44:13 volumio lircd[1176]: lircd-0.10.1[1176]: Warning: Running as root Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Options: logfile: syslog Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Options: immediate-init: 0 Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Options: permission: 666 Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Options: driver-options: Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Options: listen: 0 Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Options: connect: (null) Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Options: userelease: 0 Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Options: effective_user: (null) Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Options: release_suffix: _EVUP Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Options: allow_simulate: 0 Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Options: repeat_max: 600 Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Options: configfile: /data/plugins/system_hardware/audiophonics_evo_sabre/apps/lirc/lircd.conf Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Options: dynamic_codes: (null) Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Current driver: default Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Driver API version: 3 Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Driver version: 0.10.0 Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Info: lircd: Opening log, level: Info Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Warning: Running as root Jan 12 09:44:13 volumio lircd[1176]: lircd-0.10.1[1176]: Info: Using remote: ApEvo. Jan 12 09:44:13 volumio lircd[1176]: lircd-0.10.1[1176]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Info: Using remote: ApEvo. Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jan 12 09:44:13 volumio systemd[1]: lircd-setup.service: Succeeded. Jan 12 09:44:13 volumio systemd[1]: Started lircd(8) initialization helper tool. Jan 12 09:44:13 volumio systemd[1]: Started evo_irexec.service. Jan 12 09:44:13 volumio sudo[1178]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:13 volumio volumio[858]: info: EVO SABRE : systemd cmd restart evo_irexec.service : success Jan 12 09:44:13 volumio lircd[1176]: lircd-0.10.1[1176]: Notice: accepted new client on /var/run/lirc/lircd Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Notice: accepted new client on /var/run/lirc/lircd Jan 12 09:44:13 volumio lircd[1176]: lircd-0.10.1[1176]: Info: [lirc] protocol is enabled Jan 12 09:44:13 volumio lircd-0.10.1[1176]: Info: [lirc] protocol is enabled Jan 12 09:44:14 volumio smbd[1165]: [2026/01/12 09:44:14.402857, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 12 09:44:14 volumio systemd[1]: Started Samba SMB Daemon. Jan 12 09:44:14 volumio smbd[1165]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 12 09:44:14 volumio systemd[1]: Reached target Multi-User System. Jan 12 09:44:14 volumio systemd[1]: Reached target Graphical Interface. Jan 12 09:44:14 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 12 09:44:14 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 12 09:44:14 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 12 09:44:14 volumio volumio[858]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jan 12 09:44:14 volumio volumio[858]: info: CoreCommandRouter::volumioGetState Jan 12 09:44:14 volumio volumio[858]: info: CorePlayQueue::getTrack 0 Jan 12 09:44:14 volumio volumio[858]: info: CoreCommandRouter::volumioGetQueue Jan 12 09:44:14 volumio volumio[858]: info: CoreStateMachine::getQueue Jan 12 09:44:14 volumio volumio[858]: info: CorePlayQueue::getQueue Jan 12 09:44:14 volumio volumio[858]: info: CoreCommandRouter::volumioGetState Jan 12 09:44:14 volumio volumio[858]: info: CorePlayQueue::getTrack 0 Jan 12 09:44:14 volumio mpd[1150]: Jan 12 09:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 12 09:44:14 volumio systemd[1]: Started Music Player Daemon. Jan 12 09:44:14 volumio systemd[1]: Startup finished in 13.638s (kernel) + 19.294s (userspace) = 32.933s. Jan 12 09:44:14 volumio sudo[1120]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:15 volumio sudo[1034]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:15 volumio sudo[1044]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:15 volumio volumio[858]: info: Completed starting Core Plugins Jan 12 09:44:15 volumio volumio[858]: info: ------------------------------------------- Jan 12 09:44:15 volumio volumio[858]: info: ----- MyVolumio plugins startup ---- Jan 12 09:44:15 volumio volumio[858]: info: ------------------------------------------- Jan 12 09:44:15 volumio volumio[858]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 12 09:44:15 volumio volumio[858]: error: MPD error: The expression evaluated to a falsy value: Jan 12 09:44:15 volumio volumio[858]: assert.ok(self.idling) Jan 12 09:44:15 volumio volumio[858]: error: The expression evaluated to a falsy value: Jan 12 09:44:15 volumio volumio[858]: assert.ok(self.idling) Jan 12 09:44:15 volumio volumio[858]: error: MPD error: The expression evaluated to a falsy value: Jan 12 09:44:15 volumio volumio[858]: assert.ok(self.idling) Jan 12 09:44:15 volumio volumio[858]: error: The expression evaluated to a falsy value: Jan 12 09:44:15 volumio volumio[858]: assert.ok(self.idling) Jan 12 09:44:15 volumio volumio[858]: info: MPD running with PID1150 Jan 12 09:44:15 volumio volumio[858]: ,establishing connection Jan 12 09:44:15 volumio volumio[858]: error: updateQueue error: null Jan 12 09:44:15 volumio volumio[858]: error: updateQueue error: null Jan 12 09:44:15 volumio sudo[1201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.2/Musik /mnt/NAS/Musik Jan 12 09:44:15 volumio sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:15 volumio sudo[1203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.0.2:/mnt/user/Musik /mnt/NAS/Musik Jan 12 09:44:15 volumio sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:15 volumio kernel: CIFS: Attempting to mount //192.168.0.2/Musik Jan 12 09:44:15 volumio sudo[1201]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:15 volumio sudo[1203]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:17 volumio volumio[858]: info: Volumio Network Manager: Network status updated: 1 Jan 12 09:44:17 volumio sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 09:44:17 volumio sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:17 volumio sudo[1249]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:17 volumio sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 09:44:17 volumio sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:17 volumio sudo[1251]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:17 volumio sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 12 09:44:17 volumio sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:17 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jan 12 09:44:17 volumio sudo[1255]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:17 volumio volumio[858]: info: Upmpdcli Daemon Started Jan 12 09:44:17 volumio volumio[1257]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 12 09:44:18 volumio sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 12 09:44:18 volumio sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:18 volumio sudo[1276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 12 09:44:18 volumio sudo[1276]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:18 volumio systemd[1]: Started MPD Monitor Service. Jan 12 09:44:18 volumio sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 12 09:44:18 volumio sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:18 volumio sudo[1274]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:18 volumio mpd_monitor.sh[1280]: MPD Monitor Service: Starting MPD Monitor Service Jan 12 09:44:18 volumio volumio[858]: info: Successfully started MPD Monitor Jan 12 09:44:18 volumio systemd[1]: Stopping MPD Monitor Service... Jan 12 09:44:18 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 12 09:44:18 volumio systemd[1]: mpd_monitor.service: Succeeded. Jan 12 09:44:18 volumio systemd[1]: Stopped MPD Monitor Service. Jan 12 09:44:18 volumio systemd[1]: Started MPD Monitor Service. Jan 12 09:44:18 volumio sudo[1279]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:18 volumio mpd_monitor.sh[1285]: MPD Monitor Service: Starting MPD Monitor Service Jan 12 09:44:18 volumio sudo[1276]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:18 volumio volumio[858]: info: Successfully started MPD Monitor Jan 12 09:44:18 volumio volumio[858]: info: Successfully started MPD Monitor Jan 12 09:44:19 volumio volumio[1257]: .......................++++ Jan 12 09:44:19 volumio volumio[1257]: .++++ Jan 12 09:44:19 volumio volumio[1257]: e is 65537 (0x010001) Jan 12 09:44:19 volumio volumio[1257]: writing RSA key Jan 12 09:44:20 volumio volumio[858]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 12 09:44:23 volumio volumio[858]: info: Adding plugin bluetooth to MyMusic Plugins Jan 12 09:44:23 volumio volumio[858]: info: Adding plugin multiroom to MyMusic Plugins Jan 12 09:44:23 volumio volumio[858]: info: Adding plugin metavolumio to MyMusic Plugins Jan 12 09:44:23 volumio volumio[858]: info: Adding plugin cd_controller to MyMusic Plugins Jan 12 09:44:23 volumio volumio[858]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 12 09:44:23 volumio volumio[858]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 12 09:44:23 volumio volumio[858]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 12 09:44:23 volumio volumio[858]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 12 09:44:24 volumio volumio[858]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 12 09:44:24 volumio volumio[858]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 12 09:44:24 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:44:24 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:44:24 volumio volumio[858]: info: Starting MyVolumio Remote Streaming Endpoints Jan 12 09:44:24 volumio volumio[858]: info: MyVolumio login type: Token Jan 12 09:44:24 volumio volumio[858]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 12 09:44:24 volumio volumio[858]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 12 09:44:25 volumio volumio[858]: info: Starting Streaming Service Transparent Proxy Jan 12 09:44:25 volumio volumio[858]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 12 09:44:25 volumio volumio[858]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 12 09:44:25 volumio volumio[858]: info: Streaming services startup Jan 12 09:44:25 volumio volumio[858]: info: Starting Streaming Daemon Jan 12 09:44:25 volumio sudo[1312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 09:44:25 volumio sudo[1312]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:25 volumio volumio[858]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 12 09:44:25 volumio sudo[1312]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:25 volumio volumio[858]: error: Cannot start Volumio Streaming Daemon Jan 12 09:44:25 volumio volumio[858]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 12 09:44:25 volumio volumio[858]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 12 09:44:25 volumio volumio[858]: STREAMING PROXY: Starting server on port 3245 Jan 12 09:44:25 volumio volumio[858]: Node JS runtime: 14 Jan 12 09:44:25 volumio volumio[858]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 12 09:44:27 volumio volumio[858]: info: MyVolumio token set successfully Jan 12 09:44:27 volumio volumio[858]: info: MYVOLUMIO: Adding device Jan 12 09:44:27 volumio volumio[858]: info: MYVOLUMIO: Evaluating Server Jan 12 09:44:28 volumio volumio[858]: info: MyVolumio Plan changed: premium Jan 12 09:44:28 volumio volumio[858]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jan 12 09:44:28 volumio volumio[858]: info: Removing browser output: myVolumio user plan is not superstar Jan 12 09:44:28 volumio volumio[858]: info: Removing audio output: Jan 12 09:44:28 volumio volumio[858]: info: MYVOLUMIO: Adding device Jan 12 09:44:28 volumio volumio[858]: info: MYVOLUMIO: Evaluating Server Jan 12 09:44:28 volumio volumio[858]: info: Remote config written successfully Jan 12 09:44:28 volumio volumio[858]: info: Starting Tunnel 1 Jan 12 09:44:28 volumio volumio[858]: info: Starting Tunnel Connection Checker Jan 12 09:44:28 volumio volumio[858]: info: Completed starting MyVolumio Plugin Jan 12 09:44:28 volumio volumio[858]: info: MYVolumio Device enabled Jan 12 09:44:28 volumio volumio[858]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 12 09:44:28 volumio volumio[858]: info: MyVolumio status changed Jan 12 09:44:28 volumio volumio[858]: info: Streaming services startup Jan 12 09:44:28 volumio volumio[858]: info: Starting Streaming Daemon Jan 12 09:44:28 volumio sudo[1372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 09:44:28 volumio sudo[1372]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:28 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 12 09:44:28 volumio volumio[858]: error: [MyVolumio PluginManager] Cache data is invalid! Jan 12 09:44:28 volumio sudo[1372]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:28 volumio volumio[858]: error: Cannot start Volumio Streaming Daemon Jan 12 09:44:28 volumio volumio[858]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 12 09:44:28 volumio volumio[858]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 12 09:44:29 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jan 12 09:44:33 volumio sudo[1379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 12 09:44:33 volumio sudo[1379]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:33 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jan 12 09:44:33 volumio sudo[1379]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:33 volumio volumio[858]: info: Remote SSH Started Jan 12 09:44:33 volumio autossh[1382]: port set to 0, monitoring disabled Jan 12 09:44:33 volumio autossh[1382]: starting ssh (count 1) Jan 12 09:44:33 volumio autossh[1382]: ssh child pid is 1385 Jan 12 09:44:33 volumio volumio[858]: info: Setting Geolocation for MyVolumio to eu6 Jan 12 09:44:33 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:44:33 volumio volumio[858]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 12 09:44:34 volumio volumio[858]: info: Updating MyVolumio device info Jan 12 09:44:34 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:44:38 volumio volumio[858]: info: CoreCommandRouter::volumioGetState Jan 12 09:44:38 volumio volumio[858]: info: CorePlayQueue::getTrack 0 Jan 12 09:44:38 volumio volumiossh-tunnel[1381]: Warning: Permanently added '[eu1.myvolumio.org]:2222,[68.183.69.189]:2222' (RSA) to the list of known hosts. Jan 12 09:44:38 volumio volumio[858]: info: Successfully Added MyVolumio device Jan 12 09:44:39 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 09:44:39 volumio volumio[858]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jan 12 09:44:39 volumio volumio[858]: info: CoreCommandRouter::volumioGetState Jan 12 09:44:39 volumio volumio[858]: info: CorePlayQueue::getTrack 0 Jan 12 09:44:39 volumio volumio[858]: info: Successfully Updated MyVolumio device Jan 12 09:44:40 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 09:44:40 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:44:40 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 09:44:40 volumio volumio-remote-updater[632]: No test mode Jan 12 09:44:40 volumio volumio-remote-updater[632]: No alpha test mode Jan 12 09:44:40 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 12 09:44:41 volumio volumio[858]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 12 09:44:41 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 12 09:44:42 volumio systemd[1]: systemd-timedated.service: Succeeded. Jan 12 09:44:42 volumio volumio[858]: info: Setting Geolocation for MyVolumio to eu6 Jan 12 09:44:42 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:44:43 volumio volumio[858]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jan 12 09:44:43 volumio volumio[858]: info: [1768207483410] Starting BluetoothController Jan 12 09:44:43 volumio volumio[858]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jan 12 09:44:44 volumio volumio[858]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jan 12 09:44:44 volumio sudo[1409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 12 09:44:44 volumio sudo[1409]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:44 volumio sudo[1409]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:44 volumio volumio[858]: info: MRS: MultiRoom plugin initialized Jan 12 09:44:44 volumio volumio[858]: info: MRS: STOPPING SNAPCLIENT Jan 12 09:44:44 volumio volumio[858]: info: MRS: Snap server stop Jan 12 09:44:44 volumio sudo[1425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 12 09:44:44 volumio sudo[1425]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:44 volumio volumio[858]: info: MRS: STOPPING volumioStreaming Jan 12 09:44:44 volumio sudo[1428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 12 09:44:44 volumio sudo[1428]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:44 volumio sudo[1425]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:44 volumio sudo[1431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 12 09:44:44 volumio sudo[1431]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:44 volumio volumio[858]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jan 12 09:44:44 volumio sudo[1434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 12 09:44:44 volumio sudo[1434]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:44 volumio sudo[1434]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:44 volumio sudo[1428]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:44 volumio sudo[1431]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:44 volumio volumio[858]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jan 12 09:44:44 volumio volumio[858]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jan 12 09:44:44 volumio volumio[858]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jan 12 09:44:44 volumio volumio[858]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jan 12 09:44:44 volumio volumio[858]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jan 12 09:44:44 volumio volumio[858]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jan 12 09:44:44 volumio volumio[858]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jan 12 09:44:44 volumio volumio[858]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jan 12 09:44:44 volumio volumio[858]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Jan 12 09:44:45 volumio volumio[858]: info: Preparing to generate the ALSA configuration file Jan 12 09:44:45 volumio volumio[858]: info: Updating MyVolumio device info Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::volumioGetBrowseSources Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 12 09:44:45 volumio volumio[858]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 12 09:44:45 volumio volumio[858]: info: Reading ALSA contributions from plugins. Jan 12 09:44:45 volumio volumio[858]: info: Successfully Added MyVolumio device Jan 12 09:44:45 volumio volumio[858]: info: MRS: Removed streaming files Jan 12 09:44:45 volumio volumio[858]: info: MRS: volumioStreaming STOPPED Jan 12 09:44:45 volumio volumio[858]: info: MRS: SNAPSERVER STOPPED Jan 12 09:44:45 volumio volumio[858]: info: MRS: SNAPCLIENT STOPPED Jan 12 09:44:45 volumio volumio[858]: info: Asound.conf file unchanged, so no further update is needed Jan 12 09:44:45 volumio volumio[858]: info: Output device has changed, restarting MPD Jan 12 09:44:45 volumio sudo[1437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 09:44:45 volumio sudo[1437]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:45 volumio volumio[858]: info: Output device has changed, restarting Shairport Sync Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:44:45 volumio sudo[1437]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:45 volumio sudo[1440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 09:44:45 volumio sudo[1440]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:45 volumio systemd[1]: Stopping Music Player Daemon... Jan 12 09:44:45 volumio volumio[858]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 09:44:45 volumio volumio[858]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 09:44:45 volumio volumio[858]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jan 12 09:44:45 volumio volumio[858]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Jan 12 09:44:45 volumio volumio[858]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jan 12 09:44:45 volumio volumio[858]: info: Adding METAVOLUMIO REST API Endpoints Jan 12 09:44:45 volumio volumio[858]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 12 09:44:45 volumio volumio[858]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 12 09:44:45 volumio volumio[858]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 12 09:44:45 volumio volumio[858]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 12 09:44:45 volumio volumio[858]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jan 12 09:44:45 volumio volumio[858]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jan 12 09:44:45 volumio volumio[858]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Jan 12 09:44:45 volumio volumio[858]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jan 12 09:44:45 volumio volumio[858]: info: Adding inputs REST Endpoints Jan 12 09:44:45 volumio volumio[858]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jan 12 09:44:45 volumio volumio[858]: info: Scanning Audio Inputs Jan 12 09:44:45 volumio sudo[1448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Jan 12 09:44:45 volumio sudo[1448]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:45 volumio sudo[1448]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:45 volumio volumio[858]: info: Checking against Known Cards name Jan 12 09:44:45 volumio volumio[858]: info: Adding Server instance for streaming Jan 12 09:44:45 volumio volumio[858]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jan 12 09:44:45 volumio volumio[858]: error: Hi Res Audio Failed Login: Missing Login Data Jan 12 09:44:45 volumio volumio[858]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 12 09:44:45 volumio volumio[858]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 12 09:44:45 volumio volumio[858]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 12 09:44:45 volumio volumio[858]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jan 12 09:44:45 volumio volumio[858]: info: Refreshing TIDAL token Jan 12 09:44:45 volumio volumio[858]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jan 12 09:44:45 volumio volumio[858]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jan 12 09:44:45 volumio systemd[1]: mpd.service: Succeeded. Jan 12 09:44:45 volumio systemd[1]: Stopped Music Player Daemon. Jan 12 09:44:45 volumio volumio[858]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jan 12 09:44:45 volumio volumio[858]: info: [MyVolumio PluginManager] Plugin qobuzconnect disabled by user. Not starting Jan 12 09:44:45 volumio volumio[858]: info: Stopping AccessToken refresher cron for QOBUZ Jan 12 09:44:45 volumio sudo[1454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 12 09:44:45 volumio sudo[1454]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:45 volumio systemd[1]: Starting Music Player Daemon... Jan 12 09:44:45 volumio volumio[858]: info: AccessToken refresher cron started for QOBUZ Jan 12 09:44:45 volumio volumio[858]: info: Adding QOBUZ REST API Endpoints Jan 12 09:44:45 volumio volumio[858]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jan 12 09:44:45 volumio volumio[858]: info: Bluetooth name changed to Volumio Jan 12 09:44:45 volumio volumio[858]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jan 12 09:44:45 volumio sudo[1454]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:45 volumio volumio[858]: info: MPD Permissions set Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:44:45 volumio volumio[858]: info: Starting Shairport Sync Jan 12 09:44:45 volumio sudo[1452]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 12 09:44:45 volumio sudo[1452]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:45 volumio sudo[1452]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:45 volumio sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 12 09:44:45 volumio sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 12 09:44:45 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 12 09:44:45 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 12 09:44:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 12 09:44:45 volumio sudo[1462]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:45 volumio volumio[858]: info: Shairport-Sync Started Jan 12 09:44:45 volumio volumio[858]: info: Access Token successfully retrieved Jan 12 09:44:45 volumio volumio[858]: info: Successfully Updated MyVolumio device Jan 12 09:44:45 volumio volumio[858]: info: Successfully retrieved User Session From TIDAL Jan 12 09:44:46 volumio volumio[858]: info: Successfully retrieved User Subscription From TIDAL Jan 12 09:44:46 volumio volumio[858]: info: Adding TIDAL to Browse Sources Jan 12 09:44:46 volumio volumio[858]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 09:44:46 volumio volumio[858]: info: [1768207486024] CoreMusicLibrary::Adding element TIDAL Jan 12 09:44:46 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 09:44:46 volumio volumio[858]: Cannot find translation for source 80s80s Radio Jan 12 09:44:46 volumio volumio[858]: Cannot find translation for source Radio Paradise Jan 12 09:44:46 volumio volumio[858]: Cannot find translation for source TIDAL Jan 12 09:44:46 volumio volumio[858]: info: Adding TIDAL REST API Endpoints Jan 12 09:44:46 volumio volumio[858]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jan 12 09:44:46 volumio mpd[1464]: Jan 12 09:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 12 09:44:46 volumio systemd[1]: Started Music Player Daemon. Jan 12 09:44:46 volumio sudo[1440]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:46 volumio volumio[858]: error: updateQueue error: null Jan 12 09:44:48 volumio sudo[1486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 12 09:44:48 volumio sudo[1486]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:48 volumio systemd[1]: Started Volumio Bluetooth Module. Jan 12 09:44:48 volumio sudo[1486]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:48 volumio volumio[858]: info: Volumio BT Module successfully started Jan 12 09:44:48 volumio sudo[1489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jan 12 09:44:48 volumio sudo[1489]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:48 volumio volumiobt[1488]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jan 12 09:44:48 volumio sudo[1489]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:48 volumio volumio[858]: info: TidalConnect service stoped! Jan 12 09:44:48 volumio volumio[858]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 12 09:44:48 volumio volumio[858]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 12 09:44:48 volumio sudo[1501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 12 09:44:48 volumio sudo[1501]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:48 volumio systemd[1]: Started Volumio Tidal Connect Service. Jan 12 09:44:48 volumio sudo[1501]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:48 volumio volumio[858]: info: Executing endpoint tc_getconfig Jan 12 09:44:48 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 12 09:44:48 volumio vtcs[1503]: STARTING TidalConnect services, version: 1.5.2.56 Jan 12 09:44:48 volumio vtcs[1503]: STARTED TidalConnect services. Jan 12 09:44:48 volumio volumio[858]: info: Executing endpoint tc_connect Jan 12 09:44:48 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 12 09:44:48 volumio volumio[858]: info: Connecting to TidalConnect Jan 12 09:44:48 volumio volumio[858]: info: CoreCommandRouter::servicePushState Jan 12 09:44:48 volumio volumio[858]: info: CoreStateMachine::pushState Jan 12 09:44:48 volumio volumio[858]: info: CorePlayQueue::getTrack 0 Jan 12 09:44:48 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Jan 12 09:44:48 volumio volumio[858]: info: CorePlayQueue::getTrack 0 Jan 12 09:44:48 volumio volumio[858]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received tidalconnect Jan 12 09:44:48 volumio volumio[858]: info: CoreCommandRouter::servicePushState Jan 12 09:44:48 volumio volumio[858]: info: CoreStateMachine::pushState Jan 12 09:44:48 volumio volumio[858]: info: CorePlayQueue::getTrack 0 Jan 12 09:44:48 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Jan 12 09:44:48 volumio volumio[858]: info: CorePlayQueue::getTrack 0 Jan 12 09:44:48 volumio volumio[858]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received tidalconnect Jan 12 09:44:49 volumio sudo[1523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 12 09:44:49 volumio sudo[1523]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:49 volumio systemd[1]: Stopping MPD Monitor Service... Jan 12 09:44:49 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 12 09:44:49 volumio systemd[1]: mpd_monitor.service: Succeeded. Jan 12 09:44:49 volumio systemd[1]: Stopped MPD Monitor Service. Jan 12 09:44:49 volumio volumio[858]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 12 09:44:49 volumio volumio[858]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 12 09:44:49 volumio systemd[1]: Started MPD Monitor Service. Jan 12 09:44:49 volumio mpd_monitor.sh[1525]: MPD Monitor Service: Starting MPD Monitor Service Jan 12 09:44:49 volumio sudo[1523]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:49 volumio volumio[858]: info: Successfully started MPD Monitor Jan 12 09:44:49 volumio sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jan 12 09:44:49 volumio sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:49 volumio volumiobt[1488]: pulseaudio: no process found Jan 12 09:44:49 volumio sudo[1529]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:49 volumio dbus-daemon[623]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.19' (uid=1000 pid=1533 comm="/usr/bin/pulseaudio --high-priority=true ") Jan 12 09:44:49 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jan 12 09:44:49 volumio volumio[858]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 12 09:44:49 volumio dbus-daemon[623]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jan 12 09:44:49 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Jan 12 09:44:49 volumio rtkit-daemon[1534]: Successfully called chroot. Jan 12 09:44:49 volumio rtkit-daemon[1534]: Successfully dropped privileges. Jan 12 09:44:49 volumio rtkit-daemon[1534]: Successfully limited resources. Jan 12 09:44:49 volumio rtkit-daemon[1534]: Running. Jan 12 09:44:49 volumio rtkit-daemon[1534]: Canary thread running. Jan 12 09:44:49 volumio rtkit-daemon[1534]: Watchdog thread running. Jan 12 09:44:49 volumio dbus-daemon[623]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.20' (uid=0 pid=1534 comm="/usr/lib/rtkit/rtkit-daemon ") Jan 12 09:44:49 volumio systemd[1]: Starting Authorization Manager... Jan 12 09:44:49 volumio volumio[858]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 12 09:44:49 volumio polkitd[1538]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 12 09:44:49 volumio dbus-daemon[623]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 12 09:44:49 volumio systemd[1]: Started Authorization Manager. Jan 12 09:44:49 volumio pulseaudio[1533]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 12 09:44:49 volumio pulseaudio[1533]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 12 09:44:49 volumio bluetoothd[790]: Endpoint registered: sender=:1.40 path=/MediaEndpoint/A2DPSource Jan 12 09:44:49 volumio bluetoothd[790]: Endpoint registered: sender=:1.40 path=/MediaEndpoint/A2DPSink Jan 12 09:44:49 volumio volumiobt[1488]: Applying permissions Jan 12 09:44:49 volumio volumiobt[1488]: Setting BT discoverable and pairable Jan 12 09:44:49 volumio pulseaudio[1533]: 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 Jan 12 09:44:49 volumio kernel: Bluetooth: RFCOMM TTY layer initialized Jan 12 09:44:49 volumio kernel: Bluetooth: RFCOMM socket layer initialized Jan 12 09:44:49 volumio kernel: Bluetooth: RFCOMM ver 1.11 Jan 12 09:44:49 volumio volumiobt[1488]: [147B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [44B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [77B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [77B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [33B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [95B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [79B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [55B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [bluetoothctl]> -e power on Jan 12 09:44:49 volumio volumiobt[1488]: Invalid command in menu main: -e Jan 12 09:44:49 volumio volumiobt[1488]: Jan 12 09:44:49 volumio volumiobt[1488]: Use "help" for a list of available commands in a menu. Jan 12 09:44:49 volumio volumiobt[1488]: Use "menu " if you want to enter any submenu. Jan 12 09:44:49 volumio volumiobt[1488]: Use "back" if you want to return to menu main. Jan 12 09:44:49 volumio volumiobt[1488]: [103B blob data] Jan 12 09:44:49 volumio volumiobt[1488]: [bluetoothctl]> agent on Jan 12 09:44:49 volumio volumiobt[1488]: Agent is already registered Jan 12 09:44:49 volumio volumiobt[1488]: [bluetoothctl]> discoverable on Jan 12 09:44:49 volumio volumiobt[1488]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Jan 12 09:44:49 volumio volumiobt[1488]: [bluetoothctl]> pairable on Jan 12 09:44:49 volumio volumiobt[1488]: [bluetoothctl]> agent NoInputNoOutput Jan 12 09:44:49 volumio volumiobt[1488]: Agent is already registered Jan 12 09:44:49 volumio volumiobt[1488]: [bluetoothctl]> default-agent Jan 12 09:44:49 volumio volumiobt[1488]: [bluetoothctl]> quit Jan 12 09:44:49 volumio volumiobt[1488]: [65B blob data] Jan 12 09:44:51 volumio volumio[858]: info: TidalConnect service started! Jan 12 09:44:51 volumio volumio[858]: [Metrics] CommandRouter: 46s 520.12ms Jan 12 09:44:51 volumio volumio[858]: info: CoreCommandRouter::volumiosetStartupVolume Jan 12 09:44:51 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:44:51 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:44:51 volumio volumio[858]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:44:51 volumio volumio[858]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:44:51 volumio volumio[858]: info: Executing endpoint metavolumio Jan 12 09:44:51 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 12 09:44:51 volumio volumio[858]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 12 09:44:51 volumio volumio[858]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 12 09:44:51 volumio volumio[858]: info: camilladsp stopping service pid 1162... Jan 12 09:44:51 volumio volumio[858]: info: camilladsp service terminated, instance 1 Jan 12 09:44:51 volumio volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 12 09:44:51 volumio volumio[858]: info: FusionDsp - Effects disabled Jan 12 09:44:51 volumio volumio[858]: info: camilladsp service started and running in background, instance 1 Jan 12 09:44:51 volumio volumio[858]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 12 09:44:51 volumio volumio[858]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 12 09:44:51 volumio volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 12 09:44:51 volumio volumio[858]: info: FusionDsp - Effects disabled Jan 12 09:44:51 volumio volumio[858]: error: FusionDsp - WebSocket error: [object Object] Jan 12 09:44:51 volumio volumio[858]: info: camilladsp spawned new process with pid 1553, instance 1, run: true Jan 12 09:44:52 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 12 09:44:52 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 12 09:44:52 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 12 09:44:52 volumio volumio[858]: info: Version has changed, forcing UI Reload Jan 12 09:44:52 volumio volumio[858]: info: CoreCommandRouter::Reload Ui Jan 12 09:44:53 volumio sudo[1561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 09:44:53 volumio sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:53 volumio sudo[1561]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:53 volumio sudo[1564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 09:44:53 volumio sudo[1564]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:44:53 volumio sudo[1564]: pam_unix(sudo:session): session closed for user root Jan 12 09:44:53 volumio volumio[858]: verbose: New Socket.io Connection to 192.168.0.62 from 192.168.0.50 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 7 Jan 12 09:44:53 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 09:44:53 volumio volumio[858]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 09:44:53 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 09:44:53 volumio volumio[858]: info: CoreCommandRouter::volumioGetState Jan 12 09:44:53 volumio volumio[858]: info: CorePlayQueue::getTrack 0 Jan 12 09:44:53 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 12 09:44:53 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 09:44:53 volumio volumio[858]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 12 09:44:53 volumio volumio[858]: info: Received Get System Info Jan 12 09:44:53 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 09:44:53 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 09:44:53 volumio volumio[858]: info: Discovery: Getting this device information Jan 12 09:44:53 volumio volumio[858]: info: CoreCommandRouter::volumioGetState Jan 12 09:44:53 volumio volumio[858]: info: CorePlayQueue::getTrack 0 Jan 12 09:44:53 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 09:44:53 volumio volumio[858]: info: CoreCommandRouter::volumioGetState Jan 12 09:44:53 volumio volumio[858]: info: CorePlayQueue::getTrack 0 Jan 12 09:44:53 volumio volumio[858]: info: Listing playlists Jan 12 09:44:54 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 12 09:44:54 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 09:44:54 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 12 09:44:55 volumio volumio[858]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 09:44:55 volumio volumio[858]: info: CoreCommandRouter::volumioGetState Jan 12 09:44:55 volumio volumio[858]: info: CorePlayQueue::getTrack 0 Jan 12 09:44:55 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 09:44:55 volumio volumio[858]: info: Received Get System Info Jan 12 09:44:55 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 09:44:55 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 09:44:55 volumio volumio[858]: info: Discovery: Getting this device information Jan 12 09:44:55 volumio volumio[858]: info: CoreCommandRouter::volumioGetState Jan 12 09:44:55 volumio volumio[858]: info: CorePlayQueue::getTrack 0 Jan 12 09:44:55 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 09:44:55 volumio volumio[858]: info: FusionDsp - no preset used Jan 12 09:44:55 volumio volumio[858]: info: FusionDsp - undefined Jan 12 09:44:55 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 12 09:44:56 volumio volumio[858]: info: camilladsp spawned new process with pid 1566, instance 1, run: true Jan 12 09:44:57 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 09:44:57 volumio volumio[858]: info: Received Get System Info Jan 12 09:44:57 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 09:44:57 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 09:44:57 volumio volumio[858]: info: Discovery: Getting this device information Jan 12 09:44:57 volumio volumio[858]: info: CoreCommandRouter::volumioGetState Jan 12 09:44:57 volumio volumio[858]: info: CorePlayQueue::getTrack 0 Jan 12 09:44:57 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 09:44:57 volumio volumio[858]: info: Executing endpoint metavolumio Jan 12 09:44:57 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 12 09:44:58 volumio volumio[858]: info: BOOT COMPLETED Jan 12 09:45:03 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 09:45:04 volumio volumio[858]: info: CoreCommandRouter::volumioGetQueue Jan 12 09:45:04 volumio volumio[858]: info: CoreStateMachine::getQueue Jan 12 09:45:04 volumio volumio[858]: info: CorePlayQueue::getQueue Jan 12 09:45:06 volumio ntpd[792]: Soliciting pool server 152.53.15.127 Jan 12 09:45:07 volumio ntpd[792]: Soliciting pool server 178.251.64.52 Jan 12 09:45:08 volumio ntpd[792]: Soliciting pool server 46.102.157.67 Jan 12 09:45:09 volumio ntpd[792]: Soliciting pool server 91.206.8.70 Jan 12 09:45:09 volumio ntpd[792]: Soliciting pool server 162.159.200.1 Jan 12 09:45:10 volumio ntpd[792]: Soliciting pool server 37.252.189.117 Jan 12 09:45:10 volumio ntpd[792]: Soliciting pool server 162.159.200.123 Jan 12 09:45:10 volumio ntpd[792]: Soliciting pool server 62.40.143.228 Jan 12 09:45:11 volumio ntpd[792]: Soliciting pool server 78.41.116.149 Jan 12 09:45:11 volumio ntpd[792]: Soliciting pool server 146.255.56.84 Jan 12 09:45:11 volumio ntpd[792]: Soliciting pool server 91.206.8.36 Jan 12 09:45:11 volumio ntpd[792]: Soliciting pool server 151.236.30.71 Jan 12 09:45:11 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 09:45:11 volumio volumio[858]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 12 09:45:11 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 12 09:45:11 volumio volumio[858]: info: Received Get System Version Jan 12 09:45:11 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 09:45:11 volumio volumio[858]: info: Received Get System Info Jan 12 09:45:11 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 09:45:11 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 09:45:11 volumio volumio[858]: info: Discovery: Getting this device information Jan 12 09:45:11 volumio volumio[858]: info: CoreCommandRouter::volumioGetState Jan 12 09:45:11 volumio volumio[858]: info: CorePlayQueue::getTrack 0 Jan 12 09:45:11 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 09:45:12 volumio ntpd[792]: Soliciting pool server 91.206.237.2 Jan 12 09:45:12 volumio ntpd[792]: Soliciting pool server 152.53.119.76 Jan 12 09:45:13 volumio ntpd[792]: Soliciting pool server 152.53.132.244 Jan 12 09:45:13 volumio ntpd[792]: Soliciting pool server 83.215.130.11 Jan 12 09:45:15 volumio ntpd[792]: Soliciting pool server 91.206.8.34 Jan 12 09:45:15 volumio ntpd[792]: Soliciting pool server 2a13:9400:10:1002::123 Jan 12 09:45:17 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 09:45:17 volumio volumio[858]: info: CURURI: artists:// Jan 12 09:45:17 volumio volumio[858]: info: Preload queue cleared Jan 12 09:45:18 volumio volumio[858]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AC-DC/9ee0eca2-f690-4d9a-80db-b4d9f0be4584.jpg' Jan 12 09:45:18 volumio volumio[858]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AC%2FDC/735a55e0-0905-4c66-9794-729286aaf3f8.jpg' Jan 12 09:45:18 volumio volumio[858]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alien%20Sex%20Fiend/76f26281-fac8-45e8-9c41-3024fac05eac.jpg' Jan 12 09:45:18 volumio volumio[858]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ampex/ed02c20b-1535-4d6f-9bb2-6a5e7622e603.jpg' Jan 12 09:45:18 volumio volumio[858]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Anne%20Clark/62b1e38b-9418-4ae4-985b-992e1c146e0d.jpg' Jan 12 09:45:18 volumio volumio[858]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 09:45:18 volumio volumio[858]: Error: connect ECONNREFUSED 0.0.0.0:443 Jan 12 09:45:18 volumio volumio[858]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jan 12 09:45:18 volumio volumio[858]: errno: -111, Jan 12 09:45:18 volumio volumio[858]: code: 'ECONNREFUSED', Jan 12 09:45:18 volumio volumio[858]: syscall: 'connect', Jan 12 09:45:18 volumio volumio[858]: address: '0.0.0.0', Jan 12 09:45:18 volumio volumio[858]: port: 443 Jan 12 09:45:18 volumio volumio[858]: } Jan 12 09:45:18 volumio volumio[858]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 09:45:19 volumio sudo[1665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-12 09:44 Jan 12 09:45:19 volumio sudo[1665]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"