-- Logs begin at Thu 2025-05-29 16:39:52 CEST, end at Thu 2025-05-29 16:41:48 CEST. -- May 29 16:40:00 volumio13 volumio-time-update[555]: volumio-time-update-util: Fetching time from Volumio... May 29 16:40:00 volumio13 volumio-time-update[555]: volumio-time-update-util: Date not found in response May 29 16:40:00 volumio13 volumio-time-update[555]: volumio-time-update-util: Retrying in 5 seconds... May 29 16:40:00 volumio13 systemd[1]: systemd-rfkill.service: Succeeded. May 29 16:40:00 volumio13 ntpd[800]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 29 16:40:00 volumio13 volumio[889]: info: Loading plugin "network"... May 29 16:40:00 volumio13 volumio[889]: info: Refreshing Cached IP Addresses May 29 16:40:00 volumio13 volumio[889]: info: Loading plugin "services"... May 29 16:40:00 volumio13 sudo[929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 16:40:00 volumio13 volumio[889]: info: Loading plugin "alsa_controller"... May 29 16:40:00 volumio13 sudo[931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 16:40:00 volumio13 sudo[929]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:00 volumio13 sudo[931]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:00 volumio13 sudo[929]: pam_unix(sudo:session): session closed for user root May 29 16:40:00 volumio13 sudo[931]: pam_unix(sudo:session): session closed for user root May 29 16:40:00 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 16:40:00 volumio13 volumio[889]: info: Loading plugin "wizard"... May 29 16:40:00 volumio13 volumio[889]: info: Loading plugin "networkfs"... May 29 16:40:00 volumio13 volumio-remote-updater[559]: [2025-05-29 16:40:00] [connect] Successful connection May 29 16:40:01 volumio13 volumio[889]: info: Starting Udev Watcher for removable devices May 29 16:40:01 volumio13 sudo[970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=bunddog,password=GU_-sw05,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.0 //192.168.0.130/Volumio2 /mnt/NAS/torrent-u May 29 16:40:01 volumio13 sudo[970]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:01 volumio13 volumio[889]: info: Ignoring mount for partition: BOOT May 29 16:40:01 volumio13 volumio[889]: info: Ignoring mount for partition: volumio May 29 16:40:01 volumio13 volumio[889]: info: Ignoring mount for partition: volumio_data May 29 16:40:01 volumio13 sudo[970]: pam_unix(sudo:session): session closed for user root May 29 16:40:01 volumio13 kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 29 16:40:01 volumio13 kernel: CIFS VFS: cifs_mount failed w/return code = -101 May 29 16:40:01 volumio13 volumio[889]: info: Mounting Device PENDRIVE May 29 16:40:01 volumio13 sudo[989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/PENDRIVE -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 29 16:40:01 volumio13 sudo[989]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:01 volumio13 kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! May 29 16:40:01 volumio13 sudo[989]: pam_unix(sudo:session): session closed for user root May 29 16:40:01 volumio13 kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. May 29 16:40:01 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 29 16:40:01 volumio13 volumio[889]: info: Loading plugin "volumio_command_line_client"... May 29 16:40:01 volumio13 volumio[889]: info: Loading plugin "upnp"... May 29 16:40:01 volumio13 volumio[889]: info: [1748529601354] Starting Upmpd Daemon May 29 16:40:01 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 29 16:40:01 volumio13 volumio[889]: info: Loading plugin "my_music"... May 29 16:40:01 volumio13 volumio[889]: info: Loading plugin "mpd"... May 29 16:40:01 volumio13 volumio[889]: info: Loading plugin "upnp_browser"... May 29 16:40:01 volumio13 volumio[889]: info: Loading plugin "alarm-clock"... May 29 16:40:02 volumio13 volumio[889]: info: Loading plugin "airplay_emulation"... May 29 16:40:02 volumio13 volumio[889]: info: Starting Shairport Sync May 29 16:40:02 volumio13 volumio[889]: info: Loading plugin "last_100"... May 29 16:40:02 volumio13 volumio[889]: info: Loading plugin "webradio"... May 29 16:40:02 volumio13 volumio[889]: info: Loading plugin "i2s_dacs"... May 29 16:40:02 volumio13 volumio[889]: info: I2S DAC not set, start Auto-detection May 29 16:40:02 volumio13 volumio[889]: info: Loading plugin "volumiodiscovery"... May 29 16:40:02 volumio13 volumio[889]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 29 16:40:02 volumio13 volumio[889]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 16:40:02 volumio13 volumio[889]: *** WARNING *** For more information see May 29 16:40:02 volumio13 volumio[889]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 29 16:40:02 volumio13 volumio[889]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 16:40:02 volumio13 node[889]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 29 16:40:02 volumio13 volumio[889]: *** WARNING *** For more information see May 29 16:40:02 volumio13 node[889]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 16:40:02 volumio13 node[889]: *** WARNING *** For more information see May 29 16:40:02 volumio13 node[889]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 29 16:40:02 volumio13 node[889]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 16:40:02 volumio13 node[889]: *** WARNING *** For more information see May 29 16:40:02 volumio13 volumio[889]: info: Applying required configuration parameters for plugin volumiodiscovery May 29 16:40:02 volumio13 volumio[889]: info: Discovery: Started advertising with name: Volumio13 May 29 16:40:02 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 29 16:40:02 volumio13 volumio[889]: info: Loading plugin "youtube2"... May 29 16:40:02 volumio13 dhcpcd[560]: eth0: using static address 192.168.0.200/24 May 29 16:40:02 volumio13 dhcpcd[560]: eth0: adding route to 192.168.0.0/24 May 29 16:40:02 volumio13 dhcpcd[560]: eth0: adding default route via 192.168.0.1 May 29 16:40:02 volumio13 dhcpcd[560]: eth0: using static address 192.168.0.200/24 May 29 16:40:02 volumio13 dhcpcd[560]: eth0: adding route to 192.168.0.0/24 May 29 16:40:02 volumio13 avahi-daemon[551]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.200. May 29 16:40:02 volumio13 avahi-daemon[551]: New relevant interface eth0.IPv4 for mDNS. May 29 16:40:02 volumio13 dhcpcd[560]: eth0: adding default route via 192.168.0.1 May 29 16:40:02 volumio13 avahi-daemon[551]: Registering new address record for 192.168.0.200 on eth0.IPv4. May 29 16:40:02 volumio13 dhcpcd[560]: forked to background, child pid 1018 May 29 16:40:02 volumio13 dhcpcd[560]: forked to background, child pid 1018 May 29 16:40:02 volumio13 systemd[1]: Started DHCP Client Daemon. May 29 16:40:04 volumio13 volumio[889]: info: Loading plugin "now_playing"... May 29 16:40:04 volumio13 kernel: nf_conntrack: automatic helper assignment is deprecated and it will be removed soon. Use the iptables CT target to attach helpers instead. May 29 16:40:04 volumio13 ntpd[800]: Listen normally on 3 eth0 192.168.0.200:123 May 29 16:40:04 volumio13 ntpd[800]: new interface(s) found: waking up resolver May 29 16:40:05 volumio13 volumio-time-update[555]: volumio-time-update-util: Fetching time from Volumio... May 29 16:40:05 volumio13 volumio[889]: info: Loading plugin "outputs"... May 29 16:40:05 volumio13 volumio[889]: info: Loading plugin "albumart"... May 29 16:40:05 volumio13 volumio[889]: info: Plugin example_plugin is not enabled May 29 16:40:05 volumio13 volumio[889]: info: Loading plugin "inputs"... May 29 16:40:05 volumio13 volumio[889]: info: Loading plugin "updater_comm"... May 29 16:40:05 volumio13 volumio-time-update[555]: volumio-time-update-util: Setting system time to: 2025-05-29 16:40:07 May 29 16:40:05 volumio13 sudo[1031]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-05-29 16:40:07 May 29 16:40:05 volumio13 sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:05 volumio13 dbus-daemon[547]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=1036 comm="timedatectl set-time 2025-05-29 16:40:07 ") May 29 16:40:05 volumio13 systemd[1]: Starting Time & Date Service... May 29 16:40:05 volumio13 volumio[889]: info: Plugin mpdemulation is not enabled May 29 16:40:05 volumio13 volumio[889]: info: Loading plugin "rest_api"... May 29 16:40:05 volumio13 volumio[889]: info: Loading plugin "websocket"... May 29 16:40:05 volumio13 volumio[889]: info: Starting Socket.io Server version 2.3.0 May 29 16:40:05 volumio13 volumio[889]: info: Plugin minidlna is not enabled May 29 16:40:05 volumio13 volumio[889]: info: Loading plugin "Systeminfo"... May 29 16:40:05 volumio13 volumio[889]: Forking 3 albumart workers May 29 16:40:05 volumio13 volumio[889]: info: Loading i18n strings for locale en May 29 16:40:05 volumio13 volumio[889]: Updating browse sources language May 29 16:40:05 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::initPlayerControls May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 16:40:06 volumio13 volumio[889]: Express server listening on port 3000 May 29 16:40:06 volumio13 volumio[889]: [Metrics] WebUI: 7s 873.07ms May 29 16:40:06 volumio13 volumio[889]: info: CoreStateMachine::resetVolumioState May 29 16:40:06 volumio13 volumio[889]: info: CoreStateMachine::getcurrentVolume May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::volumioRetrievevolume May 29 16:40:06 volumio13 volumio[889]: info: CoreStateMachine::pushState May 29 16:40:06 volumio13 volumio[889]: info: CorePlayQueue::getTrack 0 May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::volumioPushState May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:06 volumio13 volumio[889]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 29 16:40:06 volumio13 volumio[889]: info: Completed loading Core Plugins May 29 16:40:06 volumio13 volumio[889]: info: Preparing to generate the ALSA configuration file May 29 16:40:06 volumio13 volumio[889]: info: Volumio Network Manager: Network status updated: 0 May 29 16:40:06 volumio13 volumio[889]: info: Cannot mount NAS torrent-u at system boot, trial number 1 ,retrying in 5 seconds May 29 16:40:06 volumio13 volumio[889]: Starting albumart workers May 29 16:40:06 volumio13 volumio[889]: Starting albumart workers May 29 16:40:06 volumio13 volumio[889]: Starting albumart workers May 29 16:40:06 volumio13 volumio[889]: verbose: New Socket.io Connection to 192.168.0.200 from 192.168.0.130 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 May 29 16:40:06 volumio13 volumio[889]: verbose: New Socket.io Connection to 192.168.0.200 from 192.168.0.130 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 May 29 16:40:06 volumio13 volumio[889]: info: Reloading queue from file May 29 16:40:06 volumio13 volumio[889]: info: Asound.conf file unchanged, so no further update is needed May 29 16:40:06 volumio13 volumio[889]: info: Output device has changed, restarting MPD May 29 16:40:06 volumio13 volumio[889]: info: Output device has changed, restarting Shairport Sync May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:06 volumio13 sudo[1071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 16:40:06 volumio13 sudo[1069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 16:40:06 volumio13 sudo[1071]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:06 volumio13 sudo[1069]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:06 volumio13 volumio[889]: info: ___________ START PLUGINS ___________ May 29 16:40:06 volumio13 volumio[889]: info: ControllerMpd::onStart: Initializing MPD May 29 16:40:06 volumio13 volumio[889]: info: Creating MPD Configuration file May 29 16:40:06 volumio13 sudo[1069]: pam_unix(sudo:session): session closed for user root May 29 16:40:06 volumio13 systemd[1]: Stopping Music Player Daemon... May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 16:40:06 volumio13 volumio[889]: info: [1748529606654] CoreMusicLibrary::Adding element Media Servers May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:06 volumio13 sudo[1086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 16:40:06 volumio13 sudo[1088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 16:40:06 volumio13 sudo[1088]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:06 volumio13 sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:06 volumio13 sudo[1086]: pam_unix(sudo:session): session closed for user root May 29 16:40:06 volumio13 systemd[1]: mpd.service: Succeeded. May 29 16:40:06 volumio13 systemd[1]: Stopped Music Player Daemon. May 29 16:40:06 volumio13 systemd[1]: Starting Music Player Daemon... May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 16:40:06 volumio13 volumio[889]: info: [1748529606914] CoreMusicLibrary::Adding element Last_100 May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 16:40:06 volumio13 volumio[889]: info: [1748529606917] CoreMusicLibrary::Adding element Webradio May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 16:40:06 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 16:40:06 volumio13 volumio[889]: info: Initializing BBC Radios May 29 16:40:06 volumio13 nmbd[793]: [2025/05/29 16:40:06.953822, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 29 16:40:06 volumio13 systemd[1]: Started Samba NMB Daemon. May 29 16:40:06 volumio13 nmbd[793]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 29 16:40:06 volumio13 systemd[1]: Starting Samba Winbind Daemon... May 29 16:40:06 volumio13 sudo[1110]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 16:40:07 volumio13 sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:07 volumio13 sudo[1110]: pam_unix(sudo:session): session closed for user root May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 16:40:07 volumio13 volumio[889]: info: [1748529607057] CoreMusicLibrary::Adding element YouTube2 May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 16:40:07 volumio13 volumio[889]: Cannot find translation for source YouTube2 May 29 16:40:07 volumio13 volumio[889]: info: [now-playing] ConfigUpdater: config is up to date. May 29 16:40:07 volumio13 volumio[889]: info: Volumio Calling Home May 29 16:40:07 volumio13 dbus-daemon[547]: [system] Successfully activated service 'org.freedesktop.timedate1' May 29 16:40:07 volumio13 systemd[1]: Started Time & Date Service. May 29 16:40:07 volumio13 systemd-timedated[1037]: Changed local time to Thu May 29 16:40:07 2025 May 29 16:40:07 volumio13 sudo[1031]: pam_unix(sudo:session): session closed for user root May 29 16:40:07 volumio13 volumio-time-update[555]: volumio-time-update-util: System time updated successfully. May 29 16:40:07 volumio13 systemd[1]: Started Volumio Time Update Utility. May 29 16:40:07 volumio13 volumio[889]: info: [now-playing] App is listening on port 4004. May 29 16:40:07 volumio13 volumio[889]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds May 29 16:40:07 volumio13 volumio[889]: info: CoreStateMachine::setRepeat false single undefined May 29 16:40:07 volumio13 volumio[889]: info: CoreStateMachine::pushState May 29 16:40:07 volumio13 volumio[889]: info: CorePlayQueue::getTrack 0 May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::volumioPushState May 29 16:40:07 volumio13 volumio[889]: info: CoreStateMachine::setRandom null May 29 16:40:07 volumio13 volumio[889]: info: CoreStateMachine::pushState May 29 16:40:07 volumio13 volumio[889]: info: CorePlayQueue::getTrack 0 May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::volumioPushState May 29 16:40:07 volumio13 volumio[889]: info: Setting Device type: Tinkerboard May 29 16:40:07 volumio13 winbindd[1125]: [2025/05/29 16:40:07.086752, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 29 16:40:07 volumio13 winbindd[1125]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 29 16:40:07 volumio13 winbindd[1125]: [2025/05/29 16:40:07.100660, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 29 16:40:07 volumio13 winbindd[1125]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 29 16:40:07 volumio13 systemd[1]: Started Samba Winbind Daemon. May 29 16:40:07 volumio13 systemd[1]: Starting Samba SMB Daemon... May 29 16:40:07 volumio13 volumio[889]: verbose: New Socket.io Connection to 192.168.0.200 from 192.168.0.130 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 May 29 16:40:07 volumio13 volumio[889]: info: Volumio called home May 29 16:40:07 volumio13 volumio[889]: info: MPD Permissions set May 29 16:40:07 volumio13 volumio[889]: info: MPD Permissions set May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::volumioGetVisibleSources May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::volumioGetState May 29 16:40:07 volumio13 volumio[889]: info: CorePlayQueue::getTrack 0 May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::volumioGetState May 29 16:40:07 volumio13 volumio[889]: info: CorePlayQueue::getTrack 0 May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 29 16:40:07 volumio13 volumio[889]: info: Received Get System Info May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 16:40:07 volumio13 volumio[889]: info: Discovery: Getting this device information May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::volumioGetState May 29 16:40:07 volumio13 volumio[889]: info: CorePlayQueue::getTrack 0 May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::volumioGetState May 29 16:40:07 volumio13 volumio[889]: info: CorePlayQueue::getTrack 0 May 29 16:40:07 volumio13 volumio[889]: info: Listing playlists May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::volumioGetQueue May 29 16:40:07 volumio13 volumio[889]: info: CoreStateMachine::getQueue May 29 16:40:07 volumio13 volumio[889]: info: CorePlayQueue::getQueue May 29 16:40:07 volumio13 volumio[889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:07 volumio13 volumio[889]: info: Discovery: adding 47f80a69-f3c0-49f5-ad07-7d0571402eb0 May 29 16:40:07 volumio13 volumio[889]: info: Discovery: Found device Volumio13 May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::volumioGetState May 29 16:40:07 volumio13 volumio[889]: info: CorePlayQueue::getTrack 0 May 29 16:40:07 volumio13 winbindd[1125]: [2025/05/29 16:40:07.499827, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) May 29 16:40:07 volumio13 winbindd[1125]: res_names->count = 3, expected 4 May 29 16:40:07 volumio13 smbd[1167]: [2025/05/29 16:40:07.515994, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 29 16:40:07 volumio13 systemd[1]: Started Samba SMB Daemon. May 29 16:40:07 volumio13 smbd[1167]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 29 16:40:07 volumio13 systemd[1]: Reached target Multi-User System. May 29 16:40:07 volumio13 systemd[1]: Reached target Graphical Interface. May 29 16:40:07 volumio13 systemd[1]: Starting Update UTMP about System Runlevel Changes... May 29 16:40:07 volumio13 volumio[889]: info: Starting Shairport Sync May 29 16:40:07 volumio13 volumio[889]: info: Starting Shairport Sync May 29 16:40:07 volumio13 volumio[889]: info: Starting Shairport Sync May 29 16:40:07 volumio13 sudo[1190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 16:40:07 volumio13 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 29 16:40:07 volumio13 systemd[1]: Started Update UTMP about System Runlevel Changes. May 29 16:40:07 volumio13 sudo[1194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 16:40:07 volumio13 sudo[1190]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:07 volumio13 sudo[1196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 16:40:07 volumio13 sudo[1196]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:07 volumio13 sudo[1194]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:07 volumio13 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 29 16:40:07 volumio13 systemd[1]: shairport-sync.service: Succeeded. May 29 16:40:07 volumio13 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 29 16:40:07 volumio13 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 29 16:40:07 volumio13 sudo[1190]: pam_unix(sudo:session): session closed for user root May 29 16:40:07 volumio13 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 29 16:40:07 volumio13 systemd[1]: shairport-sync.service: Succeeded. May 29 16:40:07 volumio13 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 29 16:40:07 volumio13 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 29 16:40:07 volumio13 sudo[1194]: pam_unix(sudo:session): session closed for user root May 29 16:40:07 volumio13 sudo[1196]: pam_unix(sudo:session): session closed for user root May 29 16:40:07 volumio13 volumio[889]: info: CoreCommandRouter::volumioGetState May 29 16:40:07 volumio13 volumio[889]: info: CorePlayQueue::getTrack 0 May 29 16:40:07 volumio13 volumio[889]: verbose: New Socket.io Connection to 192.168.0.200 from 192.168.0.130 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 May 29 16:40:07 volumio13 volumio[889]: info: Shairport-Sync Started May 29 16:40:07 volumio13 volumio[889]: Error adding Membership: Error: addMembership EINVAL May 29 16:40:07 volumio13 volumio[889]: info: Shairport-Sync Started May 29 16:40:07 volumio13 volumio[889]: info: Shairport-Sync Started May 29 16:40:08 volumio13 volumio[889]: verbose: New Socket.io Connection to 192.168.0.200 from 192.168.0.130 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 May 29 16:40:08 volumio13 mpd[1157]: May 29 16:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 29 16:40:08 volumio13 systemd[1]: Started Music Player Daemon. May 29 16:40:08 volumio13 systemd[1]: Startup finished in 14.575s (kernel) + 16.026s (userspace) = 30.602s. May 29 16:40:08 volumio13 sudo[1071]: pam_unix(sudo:session): session closed for user root May 29 16:40:08 volumio13 sudo[1088]: pam_unix(sudo:session): session closed for user root May 29 16:40:08 volumio13 volumio[889]: info: Completed starting Core Plugins May 29 16:40:08 volumio13 volumio[889]: info: ------------------------------------------- May 29 16:40:08 volumio13 volumio[889]: info: ----- MyVolumio plugins startup ---- May 29 16:40:08 volumio13 volumio[889]: info: ------------------------------------------- May 29 16:40:08 volumio13 volumio[889]: info: [MyVolumio PluginManager] Fetching plans data.... May 29 16:40:08 volumio13 volumio[889]: error: MPD error: The expression evaluated to a falsy value: May 29 16:40:08 volumio13 volumio[889]: assert.ok(self.idling) May 29 16:40:08 volumio13 volumio[889]: error: The expression evaluated to a falsy value: May 29 16:40:08 volumio13 volumio[889]: assert.ok(self.idling) May 29 16:40:08 volumio13 volumio[889]: error: updateQueue error: null May 29 16:40:08 volumio13 volumio[889]: info: MPD running with PID1157 May 29 16:40:08 volumio13 volumio[889]: ,establishing connection May 29 16:40:08 volumio13 volumio[889]: error: updateQueue error: null May 29 16:40:10 volumio13 volumio[889]: info: Volumio Network Manager: Network status updated: 1 May 29 16:40:11 volumio13 sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=bunddog,password=GU_-sw05,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.0 //192.168.0.130/Volumio2 /mnt/NAS/torrent-u May 29 16:40:11 volumio13 sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:11 volumio13 sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 16:40:11 volumio13 sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:11 volumio13 sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 16:40:11 volumio13 sudo[1259]: pam_unix(sudo:session): session closed for user root May 29 16:40:11 volumio13 sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 29 16:40:11 volumio13 sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:11 volumio13 sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:11 volumio13 sudo[1251]: pam_unix(sudo:session): session closed for user root May 29 16:40:11 volumio13 sudo[1265]: pam_unix(sudo:session): session closed for user root May 29 16:40:11 volumio13 systemd[1]: Started UPnP Renderer front-end to MPD. May 29 16:40:11 volumio13 sudo[1271]: pam_unix(sudo:session): session closed for user root May 29 16:40:11 volumio13 volumio[889]: info: Upmpdcli Daemon Started May 29 16:40:11 volumio13 volumio[1295]: Generating RSA private key, 4096 bit long modulus (2 primes) May 29 16:40:11 volumio13 volumio[1295]: .......++++ May 29 16:40:13 volumio13 volumio-remote-updater[559]: [2025-05-29 16:40:13] [connect] Successful connection May 29 16:40:13 volumio13 volumio-remote-updater[559]: [2025-05-29 16:40:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748529613 101 May 29 16:40:13 volumio13 volumio[889]: 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: 7 May 29 16:40:15 volumio13 volumio[1295]: ................................................................++++ May 29 16:40:15 volumio13 volumio[1295]: e is 65537 (0x010001) May 29 16:40:15 volumio13 volumio[1295]: writing RSA key May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 29 16:40:16 volumio13 volumio[889]: info: Adding plugin bluetooth to MyMusic Plugins May 29 16:40:16 volumio13 volumio[889]: info: Adding plugin multiroom to MyMusic Plugins May 29 16:40:16 volumio13 volumio[889]: info: Adding plugin metavolumio to MyMusic Plugins May 29 16:40:16 volumio13 volumio[889]: info: Adding plugin cd_controller to MyMusic Plugins May 29 16:40:16 volumio13 volumio[889]: info: Adding plugin smart_inputs to MyMusic Plugins May 29 16:40:16 volumio13 volumio[889]: info: Adding plugin tidalconnect to MyMusic Plugins May 29 16:40:16 volumio13 volumio[889]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 29 16:40:17 volumio13 volumio[889]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 29 16:40:17 volumio13 volumio[889]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 29 16:40:17 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:17 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:17 volumio13 volumio[889]: info: Starting MyVolumio Remote Streaming Endpoints May 29 16:40:17 volumio13 volumio[889]: info: MyVolumio login type: Token May 29 16:40:17 volumio13 volumio[889]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 29 16:40:17 volumio13 volumio[889]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 29 16:40:18 volumio13 volumio[889]: info: Starting Streaming Service Transparent Proxy May 29 16:40:18 volumio13 volumio[889]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 29 16:40:18 volumio13 volumio[889]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 29 16:40:18 volumio13 volumio[889]: info: Streaming services startup May 29 16:40:18 volumio13 volumio[889]: info: Starting Streaming Daemon May 29 16:40:18 volumio13 sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 29 16:40:18 volumio13 volumio[889]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 29 16:40:18 volumio13 sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:18 volumio13 sudo[1337]: pam_unix(sudo:session): session closed for user root May 29 16:40:18 volumio13 volumio[889]: error: Cannot start Volumio Streaming Daemon May 29 16:40:18 volumio13 volumio[889]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 29 16:40:18 volumio13 volumio[889]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 29 16:40:18 volumio13 volumio[889]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 29 16:40:19 volumio13 volumio[889]: STREAMING PROXY: Starting server on port 3245 May 29 16:40:19 volumio13 volumio[889]: Node JS runtime: 14 May 29 16:40:19 volumio13 volumio[889]: info: MyVolumio token set successfully May 29 16:40:19 volumio13 volumio[889]: info: MYVOLUMIO: Adding device May 29 16:40:19 volumio13 volumio[889]: info: MYVOLUMIO: Evaluating Server May 29 16:40:19 volumio13 volumio[889]: info: MyVolumio Plan changed: superstar May 29 16:40:19 volumio13 volumio[889]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar May 29 16:40:19 volumio13 volumio[889]: info: Adding audio output: May 29 16:40:19 volumio13 volumio[889]: info: MYVOLUMIO: Adding device May 29 16:40:19 volumio13 volumio[889]: info: MYVOLUMIO: Evaluating Server May 29 16:40:19 volumio13 volumio[889]: info: Remote config written successfully May 29 16:40:19 volumio13 volumio[889]: info: Starting Tunnel 1 May 29 16:40:19 volumio13 volumio[889]: info: Starting Tunnel Connection Checker May 29 16:40:19 volumio13 volumio[889]: info: Completed starting MyVolumio Plugin May 29 16:40:20 volumio13 volumio[889]: info: MYVolumio Device enabled May 29 16:40:20 volumio13 volumio[889]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 29 16:40:20 volumio13 volumio[889]: info: MyVolumio status changed May 29 16:40:20 volumio13 volumio[889]: info: Streaming services startup May 29 16:40:20 volumio13 volumio[889]: info: Starting Streaming Daemon May 29 16:40:20 volumio13 volumio[889]: info: Setting Geolocation for MyVolumio to eu10 May 29 16:40:20 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:20 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:20 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:20 volumio13 sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 29 16:40:20 volumio13 sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:20 volumio13 sudo[1396]: pam_unix(sudo:session): session closed for user root May 29 16:40:20 volumio13 volumio[889]: error: Cannot start Volumio Streaming Daemon May 29 16:40:20 volumio13 volumio[889]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 29 16:40:20 volumio13 volumio[889]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 29 16:40:20 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 29 16:40:20 volumio13 volumio[889]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! May 29 16:40:20 volumio13 volumio[889]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 29 16:40:20 volumio13 volumio[889]: info: [1748529620348] Starting BluetoothController May 29 16:40:20 volumio13 volumio[889]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom May 29 16:40:21 volumio13 sudo[1420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 29 16:40:21 volumio13 sudo[1420]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:21 volumio13 sudo[1420]: pam_unix(sudo:session): session closed for user root May 29 16:40:21 volumio13 volumio[889]: info: MRS: MultiRoom plugin initialized May 29 16:40:21 volumio13 volumio[889]: info: MRS: STOPPING SNAPCLIENT May 29 16:40:21 volumio13 volumio[889]: info: MRS: Snap server stop May 29 16:40:21 volumio13 volumio[889]: info: MRS: STOPPING volumioStreaming May 29 16:40:21 volumio13 sudo[1443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 29 16:40:21 volumio13 sudo[1446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 29 16:40:21 volumio13 sudo[1443]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 29 16:40:21 volumio13 sudo[1446]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:21 volumio13 sudo[1451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 29 16:40:21 volumio13 sudo[1451]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 29 16:40:21 volumio13 sudo[1443]: pam_unix(sudo:session): session closed for user root May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 29 16:40:21 volumio13 sudo[1446]: pam_unix(sudo:session): session closed for user root May 29 16:40:21 volumio13 sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 29 16:40:21 volumio13 sudo[1451]: pam_unix(sudo:session): session closed for user root May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 29 16:40:21 volumio13 sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:21 volumio13 sudo[1462]: pam_unix(sudo:session): session closed for user root May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 29 16:40:21 volumio13 volumio[889]: info: Preparing to generate the ALSA configuration file May 29 16:40:21 volumio13 volumio[889]: info: Updating MyVolumio device info May 29 16:40:21 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:21 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:21 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:21 volumio13 volumio[889]: info: Setting Geolocation for MyVolumio to eu6 May 29 16:40:21 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:21 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:21 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:21 volumio13 volumio[889]: info: MRS: Removed streaming files May 29 16:40:21 volumio13 volumio[889]: info: MRS: volumioStreaming STOPPED May 29 16:40:21 volumio13 volumio[889]: info: MRS: SNAPSERVER STOPPED May 29 16:40:21 volumio13 volumio[889]: info: MRS: SNAPCLIENT STOPPED May 29 16:40:21 volumio13 volumio[889]: info: Asound.conf file unchanged, so no further update is needed May 29 16:40:21 volumio13 volumio[889]: info: Output device has changed, restarting MPD May 29 16:40:21 volumio13 volumio[889]: info: Output device has changed, restarting Shairport Sync May 29 16:40:21 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:21 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:21 volumio13 sudo[1483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 16:40:21 volumio13 sudo[1483]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:21 volumio13 sudo[1486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 16:40:21 volumio13 sudo[1483]: pam_unix(sudo:session): session closed for user root May 29 16:40:21 volumio13 sudo[1486]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:21 volumio13 volumio[889]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 29 16:40:21 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:21 volumio13 systemd[1]: Stopping Music Player Daemon... May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 29 16:40:21 volumio13 volumio[889]: info: Adding METAVOLUMIO REST API Endpoints May 29 16:40:21 volumio13 volumio[889]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 29 16:40:21 volumio13 volumio[889]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 29 16:40:21 volumio13 volumio[889]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 29 16:40:21 volumio13 volumio[889]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 29 16:40:21 volumio13 volumio[889]: info: Adding inputs REST Endpoints May 29 16:40:21 volumio13 volumio[889]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 29 16:40:21 volumio13 volumio[889]: info: Scanning Audio Inputs May 29 16:40:21 volumio13 volumio[889]: info: Checking against Known Cards name May 29 16:40:21 volumio13 volumio[889]: info: Checking against Known Cards name May 29 16:40:21 volumio13 volumio[889]: info: Checking against Known Cards name May 29 16:40:21 volumio13 volumio[889]: info: Adding Server instance for streaming May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 29 16:40:21 volumio13 volumio[889]: error: Hi Res Audio Failed Login: Missing Login Data May 29 16:40:21 volumio13 volumio[889]: info: Adding HIGHRESAUDIO REST API Endpoints May 29 16:40:21 volumio13 volumio[889]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 29 16:40:21 volumio13 volumio[889]: info: Refreshing TIDAL token May 29 16:40:21 volumio13 systemd[1]: mpd.service: Succeeded. May 29 16:40:21 volumio13 systemd[1]: Stopped Music Player Daemon. May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 29 16:40:21 volumio13 volumio[889]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 29 16:40:21 volumio13 systemd[1]: Starting Music Player Daemon... May 29 16:40:21 volumio13 volumio[889]: info: Stopping AccessToken refresher cron for QOBUZ May 29 16:40:21 volumio13 sudo[1508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 29 16:40:21 volumio13 sudo[1508]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:21 volumio13 volumio[889]: info: AccessToken refresher cron started for QOBUZ May 29 16:40:21 volumio13 volumio[889]: info: Adding QOBUZ REST API Endpoints May 29 16:40:21 volumio13 volumio[889]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 29 16:40:21 volumio13 volumio[889]: info: MPD Permissions set May 29 16:40:21 volumio13 sudo[1506]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 16:40:21 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:21 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:21 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:21 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:21 volumio13 sudo[1508]: pam_unix(sudo:session): session closed for user root May 29 16:40:21 volumio13 sudo[1506]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:21 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:21 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 29 16:40:21 volumio13 sudo[1506]: pam_unix(sudo:session): session closed for user root May 29 16:40:21 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:21 volumio13 volumio[889]: info: Starting Shairport Sync May 29 16:40:22 volumio13 sudo[1533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 16:40:22 volumio13 sudo[1533]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:22 volumio13 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 29 16:40:22 volumio13 systemd[1]: shairport-sync.service: Succeeded. May 29 16:40:22 volumio13 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 29 16:40:22 volumio13 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 29 16:40:22 volumio13 sudo[1533]: pam_unix(sudo:session): session closed for user root May 29 16:40:22 volumio13 volumio[889]: info: Shairport-Sync Started May 29 16:40:22 volumio13 volumio[889]: info: Successfully Added MyVolumio device May 29 16:40:22 volumio13 volumio[889]: info: Access Token successfully retrieved May 29 16:40:22 volumio13 volumio[889]: info: Successfully Added MyVolumio device May 29 16:40:22 volumio13 volumio[889]: info: Successfully Updated MyVolumio device May 29 16:40:22 volumio13 volumio[889]: info: Successfully retrieved User Session From TIDAL May 29 16:40:22 volumio13 volumio[889]: info: Successfully retrieved User Subscription From TIDAL May 29 16:40:22 volumio13 volumio[889]: info: Adding TIDAL to Browse Sources May 29 16:40:22 volumio13 volumio[889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 16:40:22 volumio13 volumio[889]: info: [1748529622694] CoreMusicLibrary::Adding element TIDAL May 29 16:40:22 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 16:40:22 volumio13 volumio[889]: Cannot find translation for source YouTube2 May 29 16:40:22 volumio13 volumio[889]: Cannot find translation for source TIDAL May 29 16:40:22 volumio13 volumio[889]: info: Stopping AccessToken refresher cron May 29 16:40:22 volumio13 volumio[889]: info: AccessToken refresher cron started May 29 16:40:22 volumio13 volumio[889]: info: Adding TIDAL REST API Endpoints May 29 16:40:22 volumio13 volumio[889]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 29 16:40:22 volumio13 volumio[889]: info: Updating MyVolumio device info May 29 16:40:22 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:22 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:22 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:22 volumio13 mpd[1539]: May 29 16:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 29 16:40:22 volumio13 systemd[1]: Started Music Player Daemon. May 29 16:40:22 volumio13 sudo[1486]: pam_unix(sudo:session): session closed for user root May 29 16:40:22 volumio13 volumio[889]: error: updateQueue error: null May 29 16:40:23 volumio13 volumio[889]: info: Successfully Updated MyVolumio device May 29 16:40:24 volumio13 systemd[1]: systemd-fsckd.service: Succeeded. May 29 16:40:24 volumio13 sudo[1556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 29 16:40:24 volumio13 sudo[1556]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:24 volumio13 systemd[1]: Started MyVolumio SSH Tunnel. May 29 16:40:24 volumio13 sudo[1556]: pam_unix(sudo:session): session closed for user root May 29 16:40:24 volumio13 volumio[889]: info: Remote SSH Started May 29 16:40:24 volumio13 autossh[1564]: port set to 0, monitoring disabled May 29 16:40:24 volumio13 autossh[1564]: starting ssh (count 1) May 29 16:40:24 volumio13 autossh[1564]: ssh child pid is 1569 May 29 16:40:24 volumio13 volumio[889]: info: TidalConnect service stoped! May 29 16:40:25 volumio13 volumio[889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 29 16:40:25 volumio13 volumio[889]: info: CoreCommandRouter::volumioGetState May 29 16:40:25 volumio13 volumio[889]: info: CorePlayQueue::getTrack 0 May 29 16:40:25 volumio13 volumio[889]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 29 16:40:25 volumio13 volumio[889]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 29 16:40:25 volumio13 volumiossh-tunnel[1562]: Warning: Permanently added '[eu6.myvolumio.org]:2222,[207.154.233.230]:2222' (RSA) to the list of known hosts. May 29 16:40:25 volumio13 sudo[1579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 29 16:40:25 volumio13 sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 16:40:25 volumio13 systemd[1]: Started Volumio Tidal Connect Service. May 29 16:40:25 volumio13 sudo[1579]: pam_unix(sudo:session): session closed for user root May 29 16:40:25 volumio13 volumio[889]: info: Executing endpoint tc_getconfig May 29 16:40:25 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 29 16:40:25 volumio13 vtcs[1585]: STARTING TidalConnect services, version: 1.4.0.34 May 29 16:40:25 volumio13 vtcs[1585]: STARTED TidalConnect services. May 29 16:40:25 volumio13 volumio[889]: info: Executing endpoint tc_connect May 29 16:40:25 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 29 16:40:25 volumio13 volumio[889]: info: Connecting to TidalConnect May 29 16:40:25 volumio13 volumio[889]: info: CoreCommandRouter::servicePushState May 29 16:40:25 volumio13 volumio[889]: info: CoreStateMachine::pushState May 29 16:40:25 volumio13 volumio[889]: info: CorePlayQueue::getTrack 0 May 29 16:40:25 volumio13 volumio[889]: info: CoreCommandRouter::volumioPushState May 29 16:40:25 volumio13 volumio[889]: info: CorePlayQueue::getTrack 0 May 29 16:40:25 volumio13 volumio[889]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect May 29 16:40:25 volumio13 volumio[889]: info: CoreCommandRouter::servicePushState May 29 16:40:25 volumio13 volumio[889]: info: CoreStateMachine::pushState May 29 16:40:25 volumio13 volumio[889]: info: CorePlayQueue::getTrack 0 May 29 16:40:25 volumio13 volumio[889]: info: CoreCommandRouter::volumioPushState May 29 16:40:25 volumio13 volumio[889]: info: CorePlayQueue::getTrack 0 May 29 16:40:25 volumio13 volumio[889]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect May 29 16:40:26 volumio13 systemd[1]: systemd-hostnamed.service: Succeeded. May 29 16:40:28 volumio13 volumio[889]: info: TidalConnect service started! May 29 16:40:28 volumio13 volumio[889]: [Metrics] CommandRouter: 29s 340.79ms May 29 16:40:28 volumio13 volumio[889]: info: CoreCommandRouter::volumiosetStartupVolume May 29 16:40:28 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:28 volumio13 volumio[889]: info: VolumeController:: Setting startup Volume 40 May 29 16:40:28 volumio13 volumio[889]: info: VolumeController::SetAlsaVolume40 May 29 16:40:28 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:28 volumio13 volumio[889]: info: CoreCommandRouter::Close All Modals sent May 29 16:40:28 volumio13 volumio[889]: info: CoreCommandRouter::Close All Modals sent May 29 16:40:28 volumio13 volumio[889]: info: CoreStateMachine::pushState May 29 16:40:28 volumio13 volumio[889]: info: CorePlayQueue::getTrack 0 May 29 16:40:28 volumio13 volumio[889]: info: CoreCommandRouter::volumioPushState May 29 16:40:29 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 29 16:40:29 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 29 16:40:29 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 29 16:40:32 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 16:40:32 volumio13 volumio[889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 29 16:40:32 volumio13 volumio[889]: info: CoreCommandRouter::volumioGetState May 29 16:40:32 volumio13 volumio[889]: info: CorePlayQueue::getTrack 0 May 29 16:40:35 volumio13 volumio[889]: info: BOOT COMPLETED May 29 16:40:36 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 16:40:36 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 16:40:36 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 29 16:40:37 volumio13 volumio-remote-updater[559]: No test mode May 29 16:40:37 volumio13 volumio-remote-updater[559]: No alpha test mode May 29 16:40:37 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 29 16:40:37 volumio13 systemd[1]: systemd-timedated.service: Succeeded. May 29 16:40:37 volumio13 volumio[889]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 29 16:40:37 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 29 16:40:37 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:37 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 16:40:37 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 29 16:40:37 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 29 16:40:37 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 29 16:40:37 volumio13 volumio[889]: info: CoreCommandRouter::volumioGetBrowseSources May 29 16:40:37 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 16:41:01 volumio13 ntpd[800]: Soliciting pool server 193.227.197.2 May 29 16:41:02 volumio13 ntpd[800]: Soliciting pool server 188.156.114.7 May 29 16:41:03 volumio13 ntpd[800]: Soliciting pool server 62.112.193.9 May 29 16:41:03 volumio13 ntpd[800]: Soliciting pool server 195.111.92.55 May 29 16:41:04 volumio13 ntpd[800]: Soliciting pool server 194.38.104.150 May 29 16:41:04 volumio13 ntpd[800]: Soliciting pool server 81.0.124.253 May 29 16:41:05 volumio13 ntpd[800]: Soliciting pool server 213.157.100.66 May 29 16:41:05 volumio13 ntpd[800]: Soliciting pool server 193.224.163.77 May 29 16:41:06 volumio13 ntpd[800]: Soliciting pool server 81.0.124.200 May 29 16:41:06 volumio13 ntpd[800]: Soliciting pool server 89.107.253.4 May 29 16:41:06 volumio13 ntpd[800]: Soliciting pool server 91.82.109.180 May 29 16:41:07 volumio13 ntpd[800]: Soliciting pool server 80.249.164.129 May 29 16:41:07 volumio13 ntpd[800]: Soliciting pool server 92.249.148.253 May 29 16:41:07 volumio13 ntpd[800]: Soliciting pool server 193.6.222.47 May 29 16:41:08 volumio13 ntpd[800]: Soliciting pool server 2001:738:0:401:80:c106:de2f:123 May 29 16:41:08 volumio13 ntpd[800]: Soliciting pool server 213.157.100.71 May 29 16:41:09 volumio13 ntpd[800]: Soliciting pool server 82.141.152.3 May 29 16:41:12 volumio13 ntpd[800]: receive: Unexpected origin timestamp 0xebe2f086.41b709c5 does not match aorg 0000000000.00000000 from server@193.227.197.2 xmt 0xebe2f088.12037c5c May 29 16:41:41 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 29 16:41:41 volumio13 volumio[889]: info: Preload queue cleared May 29 16:41:42 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 29 16:41:42 volumio13 volumio[889]: info: Preload queue cleared May 29 16:41:43 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 29 16:41:44 volumio13 volumio[889]: info: Preload queue cleared May 29 16:41:45 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 29 16:41:45 volumio13 volumio[889]: info: Preload queue cleared May 29 16:41:48 volumio13 volumio[889]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 29 16:41:48 volumio13 volumio[889]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 16:41:48 volumio13 volumio[889]: TypeError: Cannot read property 'length' of undefined May 29 16:41:48 volumio13 volumio[889]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 29 16:41:48 volumio13 volumio[889]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 29 16:41:48 volumio13 volumio[889]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 29 16:41:48 volumio13 volumio[889]: at Parser.emit (events.js:400:28) May 29 16:41:48 volumio13 volumio[889]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 29 16:41:48 volumio13 volumio[889]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 29 16:41:48 volumio13 volumio[889]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 29 16:41:48 volumio13 volumio[889]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 29 16:41:48 volumio13 volumio[889]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 29 16:41:48 volumio13 volumio[889]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 29 16:41:48 volumio13 volumio[889]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 29 16:41:48 volumio13 volumio[889]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 29 16:41:48 volumio13 volumio[889]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 29 16:41:48 volumio13 volumio[889]: at IncomingMessage.emit (events.js:412:35) May 29 16:41:48 volumio13 volumio[889]: at endReadableNT (internal/streams/readable.js:1333:12) May 29 16:41:48 volumio13 volumio[889]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 29 16:41:48 volumio13 volumio[889]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 16:41:48 volumio13 sudo[1794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-29 16:40 May 29 16:41:48 volumio13 sudo[1794]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:53:51 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="53825f995260683aee752aed6b29f24e"