-- Logs begin at Tue 2024-11-26 07:50:41 UTC, end at Tue 2024-11-26 07:52:00 UTC. -- Nov 26 07:51:00 volumiogolab wireless.js[756]: trying... Nov 26 07:51:00 volumiogolab sudo[1093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 26 07:51:00 volumiogolab sudo[1093]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:00 volumiogolab sudo[1093]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:01 volumiogolab wireless.js[756]: trying... Nov 26 07:51:01 volumiogolab sudo[1096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 26 07:51:01 volumiogolab sudo[1096]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:01 volumiogolab sudo[1096]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:01 volumiogolab dhcpcd[850]: wlan0: leased 192.168.1.45 for 86400 seconds Nov 26 07:51:01 volumiogolab dhcpcd[850]: wlan0: adding route to 192.168.1.0/24 Nov 26 07:51:01 volumiogolab dhcpcd[850]: wlan0: adding default route via 192.168.1.1 Nov 26 07:51:01 volumiogolab avahi-daemon[747]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.45. Nov 26 07:51:01 volumiogolab avahi-daemon[747]: New relevant interface wlan0.IPv4 for mDNS. Nov 26 07:51:01 volumiogolab avahi-daemon[747]: Registering new address record for 192.168.1.45 on wlan0.IPv4. Nov 26 07:51:01 volumiogolab systemd[1]: Stopping Network Time Service... Nov 26 07:51:01 volumiogolab ntpd[981]: ntpd exiting on signal 15 (Terminated) Nov 26 07:51:01 volumiogolab systemd[1]: ntp.service: Succeeded. Nov 26 07:51:01 volumiogolab systemd[1]: Stopped Network Time Service. Nov 26 07:51:01 volumiogolab systemd[1]: Starting Network Time Service... Nov 26 07:51:01 volumiogolab ntpd[1132]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 26 07:51:01 volumiogolab ntpd[1132]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 26 07:51:01 volumiogolab systemd[1]: Started Network Time Service. Nov 26 07:51:01 volumiogolab ntpd[1138]: proto: precision = 1.560 usec (-19) Nov 26 07:51:01 volumiogolab ntpd[1138]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 26 07:51:01 volumiogolab ntpd[1138]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 26 07:51:01 volumiogolab volumio-time-update[748]: volumio-time-update-util: Fetching time from Volumio... Nov 26 07:51:01 volumiogolab ntpd[1138]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 700 days ago Nov 26 07:51:01 volumiogolab ntpd[1138]: Listen and drop on 0 v6wildcard [::]:123 Nov 26 07:51:01 volumiogolab ntpd[1138]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 26 07:51:01 volumiogolab ntpd[1138]: Listen normally on 2 lo 127.0.0.1:123 Nov 26 07:51:01 volumiogolab ntpd[1138]: Listen normally on 3 wlan0 192.168.1.45:123 Nov 26 07:51:01 volumiogolab ntpd[1138]: Listening on routing socket on fd #20 for interface updates Nov 26 07:51:01 volumiogolab ntpd[1138]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 26 07:51:01 volumiogolab ntpd[1138]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 26 07:51:01 volumiogolab volumio-time-update[748]: volumio-time-update-util: Setting system time to: 2024-11-26 07:51:01 Nov 26 07:51:01 volumiogolab sudo[1145]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2024-11-26 07:51:01 Nov 26 07:51:01 volumiogolab sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:01 volumiogolab dbus-daemon[767]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.7' (uid=0 pid=1146 comm="timedatectl set-time 2024-11-26 07:51:01 ") Nov 26 07:51:01 volumiogolab systemd[1]: Starting Time & Date Service... Nov 26 07:51:01 volumiogolab dbus-daemon[767]: [system] Successfully activated service 'org.freedesktop.timedate1' Nov 26 07:51:01 volumiogolab systemd[1]: Started Time & Date Service. Nov 26 07:51:01 volumiogolab systemd-timedated[1150]: Changed local time to Tue Nov 26 07:51:01 2024 Nov 26 07:51:01 volumiogolab sudo[1145]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:01 volumiogolab volumio-time-update[748]: volumio-time-update-util: System time updated successfully. Nov 26 07:51:01 volumiogolab systemd[1]: Started Volumio Time Update Utility. Nov 26 07:51:01 volumiogolab wireless.js[756]: trying... Nov 26 07:51:01 volumiogolab sudo[1153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 26 07:51:01 volumiogolab sudo[1153]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:01 volumiogolab sudo[1153]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:01 volumiogolab wireless.js[756]: Connected to: ----TIM-47637477 Nov 26 07:51:01 volumiogolab wireless.js[756]: ---- Nov 26 07:51:01 volumiogolab sudo[1156]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 07:51:01 volumiogolab sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:01 volumiogolab sudo[1156]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:01 volumiogolab wireless.js[756]: ... joined AP, wlan0 IPv4 is 192.168.1.45, ipV6 is undefined Nov 26 07:51:01 volumiogolab wireless.js[756]: It's done! AP Nov 26 07:51:01 volumiogolab iwconfig[1160]: Error for wireless request "Set Power Management" (8B2C) : Nov 26 07:51:01 volumiogolab iwconfig[1160]: SET failed on device wlan0 ; Operation not supported. Nov 26 07:51:01 volumiogolab systemd[1]: Started Wireless Services. Nov 26 07:51:01 volumiogolab systemd[1]: Started Volumio Backend Module. Nov 26 07:51:01 volumiogolab systemd[1]: Started Volumio Cpu Tweaker. Nov 26 07:51:01 volumiogolab systemd[1]: Started Intel SST and HDA soundcard init service. Nov 26 07:51:01 volumiogolab systemd[1]: Started Start Volumio Kiosk. Nov 26 07:51:01 volumiogolab ntpd[1138]: Soliciting pool server 185.19.184.35 Nov 26 07:51:01 volumiogolab volumio-cpu-tweak[1162]: Setting RT Priority for mpd Nov 26 07:51:01 volumiogolab volumio-cpu-tweak[1162]: Not enough cores to set MPD affinity Nov 26 07:51:01 volumiogolab volumio-cpu-tweak[1162]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Nov 26 07:51:01 volumiogolab systemd[1]: volumio_cpu_tweak.service: Succeeded. Nov 26 07:51:02 volumiogolab startx[1164]: X.Org X Server 1.20.4 Nov 26 07:51:02 volumiogolab startx[1164]: X Protocol Version 11, Revision 0 Nov 26 07:51:02 volumiogolab startx[1164]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Nov 26 07:51:02 volumiogolab startx[1164]: Current Operating System: Linux volumiogolab 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 Nov 26 07:51:02 volumiogolab startx[1164]: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=726f5035-61b2-44f7-935c-47f19f4cf604 bootpart=UUID=100D-1291 datapart=UUID=d824b318-3e69-4151-86db-cbe95cb86b7a hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no initrd=volumio.initrd Nov 26 07:51:02 volumiogolab startx[1164]: Build Date: 18 December 2021 09:05:36AM Nov 26 07:51:02 volumiogolab startx[1164]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Nov 26 07:51:02 volumiogolab startx[1164]: Current version of pixman: 0.36.0 Nov 26 07:51:02 volumiogolab startx[1164]: Before reporting problems, check http://wiki.x.org Nov 26 07:51:02 volumiogolab startx[1164]: to make sure that you have the latest version. Nov 26 07:51:02 volumiogolab startx[1164]: Markers: (--) probed, (**) from config file, (==) default setting, Nov 26 07:51:02 volumiogolab startx[1164]: (++) from command line, (!!) notice, (II) informational, Nov 26 07:51:02 volumiogolab startx[1164]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Nov 26 07:51:02 volumiogolab startx[1164]: (==) Log file: "/var/log/Xorg.0.log", Time: Tue Nov 26 07:51:02 2024 Nov 26 07:51:02 volumiogolab startx[1164]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Nov 26 07:51:02 volumiogolab systemd[1]: soundcard-init.service: Succeeded. Nov 26 07:51:02 volumiogolab ntpd[1138]: Soliciting pool server 95.110.254.234 Nov 26 07:51:02 volumiogolab ntpd[1138]: Soliciting pool server 216.128.27.239 Nov 26 07:51:03 volumiogolab ntpd[1138]: Soliciting pool server 212.45.144.88 Nov 26 07:51:03 volumiogolab ntpd[1138]: Soliciting pool server 212.45.144.88 Nov 26 07:51:03 volumiogolab ntpd[1138]: Soliciting pool server 212.45.144.3 Nov 26 07:51:04 volumiogolab volumio[1161]: info: ------------------------------------------- Nov 26 07:51:04 volumiogolab volumio[1161]: info: ----- Volumio3 ---- Nov 26 07:51:04 volumiogolab volumio[1161]: info: ------------------------------------------- Nov 26 07:51:04 volumiogolab volumio[1161]: info: ----- System startup ---- Nov 26 07:51:04 volumiogolab volumio[1161]: info: ------------------------------------------- Nov 26 07:51:04 volumiogolab nmbd[925]: [2024/11/26 07:51:04.041279, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 26 07:51:04 volumiogolab systemd[1]: Started Samba NMB Daemon. Nov 26 07:51:04 volumiogolab nmbd[925]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 26 07:51:04 volumiogolab systemd[1]: Starting Samba Winbind Daemon... Nov 26 07:51:04 volumiogolab startx[1164]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Nov 26 07:51:04 volumiogolab winbindd[1309]: [2024/11/26 07:51:04.384109, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 26 07:51:04 volumiogolab winbindd[1309]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 26 07:51:04 volumiogolab winbindd[1309]: [2024/11/26 07:51:04.396476, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 26 07:51:04 volumiogolab systemd[1]: Started Samba Winbind Daemon. Nov 26 07:51:04 volumiogolab winbindd[1309]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 26 07:51:04 volumiogolab systemd[1]: Starting Samba SMB Daemon... Nov 26 07:51:04 volumiogolab ntpd[1138]: Soliciting pool server 188.213.165.209 Nov 26 07:51:04 volumiogolab ntpd[1138]: Soliciting pool server 93.44.243.48 Nov 26 07:51:04 volumiogolab ntpd[1138]: Soliciting pool server 185.157.229.254 Nov 26 07:51:05 volumiogolab smbd[1314]: [2024/11/26 07:51:05.272993, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 26 07:51:05 volumiogolab smbd[1314]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 26 07:51:05 volumiogolab systemd[1]: Started Samba SMB Daemon. Nov 26 07:51:05 volumiogolab systemd[1]: Reached target Multi-User System. Nov 26 07:51:05 volumiogolab systemd[1]: Reached target Graphical Interface. Nov 26 07:51:05 volumiogolab systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 26 07:51:05 volumiogolab systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 26 07:51:05 volumiogolab systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 26 07:51:05 volumiogolab systemd[1]: Startup finished in 15.163s (kernel) + 24.889s (userspace) = 40.053s. Nov 26 07:51:05 volumiogolab volumio[1161]: info: MYVOLUMIO Environment detected Nov 26 07:51:05 volumiogolab ntpd[1138]: Soliciting pool server 45.135.69.197 Nov 26 07:51:05 volumiogolab ntpd[1138]: Soliciting pool server 2001:760:2602::233 Nov 26 07:51:05 volumiogolab ntpd[1138]: Soliciting pool server 89.46.74.148 Nov 26 07:51:06 volumiogolab ntpd[1138]: Soliciting pool server 129.152.16.145 Nov 26 07:51:06 volumiogolab volumio[1161]: info: Plugin folders cleanup Nov 26 07:51:06 volumiogolab volumio[1161]: info: Scanning into folder /volumio/app/plugins/ Nov 26 07:51:06 volumiogolab volumio[1161]: info: Scanning category audio_interface Nov 26 07:51:06 volumiogolab volumio[1161]: info: Scanning category miscellanea Nov 26 07:51:06 volumiogolab volumio[1161]: info: Scanning category music_service Nov 26 07:51:06 volumiogolab volumio[1161]: info: Scanning category plugins.json Nov 26 07:51:06 volumiogolab volumio[1161]: info: Scanning category system_controller Nov 26 07:51:06 volumiogolab volumio[1161]: info: Scanning category user_interface Nov 26 07:51:06 volumiogolab volumio[1161]: info: Scanning into folder /data/plugins/ Nov 26 07:51:06 volumiogolab volumio[1161]: info: Scanning category audio_interface Nov 26 07:51:06 volumiogolab volumio[1161]: info: Scanning category music_service Nov 26 07:51:06 volumiogolab volumio[1161]: info: Scanning category system_controller Nov 26 07:51:06 volumiogolab volumio[1161]: info: Scanning category user_interface Nov 26 07:51:06 volumiogolab volumio[1161]: info: Plugin folders cleanup completed Nov 26 07:51:06 volumiogolab volumio[1161]: info: ------------------------------------------- Nov 26 07:51:06 volumiogolab volumio[1161]: info: ----- Core plugins startup ---- Nov 26 07:51:06 volumiogolab volumio[1161]: info: ------------------------------------------- Nov 26 07:51:06 volumiogolab volumio[1161]: info: Loading plugins from folder /volumio/app/plugins/ Nov 26 07:51:06 volumiogolab volumio[1161]: info: Adding plugin upnp to MyMusic Plugins Nov 26 07:51:06 volumiogolab volumio[1161]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 26 07:51:06 volumiogolab volumio[1161]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 26 07:51:06 volumiogolab volumio[1161]: info: Loading plugins from folder /data/plugins/ Nov 26 07:51:06 volumiogolab volumio[1161]: info: Loading plugin "system"... Nov 26 07:51:07 volumiogolab volumio[1161]: info: Loading plugin "appearance"... Nov 26 07:51:08 volumiogolab volumio-remote-updater[755]: [2024-11-26 07:51:08] [connect] Successful connection Nov 26 07:51:09 volumiogolab volumio[1161]: info: Loading plugin "network"... Nov 26 07:51:09 volumiogolab volumio[1161]: info: Refreshing Cached IP Addresses Nov 26 07:51:09 volumiogolab sudo[1390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 07:51:09 volumiogolab sudo[1390]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:09 volumiogolab sudo[1392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 07:51:09 volumiogolab sudo[1392]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:09 volumiogolab sudo[1390]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:09 volumiogolab sudo[1392]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:09 volumiogolab volumio[1161]: info: Loading plugin "services"... Nov 26 07:51:09 volumiogolab volumio[1161]: info: Loading plugin "alsa_controller"... Nov 26 07:51:09 volumiogolab sudo[1405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 26 07:51:09 volumiogolab sudo[1405]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:09 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 07:51:09 volumiogolab volumio[1161]: info: Loading plugin "wizard"... Nov 26 07:51:10 volumiogolab ntpd[1138]: receive: Unexpected origin timestamp 0xeaeffc6d.be27892c does not match aorg 0000000000.00000000 from server@212.45.144.3 xmt 0xeaeffc6e.1436ecd6 Nov 26 07:51:10 volumiogolab ntpd[1138]: receive: Unexpected origin timestamp 0xeaeffc6d.be1ad710 does not match aorg 0000000000.00000000 from server@129.152.16.145 xmt 0xeaeffc6e.149b5aa9 Nov 26 07:51:10 volumiogolab ntpd[1138]: receive: Unexpected origin timestamp 0xeaeffc6d.be22ecb5 does not match aorg 0000000000.00000000 from server@185.157.229.254 xmt 0xeaeffc6e.1530da43 Nov 26 07:51:10 volumiogolab volumio[1161]: info: Loading plugin "networkfs"... Nov 26 07:51:10 volumiogolab ntpd[1138]: receive: Unexpected origin timestamp 0xeaeffc6d.be265409 does not match aorg 0000000000.00000000 from server@188.213.165.209 xmt 0xeaeffc6e.152640e9 Nov 26 07:51:10 volumiogolab ntpd[1138]: receive: Unexpected origin timestamp 0xeaeffc6d.be29dcc0 does not match aorg 0000000000.00000000 from server@95.110.254.234 xmt 0xeaeffc6e.14cb1e3e Nov 26 07:51:10 volumiogolab ntpd[1138]: receive: Unexpected origin timestamp 0xeaeffc6d.be24c71f does not match aorg 0000000000.00000000 from server@93.44.243.48 xmt 0xeaeffc6e.15e95852 Nov 26 07:51:10 volumiogolab ntpd[1138]: receive: Unexpected origin timestamp 0xeaeffc6d.be28b32a does not match aorg 0000000000.00000000 from server@216.128.27.239 xmt 0xeaeffc6e.1b51469e Nov 26 07:51:10 volumiogolab volumio[1161]: info: Starting Udev Watcher for removable devices Nov 26 07:51:10 volumiogolab sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=mantissa,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.31/Multimedia /mnt/NAS/QNAP-NAS Nov 26 07:51:10 volumiogolab sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:10 volumiogolab volumio[1161]: info: Mounting Device EFI Nov 26 07:51:10 volumiogolab kernel: FS-Cache: Loaded Nov 26 07:51:10 volumiogolab kernel: Key type dns_resolver registered Nov 26 07:51:10 volumiogolab sudo[1426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/EFI -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 26 07:51:10 volumiogolab sudo[1426]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:10 volumiogolab kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Nov 26 07:51:10 volumiogolab kernel: FAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Nov 26 07:51:10 volumiogolab sudo[1426]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:10 volumiogolab volumio[1161]: info: Mounting Device F640-69F2 Nov 26 07:51:10 volumiogolab kernel: Key type cifs.spnego registered Nov 26 07:51:10 volumiogolab kernel: Key type cifs.idmap registered Nov 26 07:51:10 volumiogolab sudo[1433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/F640-69F2 -o noatime Nov 26 07:51:10 volumiogolab sudo[1433]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:10 volumiogolab 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. Nov 26 07:51:10 volumiogolab kernel: CIFS: Attempting to mount //192.168.1.31/Multimedia Nov 26 07:51:11 volumiogolab sudo[1433]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:11 volumiogolab volumio[1161]: info: Ignoring mount for partition: boot Nov 26 07:51:11 volumiogolab volumio[1161]: info: Ignoring mount for partition: volumio Nov 26 07:51:11 volumiogolab volumio[1161]: info: Ignoring mount for partition: volumio_data Nov 26 07:51:11 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 07:51:11 volumiogolab volumio[1161]: info: Loading plugin "volumio_command_line_client"... Nov 26 07:51:11 volumiogolab volumio[1161]: info: Loading plugin "upnp"... Nov 26 07:51:11 volumiogolab volumio[1161]: info: [1732607471075] Starting Upmpd Daemon Nov 26 07:51:11 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 07:51:11 volumiogolab volumio[1161]: info: Loading plugin "my_music"... Nov 26 07:51:11 volumiogolab volumio[1161]: info: Loading plugin "mpd"... Nov 26 07:51:11 volumiogolab volumio[1161]: info: Loading plugin "upnp_browser"... Nov 26 07:51:11 volumiogolab volumio[1161]: info: Loading plugin "alarm-clock"... Nov 26 07:51:11 volumiogolab volumio[1161]: info: Loading plugin "airplay_emulation"... Nov 26 07:51:12 volumiogolab volumio[1161]: info: Starting Shairport Sync Nov 26 07:51:12 volumiogolab volumio[1161]: info: Loading plugin "last_100"... Nov 26 07:51:12 volumiogolab volumio[1161]: info: Loading plugin "webradio"... Nov 26 07:51:12 volumiogolab volumio[1161]: info: Loading plugin "i2s_dacs"... Nov 26 07:51:12 volumiogolab volumio[1161]: info: I2S DAC not set, start Auto-detection Nov 26 07:51:12 volumiogolab volumio[1161]: info: Loading plugin "volumiodiscovery"... Nov 26 07:51:12 volumiogolab volumio[1161]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 26 07:51:12 volumiogolab volumio[1161]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 07:51:12 volumiogolab volumio[1161]: *** WARNING *** For more information see Nov 26 07:51:12 volumiogolab volumio[1161]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 26 07:51:12 volumiogolab node[1161]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 26 07:51:12 volumiogolab volumio[1161]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 07:51:12 volumiogolab volumio[1161]: *** WARNING *** For more information see Nov 26 07:51:12 volumiogolab node[1161]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 07:51:12 volumiogolab node[1161]: *** WARNING *** For more information see Nov 26 07:51:12 volumiogolab node[1161]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 26 07:51:12 volumiogolab node[1161]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 07:51:12 volumiogolab node[1161]: *** WARNING *** For more information see Nov 26 07:51:12 volumiogolab volumio[1161]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 26 07:51:12 volumiogolab volumio[1161]: info: Discovery: Started advertising with name: VolumioGOLAB Nov 26 07:51:12 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 07:51:12 volumiogolab volumio[1161]: info: Loading plugin "mixcloud"... Nov 26 07:51:12 volumiogolab sudo[1405]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:14 volumiogolab systemd[1]: systemd-fsckd.service: Succeeded. Nov 26 07:51:15 volumiogolab volumio[1161]: info: Loading plugin "soundcloud"... Nov 26 07:51:16 volumiogolab volumio[1161]: info: Loading plugin "spop"... Nov 26 07:51:16 volumiogolab sh[686]: timed out Nov 26 07:51:16 volumiogolab sh[686]: dhcpcd exited Nov 26 07:51:16 volumiogolab dhcpcd[731]: timed out Nov 26 07:51:16 volumiogolab dhcpcd[731]: dhcpcd exited Nov 26 07:51:16 volumiogolab sh[686]: ifup: failed to bring up eth0 Nov 26 07:51:16 volumiogolab systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Nov 26 07:51:16 volumiogolab systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Nov 26 07:51:16 volumiogolab sudo[1416]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:16 volumiogolab kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 26 07:51:16 volumiogolab kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Nov 26 07:51:17 volumiogolab systemd[1]: systemd-hostnamed.service: Succeeded. Nov 26 07:51:17 volumiogolab volumio[1161]: info: Loading plugin "ytcr"... Nov 26 07:51:20 volumiogolab volumio[1161]: info: Loading plugin "ytmusic"... Nov 26 07:51:21 volumiogolab volumio[1161]: info: Loading plugin "autostart"... Nov 26 07:51:21 volumiogolab volumio[1161]: info: Applying required configuration parameters for plugin autostart Nov 26 07:51:21 volumiogolab volumio[1161]: info: AutoStart - onVolumioStart - read config.json Nov 26 07:51:21 volumiogolab volumio[1161]: info: Loading plugin "now_playing"... Nov 26 07:51:23 volumiogolab volumio[1161]: info: Loading plugin "outputs"... Nov 26 07:51:23 volumiogolab volumio[1161]: info: Loading plugin "albumart"... Nov 26 07:51:23 volumiogolab volumio[1161]: info: Plugin example_plugin is not enabled Nov 26 07:51:23 volumiogolab volumio[1161]: info: Loading plugin "inputs"... Nov 26 07:51:23 volumiogolab volumio[1161]: info: Loading plugin "updater_comm"... Nov 26 07:51:23 volumiogolab volumio[1161]: Forking 1 albumart workers Nov 26 07:51:23 volumiogolab volumio[1161]: info: Plugin mpdemulation is not enabled Nov 26 07:51:23 volumiogolab volumio[1161]: info: Loading plugin "rest_api"... Nov 26 07:51:23 volumiogolab volumio[1161]: info: Loading plugin "websocket"... Nov 26 07:51:23 volumiogolab volumio[1161]: info: Starting Socket.io Server version 2.3.0 Nov 26 07:51:23 volumiogolab volumio[1161]: info: Loading plugin "fusiondsp"... Nov 26 07:51:24 volumiogolab volumio[1161]: Starting albumart workers Nov 26 07:51:24 volumiogolab volumio-remote-updater[755]: [2024-11-26 07:51:24] [connect] Successful connection Nov 26 07:51:24 volumiogolab volumio[1161]: info: Applying required configuration parameters for plugin fusiondsp Nov 26 07:51:24 volumiogolab volumio[1161]: info: Loading plugin "RoonBridge"... Nov 26 07:51:24 volumiogolab volumio[1161]: info: Applying required configuration parameters for plugin RoonBridge Nov 26 07:51:24 volumiogolab volumio[1161]: info: Loading plugin "minidlna"... Nov 26 07:51:24 volumiogolab volumio[1161]: info: Applying required configuration parameters for plugin minidlna Nov 26 07:51:24 volumiogolab volumio[1161]: info: Loading plugin "motherearthradio"... Nov 26 07:51:24 volumiogolab volumio[1161]: info: Applying required configuration parameters for plugin motherearthradio Nov 26 07:51:24 volumiogolab volumio[1161]: info: [1732607484970] [MotherEarth] API delay: 5 Nov 26 07:51:24 volumiogolab volumio[1161]: info: Loading plugin "backup_restore"... Nov 26 07:51:25 volumiogolab volumio[1161]: info: Applying required configuration parameters for plugin backup_restore Nov 26 07:51:25 volumiogolab volumio[1161]: info: Loading plugin "Systeminfo"... Nov 26 07:51:25 volumiogolab volumio[1161]: info: Loading plugin "randomizer"... Nov 26 07:51:25 volumiogolab volumio[1161]: info: Applying required configuration parameters for plugin randomizer Nov 26 07:51:25 volumiogolab volumio[1161]: info: Loading i18n strings for locale it Nov 26 07:51:25 volumiogolab volumio[1161]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Nov 26 07:51:25 volumiogolab volumio[1161]: Updating browse sources language Nov 26 07:51:25 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 07:51:25 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 07:51:25 volumiogolab volumio[1161]: info: CoreCommandRouter::initPlayerControls Nov 26 07:51:25 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:25 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:25 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:25 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:25 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:25 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 07:51:25 volumiogolab volumio[1161]: Express server listening on port 3000 Nov 26 07:51:25 volumiogolab volumio[1161]: [Metrics] WebUI: 22s 217.81ms Nov 26 07:51:25 volumiogolab volumio[1161]: info: Setting Device type: x86 Nov 26 07:51:25 volumiogolab volumio[1161]: info: CoreStateMachine::resetVolumioState Nov 26 07:51:25 volumiogolab volumio[1161]: info: CoreStateMachine::getcurrentVolume Nov 26 07:51:25 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 07:51:25 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:25 volumiogolab sudo[1579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 07:51:25 volumiogolab sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:25 volumiogolab sudo[1579]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:25 volumiogolab sudo[1582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 07:51:25 volumiogolab sudo[1582]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:25 volumiogolab volumio[1161]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 26 07:51:25 volumiogolab sudo[1582]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:25 volumiogolab volumio[1161]: info: Completed loading Core Plugins Nov 26 07:51:25 volumiogolab volumio[1161]: info: Preparing to generate the ALSA configuration file Nov 26 07:51:26 volumiogolab volumio[1161]: info: Volumio Network Manager: Network status updated: 2 Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreStateMachine::pushState Nov 26 07:51:26 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioPushState Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreStateMachine::updateTrackBlock Nov 26 07:51:26 volumiogolab volumio[1161]: info: CorePlayQueue::getTrackBlock Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 07:51:26 volumiogolab volumio[1161]: info: Cannot mount NAS QNAP-NAS at system boot, trial number 1 ,retrying in 5 seconds Nov 26 07:51:26 volumiogolab volumio[1161]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Nov 26 07:51:26 volumiogolab volumio[1161]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 26 07:51:26 volumiogolab volumio[1161]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 26 07:51:26 volumiogolab volumio[1161]: info: Reading ALSA contributions from plugins. Nov 26 07:51:26 volumiogolab volumio-remote-updater[755]: [2024-11-26 07:51:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732607484 101 Nov 26 07:51:26 volumiogolab volumio[1161]: 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 Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreStateMachine::pushState Nov 26 07:51:26 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioPushState Nov 26 07:51:26 volumiogolab volumio[1161]: info: Reloading queue from file Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreStateMachine::setRepeat null single undefined Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreStateMachine::pushState Nov 26 07:51:26 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioPushState Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreStateMachine::setRandom true Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreStateMachine::pushState Nov 26 07:51:26 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioPushState Nov 26 07:51:26 volumiogolab volumio[1161]: info: Asound.conf file written Nov 26 07:51:26 volumiogolab sudo[1596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 26 07:51:26 volumiogolab sudo[1596]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:26 volumiogolab sudo[1598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 26 07:51:26 volumiogolab sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:26 volumiogolab systemd[1]: Started UPnP Renderer front-end to MPD. Nov 26 07:51:26 volumiogolab sudo[1596]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:26 volumiogolab sudo[1598]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:26 volumiogolab volumio[1161]: info: Output device has changed, restarting MPD Nov 26 07:51:26 volumiogolab sudo[1605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 07:51:26 volumiogolab sudo[1605]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:26 volumiogolab sudo[1605]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:26 volumiogolab volumio[1161]: info: Output device has changed, restarting Shairport Sync Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:26 volumiogolab sudo[1608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 07:51:26 volumiogolab sudo[1608]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:26 volumiogolab systemd[1]: Stopping Music Player Daemon... Nov 26 07:51:26 volumiogolab volumio[1161]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 07:51:26 volumiogolab volumio[1161]: info: ___________ START PLUGINS ___________ Nov 26 07:51:26 volumiogolab volumio[1161]: info: ControllerMpd::onStart: Initializing MPD Nov 26 07:51:26 volumiogolab volumio[1161]: info: Creating MPD Configuration file Nov 26 07:51:26 volumiogolab systemd[1]: mpd.service: Succeeded. Nov 26 07:51:26 volumiogolab systemd[1]: Stopped Music Player Daemon. Nov 26 07:51:26 volumiogolab sudo[1615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 07:51:26 volumiogolab sudo[1615]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:26 volumiogolab systemd[1]: Starting Music Player Daemon... Nov 26 07:51:26 volumiogolab sudo[1615]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 07:51:26 volumiogolab volumio[1161]: info: [1732607486562] CoreMusicLibrary::Adding element Server multimediali Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 07:51:26 volumiogolab sudo[1618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 07:51:26 volumiogolab sudo[1618]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:26 volumiogolab systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 26 07:51:26 volumiogolab systemd[1]: mpd.service: Succeeded. Nov 26 07:51:26 volumiogolab systemd[1]: Stopped Music Player Daemon. Nov 26 07:51:26 volumiogolab systemd[1]: Starting Music Player Daemon... Nov 26 07:51:26 volumiogolab volumio[1161]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:26 volumiogolab volumio[1161]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 07:51:26 volumiogolab volumio[1161]: info: [1732607486651] CoreMusicLibrary::Adding element Last_100 Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 07:51:26 volumiogolab volumio[1161]: info: [1732607486653] CoreMusicLibrary::Adding element Webradio Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 07:51:26 volumiogolab volumio[1161]: info: Initializing BBC Radios Nov 26 07:51:26 volumiogolab sudo[1623]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 07:51:26 volumiogolab sudo[1623]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:26 volumiogolab sudo[1623]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 07:51:26 volumiogolab volumio[1161]: info: [1732607486766] CoreMusicLibrary::Adding element Mixcloud Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 07:51:26 volumiogolab volumio[1161]: Cannot find translation for source Mixcloud Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 07:51:26 volumiogolab volumio[1161]: info: [1732607486790] CoreMusicLibrary::Adding element SoundCloud Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 07:51:26 volumiogolab volumio[1161]: Cannot find translation for source Mixcloud Nov 26 07:51:26 volumiogolab volumio[1161]: Cannot find translation for source SoundCloud Nov 26 07:51:26 volumiogolab volumio[1161]: info: Creating Spotify config file Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 07:51:26 volumiogolab volumio[1161]: info: [1732607486911] CoreMusicLibrary::Adding element YouTube Music Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 07:51:26 volumiogolab volumio[1161]: Cannot find translation for source Mixcloud Nov 26 07:51:26 volumiogolab volumio[1161]: Cannot find translation for source SoundCloud Nov 26 07:51:26 volumiogolab volumio[1161]: Cannot find translation for source YouTube Music Nov 26 07:51:26 volumiogolab volumio[1161]: info: AutoStart - onStart Nov 26 07:51:26 volumiogolab volumio[1161]: info: [now-playing] ConfigUpdater: config is up to date. Nov 26 07:51:26 volumiogolab volumio[1161]: info: Loading i18n strings for locale it Nov 26 07:51:26 volumiogolab volumio[1161]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 26 07:51:26 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:26 volumiogolab volumio[1161]: info: FusionDsp - mixtype--------------------- Software Nov 26 07:51:26 volumiogolab volumio[1161]: info: Loading i18n strings for locale it Nov 26 07:51:26 volumiogolab volumio[1161]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Nov 26 07:51:26 volumiogolab sudo[1640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Nov 26 07:51:26 volumiogolab sudo[1640]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:27 volumiogolab systemd[1]: Started RoonBridge. Nov 26 07:51:27 volumiogolab sudo[1640]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:27 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 07:51:27 volumiogolab volumio[1161]: info: [1732607487034] CoreMusicLibrary::Adding element Mother Earth Radio Nov 26 07:51:27 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 07:51:27 volumiogolab volumio[1161]: Cannot find translation for source Mixcloud Nov 26 07:51:27 volumiogolab volumio[1161]: Cannot find translation for source SoundCloud Nov 26 07:51:27 volumiogolab volumio[1161]: Cannot find translation for source YouTube Music Nov 26 07:51:27 volumiogolab volumio[1161]: Cannot find translation for source Mother Earth Radio Nov 26 07:51:27 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 07:51:27 volumiogolab volumio[1161]: info: [1732607487072] CoreMusicLibrary::Adding element Randomizer Nov 26 07:51:27 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 07:51:27 volumiogolab volumio[1161]: Cannot find translation for source Mixcloud Nov 26 07:51:27 volumiogolab volumio[1161]: Cannot find translation for source SoundCloud Nov 26 07:51:27 volumiogolab volumio[1161]: Cannot find translation for source YouTube Music Nov 26 07:51:27 volumiogolab volumio[1161]: Cannot find translation for source Mother Earth Radio Nov 26 07:51:27 volumiogolab volumio[1161]: Cannot find translation for source Randomizer Nov 26 07:51:27 volumiogolab volumio[1161]: info: Volumio Calling Home Nov 26 07:51:27 volumiogolab volumio[1161]: info: Preparing to generate the ALSA configuration file Nov 26 07:51:27 volumiogolab mpd[1629]: Nov 26 07:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 26 07:51:27 volumiogolab systemd[1]: Started Music Player Daemon. Nov 26 07:51:27 volumiogolab sudo[1618]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:27 volumiogolab sudo[1608]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:27 volumiogolab volumio[1161]: info: [now-playing] App is listening on port 4004. Nov 26 07:51:27 volumiogolab volumio[1161]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Nov 26 07:51:27 volumiogolab volumio[1161]: info: Discovery: adding 617101c9-f0df-4c51-8931-ac3b10020969 Nov 26 07:51:27 volumiogolab volumio[1161]: info: Discovery: Found device VolumioGOLAB Nov 26 07:51:27 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioGetState Nov 26 07:51:27 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:27 volumiogolab volumio[1161]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 26 07:51:27 volumiogolab volumio[1161]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 26 07:51:27 volumiogolab volumio[1161]: info: Reading ALSA contributions from plugins. Nov 26 07:51:27 volumiogolab volumio[1161]: info: MPD Permissions set Nov 26 07:51:27 volumiogolab volumio[1161]: info: MPD Permissions set Nov 26 07:51:27 volumiogolab volumio[1161]: info: Upmpdcli Daemon Started Nov 26 07:51:27 volumiogolab volumio[1161]: info: Volumio called home Nov 26 07:51:27 volumiogolab volumio[1161]: info: Spotify config file written Nov 26 07:51:27 volumiogolab sudo[1660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 26 07:51:27 volumiogolab sudo[1660]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:27 volumiogolab volumio[1161]: error: MPD error: The expression evaluated to a falsy value: Nov 26 07:51:27 volumiogolab volumio[1161]: assert.ok(self.idling) Nov 26 07:51:27 volumiogolab volumio[1161]: error: The expression evaluated to a falsy value: Nov 26 07:51:27 volumiogolab volumio[1161]: assert.ok(self.idling) Nov 26 07:51:27 volumiogolab systemd[1]: Started go-librespot Daemon. Nov 26 07:51:27 volumiogolab volumio[1161]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Nov 26 07:51:27 volumiogolab go-librespot[1663]: Librespot-go daemon starting... Nov 26 07:51:27 volumiogolab volumio[1161]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 26 07:51:27 volumiogolab sudo[1660]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:27 volumiogolab volumio[1161]: info: MPD running with PID1629 Nov 26 07:51:27 volumiogolab volumio[1161]: ,establishing connection Nov 26 07:51:27 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:27 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:27 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:27 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:27 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:27 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:27 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:27 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 26 07:51:27 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:27 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:27 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:27 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 26 07:51:27 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:27 volumiogolab volumio[1161]: info: No need to fix Spotify hosts Nov 26 07:51:27 volumiogolab volumio[1161]: error: updateQueue error: null Nov 26 07:51:28 volumiogolab volumio[1161]: error: error Nov 26 07:51:28 volumiogolab volumio[1161]: error: error Nov 26 07:51:28 volumiogolab volumio[1161]: error: error Nov 26 07:51:28 volumiogolab volumio[1161]: info: minidlna: Found miniDLNA version 1.2.1 Nov 26 07:51:28 volumiogolab volumio[1161]: info: minidlna: Starting minidlna.service Nov 26 07:51:29 volumiogolab sudo[1684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Nov 26 07:51:29 volumiogolab sudo[1684]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:29 volumiogolab systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Nov 26 07:51:29 volumiogolab sudo[1684]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:29 volumiogolab volumio[1161]: info: camilladsp spawned new process with pid 1693, instance 1, run: true Nov 26 07:51:29 volumiogolab volumio[1161]: info: camilladsp service started and running in background, instance 1 Nov 26 07:51:29 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:29 volumiogolab volumio[1161]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 96000 <-AAAAAAAAAAAAA Nov 26 07:51:29 volumiogolab volumio[1161]: info: FusionDsp loaded Nov 26 07:51:29 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 07:51:29 volumiogolab sudo[1698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 26 07:51:29 volumiogolab sudo[1698]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:29 volumiogolab volumio[1161]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 26 07:51:29 volumiogolab volumio[1161]: info: Adding Signal Path Element [object Object] Nov 26 07:51:29 volumiogolab volumio[1161]: info: Adding fusiondspeq DSP Signal Path Element Nov 26 07:51:29 volumiogolab volumio[1161]: info: FusionDsp - ---- installed callbackRead Nov 26 07:51:29 volumiogolab volumio[1161]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 07:51:29 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:29Z" level=info msg="generated new device id: 03f48bb2f904cd35c3089fa4c3398455a5ce6bea" Nov 26 07:51:29 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:29Z" level=debug msg="stored credentials found for giulio.olmetti" Nov 26 07:51:29 volumiogolab systemd[1]: Started FusionDsp Daemon. Nov 26 07:51:29 volumiogolab sudo[1698]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:29 volumiogolab volumio[1161]: info: minidlna: systemctl start minidlna.service succeeded. Nov 26 07:51:29 volumiogolab volumio[1161]: error: updateQueue error: null Nov 26 07:51:29 volumiogolab volumio[1161]: error: FusionDsp - WebSocket error: [object Object] Nov 26 07:51:29 volumiogolab volumio[1161]: error: error Nov 26 07:51:29 volumiogolab volumio[1161]: error: error Nov 26 07:51:29 volumiogolab volumio[1161]: error: error Nov 26 07:51:29 volumiogolab volumio[1161]: error: error Nov 26 07:51:29 volumiogolab volumio[1161]: error: error Nov 26 07:51:29 volumiogolab volumio[1161]: error: error Nov 26 07:51:29 volumiogolab volumio[1161]: error: error Nov 26 07:51:29 volumiogolab volumio[1161]: error: error Nov 26 07:51:29 volumiogolab volumio[1161]: error: error Nov 26 07:51:29 volumiogolab volumio[1161]: error: error Nov 26 07:51:29 volumiogolab volumio[1161]: error: error Nov 26 07:51:29 volumiogolab volumio[1161]: error: error Nov 26 07:51:29 volumiogolab volumio[1161]: info: Starting Shairport Sync Nov 26 07:51:29 volumiogolab volumio[1161]: info: Starting Shairport Sync Nov 26 07:51:29 volumiogolab volumio[1161]: info: Starting Shairport Sync Nov 26 07:51:29 volumiogolab volumio[1161]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Nov 26 07:51:29 volumiogolab volumio[1161]: SPOTIFY: BQC-mHYoMrvglS4y9Hzf4Jrz020IG3aleAqLesonK64clG06WfHT-oKEG6gd90IsN3J85pSzRdGg42ZN5xXqXT72Q8ZXcos14MQoSyCaD3ZmnFnVD_RQyg6JcD2X2AbK1qlxLY2SmiFcpotG3W39WkldFs_vfBZX9J47cl9exSdsRqYkA5UtxSvInNG1qd9EXn_nuxqcwGWv182x5bZyp8hPzzWLEde5R2tTrq41a9oOZWSrtRJRuzSrfI0m Nov 26 07:51:29 volumiogolab volumio[1161]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Nov 26 07:51:29 volumiogolab volumio[1161]: info: New Spotify access token = BQC-mHYoMrvglS4y9Hzf4Jrz020IG3aleAqLesonK64clG06WfHT-oKEG6gd90IsN3J85pSzRdGg42ZN5xXqXT72Q8ZXcos14MQoSyCaD3ZmnFnVD_RQyg6JcD2X2AbK1qlxLY2SmiFcpotG3W39WkldFs_vfBZX9J47cl9exSdsRqYkA5UtxSvInNG1qd9EXn_nuxqcwGWv182x5bZyp8hPzzWLEde5R2tTrq41a9oOZWSrtRJRuzSrfI0m Nov 26 07:51:29 volumiogolab volumio[1161]: info: Spotify credentials grant success - running version from March 24, 2019 Nov 26 07:51:29 volumiogolab sudo[1704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 26 07:51:29 volumiogolab sudo[1704]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:29 volumiogolab sudo[1708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 26 07:51:29 volumiogolab sudo[1708]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:29 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioGetState Nov 26 07:51:29 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:29 volumiogolab sudo[1706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 26 07:51:29 volumiogolab sudo[1706]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:29 volumiogolab volumio[1161]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 26 07:51:29 volumiogolab volumio[1161]: info: Asound.conf file unchanged, so no further update is needed Nov 26 07:51:29 volumiogolab volumio[1161]: info: Output device has changed, restarting MPD Nov 26 07:51:29 volumiogolab volumio[1161]: info: Output device has changed, restarting Shairport Sync Nov 26 07:51:29 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:29 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:29 volumiogolab sudo[1723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 07:51:29 volumiogolab systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 26 07:51:29 volumiogolab systemd[1]: shairport-sync.service: Succeeded. Nov 26 07:51:29 volumiogolab sudo[1723]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:29 volumiogolab systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 26 07:51:29 volumiogolab sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 07:51:29 volumiogolab sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:29 volumiogolab sudo[1721]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:29 volumiogolab systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 26 07:51:29 volumiogolab sudo[1704]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:29 volumiogolab sudo[1706]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:29 volumiogolab sudo[1708]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:29 volumiogolab systemd[1]: Stopping Music Player Daemon... Nov 26 07:51:29 volumiogolab systemd[1]: mpd.service: Succeeded. Nov 26 07:51:29 volumiogolab systemd[1]: Stopped Music Player Daemon. Nov 26 07:51:29 volumiogolab volumio[1161]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 07:51:29 volumiogolab systemd[1]: Starting Music Player Daemon... Nov 26 07:51:29 volumiogolab RoonBridge[1642]: 00:00:00.007 Warn: get lock file path: /tmp/.rnbgem0- Nov 26 07:51:29 volumiogolab sudo[1731]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 07:51:29 volumiogolab sudo[1731]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:29 volumiogolab sudo[1731]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:30 volumiogolab volumio[1161]: info: MPD Permissions set Nov 26 07:51:30 volumiogolab volumio[1161]: info: Shairport-Sync Started Nov 26 07:51:30 volumiogolab volumio[1161]: Error adding Membership: Error: addMembership EINVAL Nov 26 07:51:30 volumiogolab volumio[1161]: info: Shairport-Sync Started Nov 26 07:51:30 volumiogolab volumio[1161]: info: Shairport-Sync Started Nov 26 07:51:30 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:30 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:30 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 26 07:51:30 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:30Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Nov 26 07:51:30 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:30Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Nov 26 07:51:30 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:30Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Nov 26 07:51:30 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:30Z" level=debug msg="zeroconf server listening on port 38713" Nov 26 07:51:30 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:30 volumiogolab volumio[1161]: info: Starting Shairport Sync Nov 26 07:51:30 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:30Z" level=debug msg="obtained new client token: AAAPkw9x/d24LddDaHNXF8HJdkjnkqGJPtoSrcj8HWkKMYoz98Jetzo4Gfxr+Pd3OW5M2VsjR/S6bLfrmIcrfdlkrZSm7JgdSRu5C+ZWGz76XI7GyZL/1uxaGJuceh1D/A+ccvCfliwQjv/vaUQI0SiPuFI22AeS9+Ibjf2g7bNbkIRyJMgBf7c6GhsZhPhNaH+buZsQeuRDt2+atga5W0sDNMxKh8Ca2khH1sQbroak1FCgXnq0R2J1v8E=" Nov 26 07:51:30 volumiogolab sudo[1758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 26 07:51:30 volumiogolab sudo[1758]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:30 volumiogolab volumio[1161]: SPOTIFY: User informations: {"country":"IT","display_name":"giulio.olmetti","email":"giulio.olmetti@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/giulio.olmetti"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/giulio.olmetti","id":"giulio.olmetti","images":[],"product":"free","type":"user","uri":"spotify:user:giulio.olmetti"} Nov 26 07:51:30 volumiogolab systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 26 07:51:30 volumiogolab volumio[1161]: info: Spotify Successfully logged in Nov 26 07:51:30 volumiogolab systemd[1]: shairport-sync.service: Succeeded. Nov 26 07:51:30 volumiogolab systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 26 07:51:30 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 07:51:30 volumiogolab volumio[1161]: info: [1732607490391] CoreMusicLibrary::Adding element Spotify Nov 26 07:51:30 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 07:51:30 volumiogolab volumio[1161]: Cannot find translation for source Mixcloud Nov 26 07:51:30 volumiogolab volumio[1161]: Cannot find translation for source SoundCloud Nov 26 07:51:30 volumiogolab volumio[1161]: Cannot find translation for source YouTube Music Nov 26 07:51:30 volumiogolab volumio[1161]: Cannot find translation for source Mother Earth Radio Nov 26 07:51:30 volumiogolab volumio[1161]: Cannot find translation for source Randomizer Nov 26 07:51:30 volumiogolab volumio[1161]: Cannot find translation for source Spotify Nov 26 07:51:30 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:30Z" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 26 07:51:30 volumiogolab systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 26 07:51:30 volumiogolab sudo[1758]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:30 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:30Z" level=debug msg="completed keyexchange" Nov 26 07:51:30 volumiogolab volumio[1161]: info: Shairport-Sync Started Nov 26 07:51:30 volumiogolab RoonBridge[1642]: 00:00:00.795 Trace: [childprocess] using unix child process Nov 26 07:51:30 volumiogolab RoonBridge[1642]: 00:00:00.930 Debug: PathForResource, filename: ../.update Nov 26 07:51:30 volumiogolab mpd[1748]: Nov 26 07:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 26 07:51:30 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:30Z" level=debug msg="completed challenge" Nov 26 07:51:30 volumiogolab systemd[1]: Started Music Player Daemon. Nov 26 07:51:30 volumiogolab sudo[1723]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:30 volumiogolab volumio[1161]: error: updateQueue error: null Nov 26 07:51:30 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:30Z" level=debug msg="authenticated as giulio.olmetti" Nov 26 07:51:30 volumiogolab volumio[1161]: info: [yt-cast-receiver] DIAL server listening on port 8098 Nov 26 07:51:31 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 07:51:31 volumiogolab RoonBridge[1642]: 00:00:01.329 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Nov 26 07:51:31 volumiogolab RoonBridge[1642]: 00:00:01.339 Debug: PathForResource, filename: RAATServer Nov 26 07:51:31 volumiogolab RoonBridge[1642]: 00:00:01.347 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Nov 26 07:51:31 volumiogolab RoonBridge[1642]: 00:00:01.348 Debug: PathForResource, filename: RoonBridgeHelper Nov 26 07:51:31 volumiogolab RoonBridge[1642]: 00:00:01.350 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Nov 26 07:51:31 volumiogolab RoonBridge[1642]: Initializing Nov 26 07:51:31 volumiogolab sudo[1773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=mantissa,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.31/Multimedia /mnt/NAS/QNAP-NAS Nov 26 07:51:31 volumiogolab sudo[1773]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:31 volumiogolab kernel: CIFS: Attempting to mount //192.168.1.31/Multimedia Nov 26 07:51:31 volumiogolab RoonBridge[1642]: 00:00:01.419 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Nov 26 07:51:31 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:31Z" level=debug msg="authenticated as giulio.olmetti" Nov 26 07:51:31 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioGetState Nov 26 07:51:31 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:31 volumiogolab volumio[1161]: info: CoreStateMachine::pushState Nov 26 07:51:31 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:31 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioPushState Nov 26 07:51:31 volumiogolab volumio[1161]: info: Completed starting Core Plugins Nov 26 07:51:31 volumiogolab volumio[1161]: info: ------------------------------------------- Nov 26 07:51:31 volumiogolab volumio[1161]: info: ----- MyVolumio plugins startup ---- Nov 26 07:51:31 volumiogolab volumio[1161]: info: ------------------------------------------- Nov 26 07:51:31 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 26 07:51:31 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:31Z" level=debug msg="dealer connection opened" Nov 26 07:51:31 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:31Z" level=debug msg="initializing zeroconf session, username: giulio.olmetti" Nov 26 07:51:31 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:31Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Nov 26 07:51:31 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:31Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Nov 26 07:51:31 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:31Z" level=debug msg="autoplay enabled: false" Nov 26 07:51:31 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:31Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Nov 26 07:51:31 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:31Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Nov 26 07:51:31 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:31Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Nov 26 07:51:31 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:31Z" level=debug msg="received connection id: ZDhiODQ1NDMtNGI4NC00YzVhLWEzYjEtZDJjYWJiZjg2ODI4K2RlYWxlcit0Y3A6Ly8wYWNhNTkyMC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArRjdBQjUzQzEzNERDNUI1NjE5MDg5ODVCMTZGOTJCMDVDNTlDODkyNzREQkQzNjM1RjcxQzg4QzQwODU0RDZERA==" Nov 26 07:51:31 volumiogolab RoonBridge[1642]: 00:00:01.551 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Nov 26 07:51:31 volumiogolab systemd[1]: systemd-timedated.service: Succeeded. Nov 26 07:51:31 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:31Z" level=debug msg="put connect state because NEW_DEVICE" Nov 26 07:51:31 volumiogolab RoonBridge[1642]: Not Running (.o) Nov 26 07:51:31 volumiogolab volumio[1161]: Upnp client error: Error: read ECONNRESET Nov 26 07:51:31 volumiogolab volumio[1600]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 26 07:51:31 volumiogolab volumio[1161]: info: go-librespot daemon successfully initialized Nov 26 07:51:32 volumiogolab RoonBridge[1642]: Running Nov 26 07:51:32 volumiogolab volumio[1161]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 26 07:51:33 volumiogolab RoonBridge[1642]: 00:00:00.159 Warn: get lock file path: /tmp/.rnbhgem0- Nov 26 07:51:34 volumiogolab volumio[1161]: info: Initializing connection to go-librespot Websocket Nov 26 07:51:35 volumiogolab go-librespot[1663]: time="2024-11-26T07:51:35Z" level=debug msg="new websocket client" Nov 26 07:51:35 volumiogolab volumio[1161]: info: Connection to go-librespot Websocket established Nov 26 07:51:35 volumiogolab volumio[1161]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Nov 26 07:51:36 volumiogolab volumio[1600]: ....................................................................................................................................................................++++ Nov 26 07:51:36 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioGetState Nov 26 07:51:36 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:36 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 26 07:51:36 volumiogolab volumio[1161]: info: Listing playlists Nov 26 07:51:36 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 26 07:51:36 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 26 07:51:36 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 26 07:51:36 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 26 07:51:36 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 26 07:51:36 volumiogolab volumio[1161]: info: Received Get System Info Nov 26 07:51:36 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 26 07:51:36 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 26 07:51:36 volumiogolab volumio[1161]: info: Discovery: Getting this device information Nov 26 07:51:36 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioGetState Nov 26 07:51:36 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:36 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 07:51:37 volumiogolab sudo[1773]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:37 volumiogolab kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 26 07:51:37 volumiogolab kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Nov 26 07:51:37 volumiogolab volumio[1161]: info: Cannot mount NAS QNAP-NAS at system boot, trial number 2 ,retrying in 5 seconds Nov 26 07:51:37 volumiogolab volumio[1600]: .................................................................................++++ Nov 26 07:51:37 volumiogolab volumio[1600]: e is 65537 (0x010001) Nov 26 07:51:37 volumiogolab volumio[1600]: writing RSA key Nov 26 07:51:38 volumiogolab volumio[1161]: info: Getting Spotify volume Nov 26 07:51:38 volumiogolab volumio[1161]: info: Spotify volume: 100 Nov 26 07:51:38 volumiogolab volumio[1161]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 26 07:51:38 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioGetState Nov 26 07:51:38 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:38 volumiogolab volumio[1161]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 26 07:51:38 volumiogolab volumio[1161]: SPOTIFY: SPOTIFY VOLUME 100 Nov 26 07:51:38 volumiogolab volumio[1161]: SPOTIFY: VOLUMIO VOLUME 75 Nov 26 07:51:38 volumiogolab volumio[1161]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 26 07:51:38 volumiogolab volumio[1161]: info: Setting Spotify Volume from Volumio: 75 Nov 26 07:51:39 volumiogolab volumio[1161]: SPOTIFY: SETTING SPOTIFY VOLUME 75 Nov 26 07:51:39 volumiogolab volumio[1161]: info: Sending Spotify command with payload to local API: /player/volume Nov 26 07:51:39 volumiogolab volumio[1161]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 26 07:51:39 volumiogolab volumio[1161]: info: Adding plugin bluetooth to MyMusic Plugins Nov 26 07:51:39 volumiogolab volumio[1161]: info: Adding plugin multiroom to MyMusic Plugins Nov 26 07:51:39 volumiogolab volumio[1161]: info: Adding plugin metavolumio to MyMusic Plugins Nov 26 07:51:39 volumiogolab volumio[1161]: info: Adding plugin cd_controller to MyMusic Plugins Nov 26 07:51:39 volumiogolab volumio[1161]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 26 07:51:39 volumiogolab volumio[1161]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 26 07:51:39 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 26 07:51:40 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 26 07:51:40 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 26 07:51:40 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:40 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:40 volumiogolab volumio[1161]: info: Starting MyVolumio Remote Streaming Endpoints Nov 26 07:51:40 volumiogolab volumio[1161]: info: MyVolumio login type: Token Nov 26 07:51:40 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 26 07:51:40 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 26 07:51:41 volumiogolab volumio[1161]: info: Starting Streaming Service Transparent Proxy Nov 26 07:51:41 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 26 07:51:41 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 26 07:51:41 volumiogolab volumio[1161]: info: Streaming services startup Nov 26 07:51:41 volumiogolab volumio[1161]: info: Starting Streaming Daemon Nov 26 07:51:41 volumiogolab sudo[2073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 26 07:51:41 volumiogolab sudo[2073]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:41 volumiogolab sudo[2073]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:41 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 26 07:51:41 volumiogolab volumio[1161]: error: Cannot start Volumio Streaming Daemon Nov 26 07:51:41 volumiogolab volumio[1161]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 26 07:51:41 volumiogolab volumio[1161]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 26 07:51:41 volumiogolab volumio[1161]: STREAMING PROXY: Starting server on port 3245 Nov 26 07:51:41 volumiogolab volumio[1161]: Node JS runtime: 14 Nov 26 07:51:41 volumiogolab volumio[1161]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 26 07:51:42 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 26 07:51:42 volumiogolab volumio[1161]: info: Received Get System Info Nov 26 07:51:42 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 26 07:51:42 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 26 07:51:42 volumiogolab volumio[1161]: info: Discovery: Getting this device information Nov 26 07:51:42 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioGetState Nov 26 07:51:42 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:42 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 07:51:42 volumiogolab sudo[2083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=mantissa,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.31/Multimedia /mnt/NAS/QNAP-NAS Nov 26 07:51:42 volumiogolab sudo[2083]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:42 volumiogolab kernel: CIFS: Attempting to mount //192.168.1.31/Multimedia Nov 26 07:51:42 volumiogolab volumio[1161]: info: MyVolumio token set successfully Nov 26 07:51:42 volumiogolab volumio[1161]: info: MYVOLUMIO: Adding device Nov 26 07:51:42 volumiogolab volumio[1161]: info: MYVOLUMIO: Evaluating Server Nov 26 07:51:43 volumiogolab volumio[1161]: info: MyVolumio Plan changed: premium Nov 26 07:51:43 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Nov 26 07:51:43 volumiogolab volumio[1161]: info: Removing browser output: myVolumio user plan is not superstar Nov 26 07:51:43 volumiogolab volumio[1161]: info: Removing audio output: Nov 26 07:51:43 volumiogolab volumio[1161]: info: MYVOLUMIO: Adding device Nov 26 07:51:43 volumiogolab volumio[1161]: info: MYVOLUMIO: Evaluating Server Nov 26 07:51:43 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 26 07:51:43 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 26 07:51:43 volumiogolab volumio[1161]: info: Remote config written successfully Nov 26 07:51:43 volumiogolab volumio[1161]: info: Starting Tunnel 1 Nov 26 07:51:43 volumiogolab volumio[1161]: info: Starting Tunnel Connection Checker Nov 26 07:51:43 volumiogolab volumio[1161]: info: Completed starting MyVolumio Plugin Nov 26 07:51:44 volumiogolab volumio[1161]: info: MYVolumio Device enabled Nov 26 07:51:44 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Nov 26 07:51:44 volumiogolab volumio[1161]: info: MyVolumio status changed Nov 26 07:51:44 volumiogolab volumio[1161]: info: Streaming services startup Nov 26 07:51:44 volumiogolab volumio[1161]: info: Starting Streaming Daemon Nov 26 07:51:44 volumiogolab volumio[1161]: info: Setting Geolocation for MyVolumio to eu4 Nov 26 07:51:44 volumiogolab sudo[2120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 26 07:51:44 volumiogolab sudo[2120]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:44 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:44 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:44 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:44 volumiogolab sudo[2120]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:44 volumiogolab volumio[1161]: error: Cannot start Volumio Streaming Daemon Nov 26 07:51:44 volumiogolab volumio[1161]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 26 07:51:44 volumiogolab volumio[1161]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 26 07:51:44 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 26 07:51:44 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Nov 26 07:51:44 volumiogolab volumio[1161]: info: [1732607504310] Starting BluetoothController Nov 26 07:51:44 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Nov 26 07:51:45 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Nov 26 07:51:45 volumiogolab sudo[2124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Nov 26 07:51:45 volumiogolab sudo[2124]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:45 volumiogolab sudo[2124]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:45 volumiogolab volumio[1161]: info: MRS: MultiRoom plugin initialized Nov 26 07:51:45 volumiogolab volumio[1161]: info: MRS: STOPPING SNAPCLIENT Nov 26 07:51:45 volumiogolab volumio[1161]: info: MRS: Snap server stop Nov 26 07:51:45 volumiogolab sudo[2140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Nov 26 07:51:45 volumiogolab sudo[2140]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:45 volumiogolab sudo[2140]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:45 volumiogolab volumio[1161]: info: MRS: STOPPING volumioStreaming Nov 26 07:51:45 volumiogolab sudo[2143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Nov 26 07:51:45 volumiogolab sudo[2143]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:45 volumiogolab sudo[2143]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:45 volumiogolab sudo[2146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Nov 26 07:51:45 volumiogolab sudo[2146]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:45 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Nov 26 07:51:45 volumiogolab sudo[2146]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:45 volumiogolab sudo[2149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 26 07:51:45 volumiogolab sudo[2149]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:45 volumiogolab sudo[2149]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:45 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Nov 26 07:51:45 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Nov 26 07:51:45 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Nov 26 07:51:45 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Nov 26 07:51:45 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Nov 26 07:51:45 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Nov 26 07:51:45 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Nov 26 07:51:45 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Nov 26 07:51:45 volumiogolab volumio[1161]: info: Preparing to generate the ALSA configuration file Nov 26 07:51:45 volumiogolab volumio[1161]: info: Updating MyVolumio device info Nov 26 07:51:45 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:45 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:45 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:45 volumiogolab volumio[1161]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 26 07:51:45 volumiogolab volumio[1161]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 26 07:51:45 volumiogolab volumio[1161]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 26 07:51:45 volumiogolab volumio[1161]: info: Reading ALSA contributions from plugins. Nov 26 07:51:45 volumiogolab volumio[1161]: info: Setting Geolocation for MyVolumio to eu4 Nov 26 07:51:45 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:45 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:45 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:45 volumiogolab volumio[1161]: info: MRS: Removed streaming files Nov 26 07:51:45 volumiogolab volumio[1161]: info: MRS: volumioStreaming STOPPED Nov 26 07:51:45 volumiogolab volumio[1161]: info: MRS: SNAPSERVER STOPPED Nov 26 07:51:45 volumiogolab volumio[1161]: info: MRS: SNAPCLIENT STOPPED Nov 26 07:51:45 volumiogolab volumio[1161]: info: Asound.conf file written Nov 26 07:51:45 volumiogolab sudo[2152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 26 07:51:45 volumiogolab sudo[2152]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:45 volumiogolab sudo[2152]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:45 volumiogolab volumio[1161]: info: Output device has changed, restarting MPD Nov 26 07:51:45 volumiogolab sudo[2167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 07:51:45 volumiogolab sudo[2167]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:45 volumiogolab sudo[2167]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:45 volumiogolab volumio[1161]: info: Output device has changed, restarting Shairport Sync Nov 26 07:51:45 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:45 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:45 volumiogolab sudo[2179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 07:51:45 volumiogolab sudo[2179]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:45 volumiogolab systemd[1]: Stopping Music Player Daemon... Nov 26 07:51:46 volumiogolab systemd[1]: mpd.service: Succeeded. Nov 26 07:51:46 volumiogolab systemd[1]: Stopped Music Player Daemon. Nov 26 07:51:46 volumiogolab systemd[1]: Starting Music Player Daemon... Nov 26 07:51:46 volumiogolab volumio[1161]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 07:51:46 volumiogolab volumio[1161]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:46 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 07:51:46 volumiogolab sudo[2208]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 07:51:46 volumiogolab sudo[2208]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:46 volumiogolab sudo[2208]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:46 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Nov 26 07:51:46 volumiogolab volumio[1161]: info: MRS: Pushing multiroomSync output for this device Nov 26 07:51:46 volumiogolab volumio[1161]: info: MRS: Pushing multiroomSync output Nov 26 07:51:46 volumiogolab volumio[1161]: info: Adding audio output: Nov 26 07:51:46 volumiogolab volumio[1161]: info: Adding audio output: Nov 26 07:51:46 volumiogolab volumio[1161]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:46 volumiogolab sudo[2218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name VolumioGOLAB Nov 26 07:51:46 volumiogolab sudo[2218]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:46 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Nov 26 07:51:46 volumiogolab volumio[1161]: info: Adding METAVOLUMIO REST API Endpoints Nov 26 07:51:46 volumiogolab volumio[1161]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Nov 26 07:51:46 volumiogolab volumio[1161]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Nov 26 07:51:46 volumiogolab volumio[1161]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Nov 26 07:51:46 volumiogolab volumio[1161]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Nov 26 07:51:46 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Nov 26 07:51:46 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Nov 26 07:51:46 volumiogolab volumio[1161]: info: Preparing CD Folders Nov 26 07:51:46 volumiogolab volumio[1161]: info: Adding CD REST API Endpoints Nov 26 07:51:46 volumiogolab volumio[1161]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Nov 26 07:51:46 volumiogolab volumio[1161]: info: Starting UDEV Watcher for CD Nov 26 07:51:46 volumiogolab volumio[1161]: info: Detecting CD presence with UDEV Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Nov 26 07:51:46 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Nov 26 07:51:46 volumiogolab volumio[1161]: info: Adding inputs REST Endpoints Nov 26 07:51:46 volumiogolab volumio[1161]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Nov 26 07:51:46 volumiogolab volumio[1161]: info: Scanning Audio Inputs Nov 26 07:51:46 volumiogolab volumio[1161]: info: Checking against Known Cards name Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 07:51:46 volumiogolab volumio[1161]: info: [1732607506211] CoreMusicLibrary::Adding element HDA NVidia Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 07:51:46 volumiogolab volumio[1161]: Cannot find translation for source Mixcloud Nov 26 07:51:46 volumiogolab volumio[1161]: Cannot find translation for source SoundCloud Nov 26 07:51:46 volumiogolab volumio[1161]: Cannot find translation for source YouTube Music Nov 26 07:51:46 volumiogolab volumio[1161]: Cannot find translation for source Mother Earth Radio Nov 26 07:51:46 volumiogolab volumio[1161]: Cannot find translation for source Randomizer Nov 26 07:51:46 volumiogolab volumio[1161]: Cannot find translation for source Spotify Nov 26 07:51:46 volumiogolab volumio[1161]: Cannot find translation for source HDA NVidia Nov 26 07:51:46 volumiogolab volumio[1161]: info: Checking against Known Cards name Nov 26 07:51:46 volumiogolab volumio[1161]: info: Adding Server instance for streaming Nov 26 07:51:46 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Nov 26 07:51:46 volumiogolab sudo[2218]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:46 volumiogolab volumio[1161]: error: Hi Res Audio Failed Login: Missing Login Data Nov 26 07:51:46 volumiogolab volumio[1161]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 26 07:51:46 volumiogolab volumio[1161]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 26 07:51:46 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Nov 26 07:51:46 volumiogolab volumio[1161]: info: Refreshing TIDAL token Nov 26 07:51:46 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Nov 26 07:51:46 volumiogolab volumio[1161]: info: Refreshing QOBUZ token Nov 26 07:51:46 volumiogolab volumio[1161]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Nov 26 07:51:46 volumiogolab sudo[2226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 26 07:51:46 volumiogolab sudo[2226]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:46 volumiogolab volumio[1161]: info: Successfully Added MyVolumio device Nov 26 07:51:46 volumiogolab sudo[2226]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:46 volumiogolab volumio[1161]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Nov 26 07:51:46 volumiogolab volumio[1161]: info: Bluetooth name changed to VolumioGOLAB Nov 26 07:51:46 volumiogolab volumio[1161]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Nov 26 07:51:46 volumiogolab volumio[1161]: info: MPD Permissions set Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioGetState Nov 26 07:51:46 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:46 volumiogolab volumio[1161]: info: Starting Shairport Sync Nov 26 07:51:46 volumiogolab sudo[2236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 26 07:51:46 volumiogolab sudo[2236]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:46 volumiogolab systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 26 07:51:46 volumiogolab systemd[1]: shairport-sync.service: Succeeded. Nov 26 07:51:46 volumiogolab systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 26 07:51:46 volumiogolab systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 26 07:51:46 volumiogolab sudo[2236]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:46 volumiogolab volumio[1161]: info: Shairport-Sync Started Nov 26 07:51:46 volumiogolab volumio[1161]: info: Successfully Added MyVolumio device Nov 26 07:51:46 volumiogolab mpd[2217]: Nov 26 07:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 26 07:51:46 volumiogolab systemd[1]: Started Music Player Daemon. Nov 26 07:51:46 volumiogolab sudo[2179]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:46 volumiogolab volumio[1161]: error: updateQueue error: null Nov 26 07:51:46 volumiogolab volumio[1161]: info: Access Token successfully retrieved Nov 26 07:51:46 volumiogolab volumio[1161]: info: Access Token successfully retrieved Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 07:51:46 volumiogolab volumio[1161]: info: [1732607506717] CoreMusicLibrary::Adding element QOBUZ Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 07:51:46 volumiogolab volumio[1161]: Cannot find translation for source Mixcloud Nov 26 07:51:46 volumiogolab volumio[1161]: Cannot find translation for source SoundCloud Nov 26 07:51:46 volumiogolab volumio[1161]: Cannot find translation for source YouTube Music Nov 26 07:51:46 volumiogolab volumio[1161]: Cannot find translation for source Mother Earth Radio Nov 26 07:51:46 volumiogolab volumio[1161]: Cannot find translation for source Randomizer Nov 26 07:51:46 volumiogolab volumio[1161]: Cannot find translation for source Spotify Nov 26 07:51:46 volumiogolab volumio[1161]: Cannot find translation for source HDA NVidia Nov 26 07:51:46 volumiogolab volumio[1161]: Cannot find translation for source QOBUZ Nov 26 07:51:46 volumiogolab volumio[1161]: info: Stopping AccessToken refresher cron for QOBUZ Nov 26 07:51:46 volumiogolab volumio[1161]: info: AccessToken refresher cron started for QOBUZ Nov 26 07:51:46 volumiogolab volumio[1161]: info: Adding QOBUZ REST API Endpoints Nov 26 07:51:46 volumiogolab volumio[1161]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Nov 26 07:51:46 volumiogolab volumio[1161]: info: Updating MyVolumio device info Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:46 volumiogolab volumio[1161]: info: AutoStart - Plugin is starting Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioGetQueue Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreStateMachine::getQueue Nov 26 07:51:46 volumiogolab volumio[1161]: info: CorePlayQueue::getQueue Nov 26 07:51:46 volumiogolab volumio[1161]: info: AutoStart - start playing Nov 26 07:51:46 volumiogolab volumio[1161]: info: AutoStart - start playing with no specific position Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioPlay Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreStateMachine::play index 0 Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreStateMachine::stop Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreStateMachine::play index undefined Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 26 07:51:46 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:46 volumiogolab volumio[1161]: info: CoreStateMachine::startPlaybackTimer Nov 26 07:51:46 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:46 volumiogolab volumio[1161]: verbose: ControllerMpd::clearAddPlayTracks USB/F640-69F2/v.a. - Complete Verve __ Remixed (Deluxe Box) CD3/07. Astrud Gilberto - The Gentle Rain (RJD2 Remix) - The Complete Verve __ Remixed (Deluxe Box).mp3 Nov 26 07:51:46 volumiogolab volumio[1161]: verbose: ControllerMpd::sendMpdCommand stop Nov 26 07:51:46 volumiogolab volumio[1161]: info: sendMpdCommand stop took 1 milliseconds Nov 26 07:51:46 volumiogolab volumio[1161]: verbose: ControllerMpd::sendMpdCommand clear Nov 26 07:51:46 volumiogolab volumio[1161]: info: Nov 26 07:51:46 volumiogolab volumio[1161]: ---------------------------- MPD announces system playlist update Nov 26 07:51:46 volumiogolab volumio[1161]: info: Ignoring MPD Status Update Nov 26 07:51:46 volumiogolab volumio[1161]: info: sendMpdCommand clear took 2 milliseconds Nov 26 07:51:46 volumiogolab volumio[1161]: verbose: ControllerMpd::sendMpdCommand add "USB/F640-69F2/v.a. - Complete Verve __ Remixed (Deluxe Box) CD3/07. Astrud Gilberto - The Gentle Rain (RJD2 Remix) - The Complete Verve __ Remixed (Deluxe Box).mp3" Nov 26 07:51:46 volumiogolab volumio[1161]: error: updateQueue error: null Nov 26 07:51:46 volumiogolab volumio[1161]: info: ------------------------------ 4ms Nov 26 07:51:46 volumiogolab volumio[1161]: info: Nov 26 07:51:46 volumiogolab volumio[1161]: ---------------------------- MPD announces system playlist update Nov 26 07:51:46 volumiogolab volumio[1161]: info: Ignoring MPD Status Update Nov 26 07:51:46 volumiogolab volumio[1161]: info: sendMpdCommand add "USB/F640-69F2/v.a. - Complete Verve __ Remixed (Deluxe Box) CD3/07. Astrud Gilberto - The Gentle Rain (RJD2 Remix) - The Complete Verve __ Remixed (Deluxe Box).mp3" took 2 milliseconds Nov 26 07:51:46 volumiogolab volumio[1161]: verbose: ControllerMpd::sendMpdCommand play Nov 26 07:51:46 volumiogolab volumio[1161]: info: ------------------------------ 2ms Nov 26 07:51:46 volumiogolab volumio[1161]: info: sendMpdCommand play took 3 milliseconds Nov 26 07:51:46 volumiogolab volumio[1161]: info: Nov 26 07:51:46 volumiogolab volumio[1161]: ---------------------------- MPD announces state update: player Nov 26 07:51:46 volumiogolab volumio[1161]: info: ControllerMpd::getState Nov 26 07:51:46 volumiogolab volumio[1161]: verbose: ControllerMpd::sendMpdCommand status Nov 26 07:51:46 volumiogolab volumio[1161]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 26 07:51:46 volumiogolab volumio[1161]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 26 07:51:46 volumiogolab volumio[1161]: info: camilladsp stopping service pid 1693... Nov 26 07:51:47 volumiogolab volumio[1161]: info: camilladsp service terminated, instance 1 Nov 26 07:51:47 volumiogolab volumio[1161]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 07:51:47 volumiogolab volumio[1161]: info: camilladsp service started and running in background, instance 1 Nov 26 07:51:47 volumiogolab volumio[1161]: info: Nov 26 07:51:47 volumiogolab volumio[1161]: ---------------------------- MPD announces state update: player Nov 26 07:51:47 volumiogolab volumio[1161]: info: sendMpdCommand status took 92 milliseconds Nov 26 07:51:47 volumiogolab volumio[1161]: info: ControllerMpd::getState Nov 26 07:51:47 volumiogolab volumio[1161]: verbose: ControllerMpd::sendMpdCommand status Nov 26 07:51:47 volumiogolab volumio[1161]: verbose: ControllerMpd::parseState Nov 26 07:51:47 volumiogolab volumio[1161]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 07:51:47 volumiogolab volumio[1161]: info: Successfully retrieved User Session From TIDAL Nov 26 07:51:47 volumiogolab volumio[1161]: info: Successfully Updated MyVolumio device Nov 26 07:51:47 volumiogolab volumio[1161]: info: sendMpdCommand status took 13 milliseconds Nov 26 07:51:47 volumiogolab volumio[1161]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 26 07:51:47 volumiogolab volumio[1161]: verbose: ControllerMpd::parseState Nov 26 07:51:47 volumiogolab volumio[1161]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 07:51:47 volumiogolab volumio[1161]: verbose: ControllerMpd::parseTrackInfo Nov 26 07:51:47 volumiogolab volumio[1161]: info: ControllerMpd::pushState Nov 26 07:51:47 volumiogolab volumio[1161]: info: CoreCommandRouter::servicePushState Nov 26 07:51:47 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:47 volumiogolab volumio[1161]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":361,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Gentle Rain (RJD2 Remix)","artist":"Astrud Gilberto","album":"The Complete Verve // Remixed (Deluxe Box)","uri":"USB/F640-69F2/v.a. - Complete Verve __ Remixed (Deluxe Box) CD3/07. Astrud Gilberto - The Gentle Rain (RJD2 Remix) - The Complete Verve __ Remixed (Deluxe Box).mp3","trackType":"mp3"} Nov 26 07:51:47 volumiogolab volumio[1161]: verbose: CURRENT POSITION 0 Nov 26 07:51:47 volumiogolab volumio[1161]: info: CoreStateMachine::syncState stateService play Nov 26 07:51:47 volumiogolab volumio[1161]: info: CoreStateMachine::syncState currentStatus stop Nov 26 07:51:47 volumiogolab volumio[1161]: info: ------------------------------ 111ms Nov 26 07:51:47 volumiogolab volumio[1161]: info: camilladsp spawned new process with pid 2249, instance 1, run: true Nov 26 07:51:47 volumiogolab volumio[1161]: info: sendMpdCommand playlistinfo took 32 milliseconds Nov 26 07:51:47 volumiogolab volumio[1161]: verbose: ControllerMpd::parseTrackInfo Nov 26 07:51:47 volumiogolab volumio[1161]: info: ControllerMpd::pushState Nov 26 07:51:47 volumiogolab volumio[1161]: info: CoreCommandRouter::servicePushState Nov 26 07:51:47 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:47 volumiogolab volumio[1161]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":361,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Gentle Rain (RJD2 Remix)","artist":"Astrud Gilberto","album":"The Complete Verve // Remixed (Deluxe Box)","uri":"USB/F640-69F2/v.a. - Complete Verve __ Remixed (Deluxe Box) CD3/07. Astrud Gilberto - The Gentle Rain (RJD2 Remix) - The Complete Verve __ Remixed (Deluxe Box).mp3","trackType":"mp3"} Nov 26 07:51:47 volumiogolab volumio[1161]: verbose: CURRENT POSITION 0 Nov 26 07:51:47 volumiogolab volumio[1161]: info: CoreStateMachine::syncState stateService play Nov 26 07:51:47 volumiogolab volumio[1161]: info: CoreStateMachine::syncState currentStatus play Nov 26 07:51:47 volumiogolab volumio[1161]: info: Received an update from plugin. extracting info from payload Nov 26 07:51:47 volumiogolab volumio[1161]: info: CoreStateMachine::pushState Nov 26 07:51:47 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:47 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 07:51:47 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioPushState Nov 26 07:51:47 volumiogolab volumio[1161]: info: MRS: Pushing multiroomSync output update for this device Nov 26 07:51:47 volumiogolab volumio[1161]: info: MRS: Pushing multiroomSync output Nov 26 07:51:47 volumiogolab volumio[1161]: info: CoreStateMachine::pushState Nov 26 07:51:47 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:47 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioPushState Nov 26 07:51:47 volumiogolab volumio[1161]: info: MRS: Pushing multiroomSync output update for this device Nov 26 07:51:47 volumiogolab volumio[1161]: info: MRS: Pushing multiroomSync output Nov 26 07:51:47 volumiogolab volumio[1161]: info: ------------------------------ 86ms Nov 26 07:51:47 volumiogolab volumio[1161]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 26 07:51:47 volumiogolab volumio[1161]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 26 07:51:47 volumiogolab volumio[1161]: info: Successfully Updated MyVolumio device Nov 26 07:51:47 volumiogolab volumio[1161]: info: Successfully retrieved User Subscription From TIDAL Nov 26 07:51:47 volumiogolab volumio[1161]: info: Adding TIDAL to Browse Sources Nov 26 07:51:47 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 07:51:47 volumiogolab volumio[1161]: info: [1732607507315] CoreMusicLibrary::Adding element TIDAL Nov 26 07:51:47 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 07:51:47 volumiogolab volumio[1161]: Cannot find translation for source Mixcloud Nov 26 07:51:47 volumiogolab volumio[1161]: Cannot find translation for source SoundCloud Nov 26 07:51:47 volumiogolab volumio[1161]: Cannot find translation for source YouTube Music Nov 26 07:51:47 volumiogolab volumio[1161]: Cannot find translation for source Mother Earth Radio Nov 26 07:51:47 volumiogolab volumio[1161]: Cannot find translation for source Randomizer Nov 26 07:51:47 volumiogolab volumio[1161]: Cannot find translation for source Spotify Nov 26 07:51:47 volumiogolab volumio[1161]: Cannot find translation for source HDA NVidia Nov 26 07:51:47 volumiogolab volumio[1161]: Cannot find translation for source QOBUZ Nov 26 07:51:47 volumiogolab volumio[1161]: Cannot find translation for source TIDAL Nov 26 07:51:47 volumiogolab volumio[1161]: info: Stopping AccessToken refresher cron Nov 26 07:51:47 volumiogolab volumio[1161]: info: AccessToken refresher cron started Nov 26 07:51:47 volumiogolab volumio[1161]: info: Adding TIDAL REST API Endpoints Nov 26 07:51:47 volumiogolab volumio[1161]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 26 07:51:48 volumiogolab sudo[2083]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:48 volumiogolab volumio[1161]: info: Cannot mount NAS QNAP-NAS at system boot, trial number 3 ,retrying in 5 seconds Nov 26 07:51:48 volumiogolab kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 26 07:51:48 volumiogolab kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Nov 26 07:51:48 volumiogolab sudo[2261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 26 07:51:48 volumiogolab sudo[2261]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:48 volumiogolab systemd[1]: Started MyVolumio SSH Tunnel. Nov 26 07:51:48 volumiogolab sudo[2261]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:48 volumiogolab volumio[1161]: info: Remote SSH Started Nov 26 07:51:48 volumiogolab autossh[2264]: port set to 0, monitoring disabled Nov 26 07:51:48 volumiogolab autossh[2264]: starting ssh (count 1) Nov 26 07:51:48 volumiogolab autossh[2264]: ssh child pid is 2268 Nov 26 07:51:48 volumiogolab volumio[1161]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Nov 26 07:51:48 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioGetState Nov 26 07:51:48 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:49 volumiogolab sudo[2270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Nov 26 07:51:49 volumiogolab sudo[2270]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:49 volumiogolab systemd[1]: Started Volumio Bluetooth Module. Nov 26 07:51:49 volumiogolab sudo[2270]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:49 volumiogolab sudo[2273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Nov 26 07:51:49 volumiogolab volumio[1161]: info: Volumio BT Module successfully started Nov 26 07:51:49 volumiogolab sudo[2273]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:49 volumiogolab volumiobt[2272]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Nov 26 07:51:49 volumiogolab sudo[2273]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:49 volumiogolab volumio[1161]: info: TidalConnect service stoped! Nov 26 07:51:49 volumiogolab volumio[1161]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 26 07:51:49 volumiogolab volumio[1161]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 26 07:51:49 volumiogolab sudo[2285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 26 07:51:49 volumiogolab sudo[2285]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:49 volumiogolab systemd[1]: Started Volumio Tidal Connect Service. Nov 26 07:51:49 volumiogolab sudo[2285]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:49 volumiogolab volumio[1161]: info: Executing endpoint tc_getconfig Nov 26 07:51:49 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 26 07:51:49 volumiogolab vtcs[2287]: STARTING TidalConnect services, version: 1.4.0.34 Nov 26 07:51:49 volumiogolab vtcs[2287]: STARTED TidalConnect services. Nov 26 07:51:49 volumiogolab volumio[1161]: info: Executing endpoint tc_connect Nov 26 07:51:49 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 26 07:51:49 volumiogolab volumio[1161]: info: Connecting to TidalConnect Nov 26 07:51:49 volumiogolab volumio[1161]: info: CoreCommandRouter::servicePushState Nov 26 07:51:49 volumiogolab volumio[1161]: info: CoreStateMachine::pushState Nov 26 07:51:49 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:49 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 07:51:49 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioPushState Nov 26 07:51:49 volumiogolab volumio[1161]: info: MRS: Pushing multiroomSync output update for this device Nov 26 07:51:49 volumiogolab volumio[1161]: info: MRS: Pushing multiroomSync output Nov 26 07:51:49 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:49 volumiogolab volumio[1161]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Nov 26 07:51:49 volumiogolab volumio[1161]: info: CoreCommandRouter::servicePushState Nov 26 07:51:49 volumiogolab volumio[1161]: info: CoreStateMachine::pushState Nov 26 07:51:49 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:49 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioPushState Nov 26 07:51:49 volumiogolab volumio[1161]: info: MRS: Pushing multiroomSync output update for this device Nov 26 07:51:49 volumiogolab volumio[1161]: info: MRS: Pushing multiroomSync output Nov 26 07:51:49 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:49 volumiogolab volumio[1161]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Nov 26 07:51:49 volumiogolab volumio[1161]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 26 07:51:50 volumiogolab sudo[2320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Nov 26 07:51:50 volumiogolab sudo[2320]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:50 volumiogolab volumiobt[2272]: pulseaudio: no process found Nov 26 07:51:50 volumiogolab sudo[2320]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:50 volumiogolab dbus-daemon[767]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.23' (uid=1000 pid=2324 comm="/usr/bin/pulseaudio --high-priority=true ") Nov 26 07:51:50 volumiogolab systemd[1]: Starting RealtimeKit Scheduling Policy Service... Nov 26 07:51:50 volumiogolab volumio[1161]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 26 07:51:50 volumiogolab dbus-daemon[767]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Nov 26 07:51:50 volumiogolab rtkit-daemon[2325]: Successfully called chroot. Nov 26 07:51:50 volumiogolab rtkit-daemon[2325]: Successfully dropped privileges. Nov 26 07:51:50 volumiogolab rtkit-daemon[2325]: Successfully limited resources. Nov 26 07:51:50 volumiogolab rtkit-daemon[2325]: Running. Nov 26 07:51:50 volumiogolab rtkit-daemon[2325]: Watchdog thread running. Nov 26 07:51:50 volumiogolab systemd[1]: Started RealtimeKit Scheduling Policy Service. Nov 26 07:51:50 volumiogolab rtkit-daemon[2325]: Canary thread running. Nov 26 07:51:50 volumiogolab dbus-daemon[767]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.24' (uid=0 pid=2325 comm="/usr/lib/rtkit/rtkit-daemon ") Nov 26 07:51:50 volumiogolab systemd[1]: Starting Authorization Manager... Nov 26 07:51:50 volumiogolab volumio[1161]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 26 07:51:50 volumiogolab polkitd[2328]: started daemon version 0.105 using authority implementation `local' version `0.105' Nov 26 07:51:50 volumiogolab dbus-daemon[767]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 26 07:51:50 volumiogolab systemd[1]: Started Authorization Manager. Nov 26 07:51:50 volumiogolab pulseaudio[2324]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 26 07:51:50 volumiogolab pulseaudio[2324]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 26 07:51:50 volumiogolab volumiobt[2272]: Applying permissions Nov 26 07:51:50 volumiogolab volumiobt[2272]: Setting BT discoverable and pairable Nov 26 07:51:50 volumiogolab bluetoothd[768]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSource Nov 26 07:51:50 volumiogolab bluetoothd[768]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSink Nov 26 07:51:50 volumiogolab pulseaudio[2324]: 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 Nov 26 07:51:50 volumiogolab kernel: Bluetooth: RFCOMM TTY layer initialized Nov 26 07:51:50 volumiogolab kernel: Bluetooth: RFCOMM socket layer initialized Nov 26 07:51:50 volumiogolab kernel: Bluetooth: RFCOMM ver 1.11 Nov 26 07:51:50 volumiogolab volumiobt[2272]: Agent registered Nov 26 07:51:50 volumiogolab volumiobt[2272]: [bluetooth]# -e power on Nov 26 07:51:50 volumiogolab volumiobt[2272]: Invalid command in menu main: -e Nov 26 07:51:50 volumiogolab volumiobt[2272]: Nov 26 07:51:50 volumiogolab volumiobt[2272]: Use "help" for a list of available commands in a menu. Nov 26 07:51:50 volumiogolab volumiobt[2272]: Use "menu " if you want to enter any submenu. Nov 26 07:51:50 volumiogolab volumiobt[2272]: Use "back" if you want to return to menu main. Nov 26 07:51:50 volumiogolab volumiobt[2272]: [bluetooth]# agent on Nov 26 07:51:50 volumiogolab volumiobt[2272]: Agent is already registered Nov 26 07:51:50 volumiogolab volumiobt[2272]: [bluetooth]# discoverable on Nov 26 07:51:50 volumiogolab volumiobt[2272]: [bluetooth]# pairable on Nov 26 07:51:50 volumiogolab volumiobt[2272]: [bluetooth]# agent NoInputNoOutput Nov 26 07:51:50 volumiogolab volumiobt[2272]: Agent is already registered Nov 26 07:51:50 volumiogolab volumiobt[2272]: [bluetooth]# default-agent Nov 26 07:51:50 volumiogolab volumiobt[2272]: [bluetooth]# quit Nov 26 07:51:50 volumiogolab volumiobt[2272]: [59B blob data] Nov 26 07:51:51 volumiogolab volumio[1161]: info: MRS: Getting audio outputs on start Nov 26 07:51:51 volumiogolab volumio[1161]: info: MRS: Requesting all other devices output Nov 26 07:51:52 volumiogolab volumio[1161]: info: TidalConnect service started! Nov 26 07:51:52 volumiogolab volumio[1161]: [Metrics] CommandRouter: 48s 190.66ms Nov 26 07:51:52 volumiogolab volumio[1161]: info: CoreCommandRouter::volumiosetStartupVolume Nov 26 07:51:52 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:51:52 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:52 volumiogolab volumio[1161]: info: CoreCommandRouter::Close All Modals sent Nov 26 07:51:52 volumiogolab volumio[1161]: info: CoreCommandRouter::Close All Modals sent Nov 26 07:51:52 volumiogolab volumio[1161]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 26 07:51:52 volumiogolab volumio[1161]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 26 07:51:52 volumiogolab volumio[1161]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 07:51:52 volumiogolab volumio[1161]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 26 07:51:52 volumiogolab volumio[1161]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 26 07:51:52 volumiogolab volumio[1161]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 07:51:52 volumiogolab volumio[1161]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 26 07:51:52 volumiogolab volumio[1161]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 26 07:51:53 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 26 07:51:53 volumiogolab volumio[1161]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 26 07:51:53 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 26 07:51:53 volumiogolab volumio[1161]: info: Received Get System Version Nov 26 07:51:53 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 07:51:53 volumiogolab volumio[1161]: info: Received Get System Info Nov 26 07:51:53 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 26 07:51:53 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 26 07:51:53 volumiogolab volumio[1161]: info: Discovery: Getting this device information Nov 26 07:51:53 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioGetState Nov 26 07:51:53 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:53 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 07:51:53 volumiogolab sudo[2348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=mantissa,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.31/Multimedia /mnt/NAS/QNAP-NAS Nov 26 07:51:53 volumiogolab sudo[2348]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 07:51:53 volumiogolab kernel: CIFS: Attempting to mount //192.168.1.31/Multimedia Nov 26 07:51:53 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 26 07:51:53 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 26 07:51:53 volumiogolab volumio[1161]: No protocol specified Nov 26 07:51:53 volumiogolab volumio[1161]: xcb_connection_has_error() returned true Nov 26 07:51:53 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 26 07:51:55 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 26 07:51:55 volumiogolab volumio[1161]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 26 07:51:55 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioGetState Nov 26 07:51:55 volumiogolab volumio[1161]: info: CorePlayQueue::getTrack 0 Nov 26 07:51:56 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 07:51:56 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 07:51:56 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 26 07:51:57 volumiogolab volumio-remote-updater[755]: No test mode Nov 26 07:51:57 volumiogolab volumio-remote-updater[755]: No alpha test mode Nov 26 07:51:57 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 26 07:51:57 volumiogolab volumio[1161]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/youtube2/volumio/buster/amd64 Nov 26 07:51:57 volumiogolab volumio[1161]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 26 07:51:57 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 26 07:51:59 volumiogolab volumio[1161]: info: BOOT COMPLETED Nov 26 07:51:59 volumiogolab sudo[2348]: pam_unix(sudo:session): session closed for user root Nov 26 07:51:59 volumiogolab kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 26 07:51:59 volumiogolab kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Nov 26 07:51:59 volumiogolab volumio[1161]: info: Cannot mount NAS QNAP-NAS at system boot, trial number 4 ,retrying in 5 seconds Nov 26 07:51:59 volumiogolab volumio[1161]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/youtube2/volumio/buster/amd64 Nov 26 07:51:59 volumiogolab volumio[1161]: info: Folder /tmp/plugins removed Nov 26 07:52:00 volumiogolab volumio[1161]: info: Check plugin dependencies Nov 26 07:52:00 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 07:52:00 volumiogolab volumio[1161]: info: Checking if plugin already exists Nov 26 07:52:00 volumiogolab volumio[1161]: info: Rename folder Nov 26 07:52:00 volumiogolab volumio[1161]: info: Folder /tmp/downloaded_plugin.zip removed Nov 26 07:52:00 volumiogolab volumio[1161]: info: Move to category Nov 26 07:52:00 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:52:00 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 07:52:00 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 26 07:52:00 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 26 07:52:00 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 26 07:52:00 volumiogolab volumio[1161]: info: CoreCommandRouter::volumioGetBrowseSources Nov 26 07:52:00 volumiogolab volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 07:52:00 volumiogolab volumio[1161]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 07:52:00 volumiogolab volumio[1161]: TypeError: Cannot read property 'plugin_type' of undefined Nov 26 07:52:00 volumiogolab volumio[1161]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1644:56) Nov 26 07:52:00 volumiogolab volumio[1161]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1535:29) Nov 26 07:52:00 volumiogolab volumio[1161]: at myVolumio.identifyUserProperties (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:42317) Nov 26 07:52:00 volumiogolab volumio[1161]: at Timeout._onTimeout (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:2508) Nov 26 07:52:00 volumiogolab volumio[1161]: at listOnTimeout (internal/timers.js:557:17) Nov 26 07:52:00 volumiogolab volumio[1161]: at processTimers (internal/timers.js:500:7) Nov 26 07:52:00 volumiogolab volumio[1161]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 07:52:00 volumiogolab sudo[2388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 07:51 Nov 26 07:52:00 volumiogolab sudo[2388]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"