-- Logs begin at Mon 2025-06-02 00:05:52 EEST, end at Mon 2025-06-02 00:07:10 EEST. -- Jun 02 00:06:00 volumio volumio-time-update[785]: volumio-time-update-util: Fetching time from Volumio... Jun 02 00:06:00 volumio volumio-time-update[785]: volumio-time-update-util: Date not found in response Jun 02 00:06:00 volumio volumio-time-update[785]: volumio-time-update-util: Retrying in 5 seconds... Jun 02 00:06:00 volumio ntpd[1064]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 02 00:06:00 volumio volumio-remote-updater[783]: [2025-06-02 00:06:00] [connect] Successful connection Jun 02 00:06:00 volumio volumio[1105]: info: ------------------------------------------- Jun 02 00:06:00 volumio volumio[1105]: info: ----- Volumio3 ---- Jun 02 00:06:00 volumio volumio[1105]: info: ------------------------------------------- Jun 02 00:06:00 volumio volumio[1105]: info: ----- System startup ---- Jun 02 00:06:00 volumio volumio[1105]: info: ------------------------------------------- Jun 02 00:06:01 volumio systemd[1]: twonky.service: Succeeded. Jun 02 00:06:01 volumio systemd[1]: twonky.service: Service RestartSec=100ms expired, scheduling restart. Jun 02 00:06:01 volumio volumio[1105]: info: MYVOLUMIO Environment detected Jun 02 00:06:01 volumio systemd[1]: twonky.service: Scheduled restart job, restart counter is at 4. Jun 02 00:06:01 volumio systemd[1]: Stopped Twonky Server at Volumio. Jun 02 00:06:01 volumio systemd[1]: Started Twonky Server at Volumio. Jun 02 00:06:01 volumio systemd[1]: twonky.service: Succeeded. Jun 02 00:06:01 volumio volumio[1105]: info: Plugin folders cleanup Jun 02 00:06:01 volumio volumio[1105]: info: Scanning into folder /volumio/app/plugins/ Jun 02 00:06:01 volumio volumio[1105]: info: Scanning category audio_interface Jun 02 00:06:01 volumio volumio[1105]: info: Scanning category miscellanea Jun 02 00:06:01 volumio volumio[1105]: info: Scanning category music_service Jun 02 00:06:02 volumio volumio[1105]: info: Scanning category plugins.json Jun 02 00:06:02 volumio volumio[1105]: info: Scanning category system_controller Jun 02 00:06:02 volumio volumio[1105]: info: Scanning category user_interface Jun 02 00:06:02 volumio volumio[1105]: info: Scanning into folder /data/plugins/ Jun 02 00:06:02 volumio volumio[1105]: info: Scanning category music_service Jun 02 00:06:02 volumio volumio[1105]: info: Scanning category system_hardware Jun 02 00:06:02 volumio volumio[1105]: info: Scanning category user_interface Jun 02 00:06:02 volumio volumio[1105]: info: Plugin folders cleanup completed Jun 02 00:06:02 volumio volumio[1105]: info: ------------------------------------------- Jun 02 00:06:02 volumio volumio[1105]: info: ----- Core plugins startup ---- Jun 02 00:06:02 volumio volumio[1105]: info: ------------------------------------------- Jun 02 00:06:02 volumio volumio[1105]: info: Loading plugins from folder /volumio/app/plugins/ Jun 02 00:06:02 volumio volumio[1105]: info: Adding plugin upnp to MyMusic Plugins Jun 02 00:06:02 volumio volumio[1105]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 02 00:06:02 volumio volumio[1105]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 02 00:06:02 volumio volumio[1105]: info: Loading plugins from folder /data/plugins/ Jun 02 00:06:02 volumio volumio[1105]: info: Loading plugin "system"... Jun 02 00:06:02 volumio volumio[1105]: info: Loading plugin "appearance"... Jun 02 00:06:02 volumio systemd[1]: twonky.service: Service RestartSec=100ms expired, scheduling restart. Jun 02 00:06:02 volumio systemd[1]: twonky.service: Scheduled restart job, restart counter is at 5. Jun 02 00:06:02 volumio systemd[1]: Stopped Twonky Server at Volumio. Jun 02 00:06:02 volumio systemd[1]: twonky.service: Start request repeated too quickly. Jun 02 00:06:02 volumio systemd[1]: twonky.service: Failed with result 'start-limit-hit'. Jun 02 00:06:02 volumio systemd[1]: Failed to start Twonky Server at Volumio. Jun 02 00:06:03 volumio volumio[1105]: info: Loading plugin "network"... Jun 02 00:06:03 volumio volumio[1105]: info: Refreshing Cached IP Addresses Jun 02 00:06:03 volumio sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 00:06:03 volumio sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:03 volumio sudo[1360]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:03 volumio volumio[1105]: info: Loading plugin "services"... Jun 02 00:06:03 volumio sudo[1362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 00:06:03 volumio sudo[1362]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:03 volumio volumio[1105]: info: Loading plugin "alsa_controller"... Jun 02 00:06:03 volumio sudo[1362]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:03 volumio volumio[1105]: Home directory not accessible: Permission denied Jun 02 00:06:03 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 00:06:03 volumio volumio[1105]: info: Loading plugin "wizard"... Jun 02 00:06:03 volumio volumio[1105]: info: Loading plugin "networkfs"... Jun 02 00:06:03 volumio volumio[1105]: info: Starting Udev Watcher for removable devices Jun 02 00:06:03 volumio sudo[1383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=HPAdmin,password=HP2020,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.44/e\_Music /mnt/NAS/HPMicroServer10 Jun 02 00:06:03 volumio sudo[1383]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:03 volumio sudo[1385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=ASAdmin,password=AS2020,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.4/_Music /mnt/NAS/AsusDesktop Jun 02 00:06:03 volumio volumio[1105]: info: Ignoring mount for partition: boot Jun 02 00:06:03 volumio sudo[1385]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:03 volumio volumio[1105]: info: Ignoring mount for partition: volumio Jun 02 00:06:03 volumio volumio[1105]: info: Ignoring mount for partition: volumio_data Jun 02 00:06:03 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 00:06:03 volumio volumio[1105]: info: Loading plugin "volumio_command_line_client"... Jun 02 00:06:03 volumio volumio[1105]: info: Loading plugin "upnp"... Jun 02 00:06:03 volumio volumio[1105]: info: [1748811963615] Starting Upmpd Daemon Jun 02 00:06:03 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 00:06:03 volumio volumio[1105]: info: Loading plugin "my_music"... Jun 02 00:06:03 volumio kernel: FS-Cache: Loaded Jun 02 00:06:03 volumio kernel: Key type dns_resolver registered Jun 02 00:06:03 volumio volumio[1105]: info: Loading plugin "mpd"... Jun 02 00:06:03 volumio kernel: Key type cifs.spnego registered Jun 02 00:06:03 volumio kernel: Key type cifs.idmap registered Jun 02 00:06:03 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jun 02 00:06:03 volumio kernel: CIFS: Attempting to mount //192.168.1.4/_Music Jun 02 00:06:03 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 02 00:06:03 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jun 02 00:06:03 volumio sudo[1383]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:03 volumio kernel: CIFS: Attempting to mount //192.168.1.44/e/_Music Jun 02 00:06:03 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 02 00:06:03 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jun 02 00:06:03 volumio sudo[1385]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:03 volumio dhcpcd[938]: eth0: using static address 192.168.1.67/24 Jun 02 00:06:03 volumio avahi-daemon[787]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.67. Jun 02 00:06:03 volumio dhcpcd[938]: eth0: adding route to 192.168.1.0/24 Jun 02 00:06:03 volumio dhcpcd[938]: eth0: adding default route via 192.168.1.1 Jun 02 00:06:03 volumio avahi-daemon[787]: New relevant interface eth0.IPv4 for mDNS. Jun 02 00:06:03 volumio avahi-daemon[787]: Registering new address record for 192.168.1.67 on eth0.IPv4. Jun 02 00:06:04 volumio volumio[1105]: info: Loading plugin "upnp_browser"... Jun 02 00:06:04 volumio volumio[1105]: info: Loading plugin "alarm-clock"... Jun 02 00:06:04 volumio volumio[1105]: info: Loading plugin "airplay_emulation"... Jun 02 00:06:04 volumio volumio[1105]: info: Starting Shairport Sync Jun 02 00:06:04 volumio volumio[1105]: info: Loading plugin "last_100"... Jun 02 00:06:04 volumio volumio[1105]: info: Loading plugin "webradio"... Jun 02 00:06:04 volumio volumio[1105]: info: Loading plugin "i2s_dacs"... Jun 02 00:06:04 volumio volumio[1105]: info: I2S DAC not set, start Auto-detection Jun 02 00:06:04 volumio volumio[1105]: info: Loading plugin "volumiodiscovery"... Jun 02 00:06:04 volumio volumio[1105]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 02 00:06:04 volumio node[1105]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 02 00:06:04 volumio volumio[1105]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 00:06:04 volumio node[1105]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 00:06:04 volumio volumio[1105]: *** WARNING *** For more information see Jun 02 00:06:04 volumio node[1105]: *** WARNING *** For more information see Jun 02 00:06:04 volumio volumio[1105]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 02 00:06:04 volumio node[1105]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 02 00:06:04 volumio volumio[1105]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 00:06:04 volumio node[1105]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 00:06:04 volumio volumio[1105]: *** WARNING *** For more information see Jun 02 00:06:04 volumio node[1105]: *** WARNING *** For more information see Jun 02 00:06:04 volumio volumio[1105]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 02 00:06:04 volumio volumio[1105]: info: Discovery: Started advertising with name: Volumio Jun 02 00:06:04 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 00:06:04 volumio volumio[1105]: info: Loading plugin "bandcamp"... Jun 02 00:06:05 volumio volumio-time-update[785]: volumio-time-update-util: Fetching time from Volumio... Jun 02 00:06:05 volumio ntpd[1064]: Listen normally on 3 eth0 192.168.1.67:123 Jun 02 00:06:05 volumio volumio-time-update[785]: volumio-time-update-util: Setting system time to: 2025-06-02 00:06:05 Jun 02 00:06:05 volumio ntpd[1064]: new interface(s) found: waking up resolver Jun 02 00:06:05 volumio sudo[1455]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-02 00:06:05 Jun 02 00:06:05 volumio sudo[1455]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:05 volumio dbus-daemon[778]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.11' (uid=0 pid=1456 comm="timedatectl set-time 2025-06-02 00:06:05 ") Jun 02 00:06:05 volumio systemd[1]: Starting Time & Date Service... Jun 02 00:06:05 volumio dbus-daemon[778]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 02 00:06:05 volumio systemd[1]: Started Time & Date Service. Jun 02 00:06:05 volumio systemd-timedated[1459]: Changed local time to Mon Jun 2 00:06:05 2025 Jun 02 00:06:05 volumio sudo[1455]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:05 volumio volumio-time-update[785]: volumio-time-update-util: System time updated successfully. Jun 02 00:06:05 volumio systemd[1]: Started Volumio Time Update Utility. Jun 02 00:06:05 volumio volumio[1105]: info: Loading plugin "calmradio"... Jun 02 00:06:06 volumio volumio[1105]: info: Loading plugin "youtube2"... Jun 02 00:06:06 volumio nmbd[1060]: [2025/06/02 00:06:06.661123, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 02 00:06:06 volumio nmbd[1060]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 02 00:06:06 volumio systemd[1]: Started Samba NMB Daemon. Jun 02 00:06:06 volumio systemd[1]: Starting Samba Winbind Daemon... Jun 02 00:06:06 volumio winbindd[1514]: [2025/06/02 00:06:06.873628, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 02 00:06:06 volumio winbindd[1514]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 02 00:06:06 volumio systemd[1]: Started Samba Winbind Daemon. Jun 02 00:06:06 volumio winbindd[1514]: [2025/06/02 00:06:06.883158, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 02 00:06:06 volumio winbindd[1514]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 02 00:06:06 volumio systemd[1]: Starting Samba SMB Daemon... Jun 02 00:06:07 volumio systemd[1]: Started Samba SMB Daemon. Jun 02 00:06:07 volumio systemd[1]: Reached target Multi-User System. Jun 02 00:06:07 volumio smbd[1519]: [2025/06/02 00:06:07.223421, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 02 00:06:07 volumio systemd[1]: Reached target Graphical Interface. Jun 02 00:06:07 volumio smbd[1519]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 02 00:06:07 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 02 00:06:07 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 02 00:06:07 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 02 00:06:07 volumio systemd[1]: Startup finished in 4.574s (firmware) + 2.409s (loader) + 7.776s (kernel) + 16.263s (userspace) = 31.024s. Jun 02 00:06:07 volumio volumio[1105]: info: Loading plugin "ytcr"... Jun 02 00:06:09 volumio volumio[1105]: info: Loading plugin "ytmusic"... Jun 02 00:06:10 volumio volumio[1105]: info: Loading plugin "now_playing"... Jun 02 00:06:11 volumio volumio[1105]: info: Loading plugin "outputs"... Jun 02 00:06:11 volumio volumio[1105]: info: Loading plugin "albumart"... Jun 02 00:06:11 volumio volumio[1105]: info: Plugin example_plugin is not enabled Jun 02 00:06:11 volumio volumio[1105]: info: Loading plugin "inputs"... Jun 02 00:06:11 volumio volumio[1105]: info: Loading plugin "updater_comm"... Jun 02 00:06:11 volumio volumio[1105]: Forking 1 albumart workers Jun 02 00:06:11 volumio volumio[1105]: info: Plugin mpdemulation is not enabled Jun 02 00:06:11 volumio volumio[1105]: info: Loading plugin "rest_api"... Jun 02 00:06:11 volumio volumio[1105]: info: Loading plugin "websocket"... Jun 02 00:06:11 volumio volumio[1105]: info: Starting Socket.io Server version 2.3.0 Jun 02 00:06:11 volumio volumio[1105]: info: Loading plugin "RoonBridge"... Jun 02 00:06:11 volumio volumio[1105]: info: Applying required configuration parameters for plugin RoonBridge Jun 02 00:06:11 volumio volumio[1105]: info: Loading plugin "minidlna"... Jun 02 00:06:11 volumio volumio[1105]: Starting albumart workers Jun 02 00:06:11 volumio volumio[1105]: info: Applying required configuration parameters for plugin minidlna Jun 02 00:06:12 volumio volumio[1105]: info: Loading plugin "motherearthradio"... Jun 02 00:06:12 volumio volumio[1105]: info: Applying required configuration parameters for plugin motherearthradio Jun 02 00:06:12 volumio volumio[1105]: info: [1748811972209] [MotherEarth] API delay: 5 Jun 02 00:06:12 volumio volumio[1105]: info: Loading plugin "radio_paradise"... Jun 02 00:06:12 volumio volumio[1105]: info: Applying required configuration parameters for plugin radio_paradise Jun 02 00:06:12 volumio volumio[1105]: info: [1748811972330] [RadioParadise] API delay: 5 Jun 02 00:06:12 volumio volumio[1105]: info: Loading plugin "music_services_shield"... Jun 02 00:06:12 volumio volumio[1105]: info: Applying required configuration parameters for plugin music_services_shield Jun 02 00:06:12 volumio volumio[1105]: info: Loading plugin "Systeminfo"... Jun 02 00:06:12 volumio volumio[1105]: info: Loading i18n strings for locale ua Jun 02 00:06:12 volumio volumio[1105]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jun 02 00:06:12 volumio volumio[1105]: Updating browse sources language Jun 02 00:06:12 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 00:06:12 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 00:06:12 volumio volumio[1105]: info: CoreCommandRouter::initPlayerControls Jun 02 00:06:12 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:12 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:12 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:12 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:12 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:12 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:12 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:12 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:12 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 00:06:12 volumio volumio[1105]: Express server listening on port 3000 Jun 02 00:06:12 volumio volumio[1105]: [Metrics] WebUI: 13s 389.73ms Jun 02 00:06:12 volumio volumio[1105]: info: Setting Device type: x86 Jun 02 00:06:12 volumio volumio[1105]: info: CoreStateMachine::resetVolumioState Jun 02 00:06:12 volumio volumio[1105]: info: CoreStateMachine::getcurrentVolume Jun 02 00:06:12 volumio volumio[1105]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 00:06:12 volumio volumio[1105]: info: CoreStateMachine::pushState Jun 02 00:06:12 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:12 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 00:06:12 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Jun 02 00:06:12 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:12 volumio volumio[1105]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 02 00:06:12 volumio volumio[1105]: info: Completed loading Core Plugins Jun 02 00:06:12 volumio volumio[1105]: info: Preparing to generate the ALSA configuration file Jun 02 00:06:12 volumio volumio[1105]: info: Volumio Network Manager: Network status updated: 0 Jun 02 00:06:12 volumio volumio[1105]: info: Cannot mount NAS AsusDesktop at system boot, trial number 1 ,retrying in 5 seconds Jun 02 00:06:12 volumio volumio[1105]: info: Cannot mount NAS HPMicroServer10 at system boot, trial number 1 ,retrying in 5 seconds Jun 02 00:06:12 volumio volumio[1105]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 02 00:06:12 volumio volumio[1105]: verbose: New Socket.io Connection to 192.168.1.67 from 192.168.1.4 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 02 00:06:12 volumio volumio[1105]: verbose: New Socket.io Connection to 192.168.1.67 from 192.168.1.4 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 02 00:06:12 volumio volumio[1105]: verbose: New Socket.io Connection to 192.168.1.67 from 192.168.1.4 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 02 00:06:12 volumio volumio[1105]: verbose: New Socket.io Connection to 192.168.1.67 from 192.168.1.4 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 02 00:06:12 volumio sudo[1570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 00:06:12 volumio sudo[1570]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:12 volumio sudo[1570]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:12 volumio volumio[1105]: info: Asound.conf file unchanged, so no further update is needed Jun 02 00:06:12 volumio volumio[1105]: info: Output device has changed, restarting MPD Jun 02 00:06:12 volumio sudo[1573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 00:06:13 volumio sudo[1573]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:13 volumio sudo[1573]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:13 volumio sudo[1576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 00:06:13 volumio sudo[1576]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:13 volumio sudo[1576]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:13 volumio volumio[1105]: info: Output device has changed, restarting Shairport Sync Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:13 volumio sudo[1579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 00:06:13 volumio sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:13 volumio systemd[1]: musicservicesshield.service: Succeeded. Jun 02 00:06:13 volumio volumio-remote-updater[783]: [2025-06-02 00:06:13] [connect] Successful connection Jun 02 00:06:13 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jun 02 00:06:13 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jun 02 00:06:13 volumio systemd[1]: Stopping Music Player Daemon... Jun 02 00:06:13 volumio systemd[1]: mpd.service: Succeeded. Jun 02 00:06:13 volumio systemd[1]: Stopped Music Player Daemon. Jun 02 00:06:13 volumio volumio[1105]: Home directory not accessible: Permission denied Jun 02 00:06:13 volumio volumio[1105]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 00:06:13 volumio volumio[1105]: Home directory not accessible: Permission denied Jun 02 00:06:13 volumio volumio[1105]: info: ___________ START PLUGINS ___________ Jun 02 00:06:13 volumio systemd[1]: Starting Music Player Daemon... Jun 02 00:06:13 volumio volumio[1105]: info: ControllerMpd::onStart: Initializing MPD Jun 02 00:06:13 volumio volumio[1105]: info: Creating MPD Configuration file Jun 02 00:06:13 volumio sudo[1586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 00:06:13 volumio sudo[1586]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:13 volumio sudo[1584]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 02 00:06:13 volumio sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:13 volumio sudo[1586]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:13 volumio sudo[1584]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 00:06:13 volumio volumio[1105]: info: [1748811973273] CoreMusicLibrary::Adding element Media Servers Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 00:06:13 volumio sudo[1591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:13 volumio sudo[1591]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:13 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 02 00:06:13 volumio systemd[1]: mpd.service: Succeeded. Jun 02 00:06:13 volumio systemd[1]: Stopped Music Player Daemon. Jun 02 00:06:13 volumio systemd[1]: Starting Music Player Daemon... Jun 02 00:06:13 volumio volumio[1105]: Home directory not accessible: Permission denied Jun 02 00:06:13 volumio volumio[1105]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 00:06:13 volumio volumio[1105]: Home directory not accessible: Permission denied Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:13 volumio volumio[1105]: Home directory not accessible: Permission denied Jun 02 00:06:13 volumio volumio[1105]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 00:06:13 volumio volumio[1105]: Home directory not accessible: Permission denied Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 00:06:13 volumio sudo[1594]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 02 00:06:13 volumio volumio[1105]: info: [1748811973458] CoreMusicLibrary::Adding element Last_100 Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 00:06:13 volumio volumio[1105]: info: [1748811973460] CoreMusicLibrary::Adding element Webradio Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 00:06:13 volumio sudo[1594]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:13 volumio volumio[1105]: info: Initializing BBC Radios Jun 02 00:06:13 volumio sudo[1594]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 00:06:13 volumio volumio[1105]: info: [1748811973634] CoreMusicLibrary::Adding element Bandcamp Discover Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source Bandcamp Discover Jun 02 00:06:13 volumio volumio[1105]: info: Adding Calm Radio to Browse Sources Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 00:06:13 volumio volumio[1105]: info: [1748811973642] CoreMusicLibrary::Adding element Calm Radio Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source Bandcamp Discover Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source Calm Radio Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 00:06:13 volumio volumio[1105]: info: [1748811973647] CoreMusicLibrary::Adding element YouTube2 Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source Bandcamp Discover Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source Calm Radio Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source YouTube2 Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 00:06:13 volumio volumio[1105]: info: [1748811973690] CoreMusicLibrary::Adding element YouTube Music Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source Bandcamp Discover Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source Calm Radio Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source YouTube2 Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source YouTube Music Jun 02 00:06:13 volumio volumio[1105]: info: [now-playing] ConfigUpdater: config is up to date. Jun 02 00:06:13 volumio volumio[1105]: info: Loading i18n strings for locale ua Jun 02 00:06:13 volumio sudo[1613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jun 02 00:06:13 volumio volumio[1105]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jun 02 00:06:13 volumio sudo[1613]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:13 volumio systemd[1]: Started RoonBridge. Jun 02 00:06:13 volumio sudo[1613]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 00:06:13 volumio volumio[1105]: info: [1748811973878] CoreMusicLibrary::Adding element Mother Earth Radio Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source Bandcamp Discover Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source Calm Radio Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source YouTube2 Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source YouTube Music Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source Mother Earth Radio Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 00:06:13 volumio volumio[1105]: info: [1748811973896] CoreMusicLibrary::Adding element Radio Paradise Jun 02 00:06:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source Bandcamp Discover Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source Calm Radio Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source YouTube2 Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source YouTube Music Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source Mother Earth Radio Jun 02 00:06:13 volumio volumio[1105]: Cannot find translation for source Radio Paradise Jun 02 00:06:14 volumio volumio[1105]: info: Volumio Calling Home Jun 02 00:06:14 volumio mpd[1601]: Jun 02 00:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 02 00:06:14 volumio systemd[1]: Started Music Player Daemon. Jun 02 00:06:14 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jun 02 00:06:14 volumio sudo[1579]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:14 volumio sudo[1591]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:14 volumio sudo[1653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Jun 02 00:06:14 volumio sudo[1653]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:14 volumio RoonBridge[1615]: 00:00:00.015 Warn: get lock file path: /tmp/.rnbgem0- Jun 02 00:06:15 volumio volumio[1105]: info: [now-playing] App is listening on port 4004. Jun 02 00:06:15 volumio volumio[1105]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jun 02 00:06:15 volumio volumio[1657]: cset: --> shielding system active with Jun 02 00:06:15 volumio volumio[1657]: cset: "system" cpuset of CPUSPEC(1) with 183 tasks running Jun 02 00:06:15 volumio volumio[1657]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jun 02 00:06:15 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jun 02 00:06:15 volumio volumio[1105]: info: CoreStateMachine::setRepeat null single undefined Jun 02 00:06:15 volumio volumio[1105]: info: CoreStateMachine::pushState Jun 02 00:06:15 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:15 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Jun 02 00:06:15 volumio volumio[1105]: info: CoreStateMachine::setRandom null Jun 02 00:06:15 volumio volumio[1105]: info: CoreStateMachine::pushState Jun 02 00:06:15 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:15 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Jun 02 00:06:15 volumio RoonBridge[1615]: 00:00:00.488 Trace: [childprocess] using unix child process Jun 02 00:06:15 volumio RoonBridge[1615]: 00:00:00.543 Debug: PathForResource, filename: ../.update Jun 02 00:06:15 volumio volumio[1105]: info: Reloading queue from file Jun 02 00:06:15 volumio volumio[1105]: verbose: New Socket.io Connection to 192.168.1.67 from 192.168.1.4 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 02 00:06:15 volumio RoonBridge[1615]: 00:00:00.569 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Jun 02 00:06:15 volumio RoonBridge[1615]: Initializing Jun 02 00:06:15 volumio RoonBridge[1615]: 00:00:00.581 Debug: PathForResource, filename: RAATServer Jun 02 00:06:15 volumio RoonBridge[1615]: 00:00:00.581 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jun 02 00:06:15 volumio RoonBridge[1615]: 00:00:00.582 Debug: PathForResource, filename: RoonBridgeHelper Jun 02 00:06:15 volumio RoonBridge[1615]: 00:00:00.582 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Jun 02 00:06:15 volumio volumio[1105]: info: minidlna: Found miniDLNA version 1.2.1 Jun 02 00:06:15 volumio volumio[1105]: info: minidlna: Starting minidlna.service Jun 02 00:06:15 volumio RoonBridge[1615]: 00:00:00.637 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jun 02 00:06:15 volumio sudo[1692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Jun 02 00:06:15 volumio sudo[1692]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:15 volumio volumio[1105]: info: MPD Permissions set Jun 02 00:06:15 volumio sudo[1692]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:15 volumio volumio[1105]: info: MPD Permissions set Jun 02 00:06:15 volumio volumio-remote-updater[783]: [2025-06-02 00:06:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748811973 101 Jun 02 00:06:15 volumio volumio[1105]: 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: 6 Jun 02 00:06:15 volumio volumio[1105]: info: MPD running with PID1601 Jun 02 00:06:15 volumio volumio[1105]: ,establishing connection Jun 02 00:06:15 volumio volumio[1105]: info: minidlna: systemctl start minidlna.service succeeded. Jun 02 00:06:15 volumio volumio[1105]: info: Volumio called home Jun 02 00:06:15 volumio sudo[1653]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:15 volumio RoonBridge[1615]: Not Running (o.) Jun 02 00:06:15 volumio RoonBridge[1615]: 00:00:01.105 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Jun 02 00:06:15 volumio volumio[1105]: error: MPD error: The expression evaluated to a falsy value: Jun 02 00:06:15 volumio volumio[1105]: assert.ok(self.idling) Jun 02 00:06:15 volumio volumio[1105]: error: The expression evaluated to a falsy value: Jun 02 00:06:15 volumio volumio[1105]: assert.ok(self.idling) Jun 02 00:06:15 volumio volumio[1105]: error: MPD error: The expression evaluated to a falsy value: Jun 02 00:06:15 volumio volumio[1105]: assert.ok(self.idling) Jun 02 00:06:15 volumio volumio[1105]: error: The expression evaluated to a falsy value: Jun 02 00:06:15 volumio volumio[1105]: assert.ok(self.idling) Jun 02 00:06:15 volumio volumio[1105]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 02 00:06:16 volumio RoonBridge[1615]: Running Jun 02 00:06:16 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:16 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:16 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:16 volumio sudo[1715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Jun 02 00:06:16 volumio sudo[1715]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:16 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:16 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 02 00:06:16 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:16 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:16 volumio systemd[1]: Reloading. Jun 02 00:06:16 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:16 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 02 00:06:16 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:16 volumio volumio[1105]: info: Discovery: adding acad17b3-24f4-442f-b656-7fa79d67a84b Jun 02 00:06:16 volumio volumio[1105]: info: Discovery: Found device Volumio Jun 02 00:06:16 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Jun 02 00:06:16 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:16 volumio volumio[1105]: error: updateQueue error: null Jun 02 00:06:16 volumio volumio[1105]: info: Starting Shairport Sync Jun 02 00:06:16 volumio sudo[1751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 02 00:06:16 volumio sudo[1751]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:16 volumio volumio[1105]: info: Starting Shairport Sync Jun 02 00:06:17 volumio sudo[1754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 02 00:06:17 volumio sudo[1754]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:17 volumio volumio[1105]: info: Starting Shairport Sync Jun 02 00:06:17 volumio sudo[1757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 02 00:06:17 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 02 00:06:17 volumio systemd[1]: Configuration file /etc/systemd/system/twonky.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jun 02 00:06:17 volumio systemd[1]: Configuration file /etc/systemd/system/twonky.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Jun 02 00:06:17 volumio sudo[1757]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:17 volumio systemd[1]: /etc/systemd/system/minidlna.service:14: Failed to parse service restart specifier, ignoring: always # added for autorestart Jun 02 00:06:17 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Jun 02 00:06:17 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:17 volumio sudo[1760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 02 00:06:17 volumio sudo[1760]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:17 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jun 02 00:06:17 volumio sudo[1715]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:17 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 02 00:06:17 volumio sudo[1751]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 02 00:06:17 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 02 00:06:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 02 00:06:17 volumio volumio[1105]: info: succeeded Jun 02 00:06:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 02 00:06:17 volumio sudo[1768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=ASAdmin,password=AS2020,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.4/_Music /mnt/NAS/AsusDesktop Jun 02 00:06:17 volumio sudo[1768]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:17 volumio sudo[1754]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:17 volumio sudo[1760]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:17 volumio sudo[1757]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:18 volumio kernel: CIFS: Attempting to mount //192.168.1.4/_Music Jun 02 00:06:18 volumio sudo[1768]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:18 volumio sudo[1779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=HPAdmin,password=HP2020,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.44/e\_Music /mnt/NAS/HPMicroServer10 Jun 02 00:06:18 volumio sudo[1779]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:18 volumio volumio[1105]: info: Shairport-Sync Started Jun 02 00:06:18 volumio volumio[1105]: Error adding Membership: Error: addMembership EINVAL Jun 02 00:06:18 volumio kernel: CIFS: Attempting to mount //192.168.1.44/e/_Music Jun 02 00:06:18 volumio volumio[1105]: info: Shairport-Sync Started Jun 02 00:06:18 volumio volumio[1105]: info: Shairport-Sync Started Jun 02 00:06:18 volumio volumio[1105]: info: Upmpdcli Daemon Started Jun 02 00:06:18 volumio volumio[1105]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jun 02 00:06:18 volumio volumio[1105]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 00:06:18 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Jun 02 00:06:18 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:18 volumio volumio[1105]: info: CoreStateMachine::pushState Jun 02 00:06:18 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:18 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 00:06:18 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Jun 02 00:06:18 volumio volumio[1767]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 02 00:06:18 volumio volumio[1105]: error: [ytcr] VolumeControl failed to obtain volume from Volumio: Jun 02 00:06:18 volumio volumio[1105]: (TypeError) Cannot read property 'vol' of undefined Jun 02 00:06:18 volumio volumio[1105]: TypeError: Cannot read property 'vol' of undefined Jun 02 00:06:18 volumio volumio[1105]: at VolumeControl.getVolume (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:56:42) Jun 02 00:06:18 volumio volumio[1105]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Jun 02 00:06:18 volumio volumio[1105]: at async VolumeControl.init (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:28:68) Jun 02 00:06:18 volumio volumio[1105]: at async /data/plugins/music_service/ytcr/dist/index.js:326:13 Jun 02 00:06:18 volumio volumio[1105]: info: Completed starting Core Plugins Jun 02 00:06:18 volumio volumio[1105]: info: ------------------------------------------- Jun 02 00:06:18 volumio volumio[1105]: info: ----- MyVolumio plugins startup ---- Jun 02 00:06:18 volumio volumio[1105]: info: ------------------------------------------- Jun 02 00:06:18 volumio volumio[1105]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 02 00:06:18 volumio RoonBridge[1615]: 00:00:00.114 Warn: get lock file path: /tmp/.rnbhgem0- Jun 02 00:06:19 volumio volumio[1105]: verbose: New Socket.io Connection to 192.168.1.67 from 192.168.1.4 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 02 00:06:19 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 02 00:06:19 volumio volumio[1105]: info: CoreCommandRouter::volumioGetVisibleSources Jun 02 00:06:19 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 00:06:19 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Jun 02 00:06:19 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:19 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Jun 02 00:06:19 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:19 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 02 00:06:19 volumio volumio[1105]: info: CoreCommandRouter::volumioGetQueue Jun 02 00:06:19 volumio volumio[1105]: info: CoreStateMachine::getQueue Jun 02 00:06:19 volumio volumio[1105]: info: CorePlayQueue::getQueue Jun 02 00:06:19 volumio volumio[1105]: info: Listing playlists Jun 02 00:06:19 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 02 00:06:19 volumio volumio[1105]: info: Received Get System Info Jun 02 00:06:19 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 00:06:19 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 00:06:19 volumio volumio[1105]: info: Discovery: Getting this device information Jun 02 00:06:19 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Jun 02 00:06:19 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:19 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 00:06:19 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Jun 02 00:06:19 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:20 volumio volumio[1105]: error: error Jun 02 00:06:20 volumio volumio[1105]: error: error Jun 02 00:06:21 volumio volumio[1767]: .............................................................................................++++ Jun 02 00:06:22 volumio volumio[1105]: 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: 9 Jun 02 00:06:22 volumio volumio[1105]: info: Volumio Network Manager: Network status updated: 1 Jun 02 00:06:22 volumio volumio[1767]: ....................................................++++ Jun 02 00:06:22 volumio volumio[1767]: e is 65537 (0x010001) Jun 02 00:06:22 volumio volumio[1767]: writing RSA key Jun 02 00:06:23 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 02 00:06:24 volumio sudo[1779]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:24 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 02 00:06:24 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jun 02 00:06:24 volumio volumio[1105]: info: Cannot mount NAS HPMicroServer10 at system boot, trial number 2 ,retrying in 5 seconds Jun 02 00:06:25 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jun 02 00:06:25 volumio volumio[1105]: 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: 10 Jun 02 00:06:25 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 02 00:06:26 volumio volumio[1105]: info: CoreCommandRouter::volumioGetVisibleSources Jun 02 00:06:26 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 00:06:26 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Jun 02 00:06:26 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:26 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 02 00:06:26 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 02 00:06:26 volumio volumio[1105]: info: Received Get System Info Jun 02 00:06:26 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 00:06:26 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 00:06:26 volumio volumio[1105]: info: Discovery: Getting this device information Jun 02 00:06:26 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Jun 02 00:06:26 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:26 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 00:06:26 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Jun 02 00:06:26 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:26 volumio volumio[1105]: info: Listing playlists Jun 02 00:06:26 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 02 00:06:26 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 02 00:06:26 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Jun 02 00:06:26 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:26 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 00:06:26 volumio volumio[1105]: info: Received Get System Info Jun 02 00:06:26 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 00:06:26 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 00:06:26 volumio volumio[1105]: info: Discovery: Getting this device information Jun 02 00:06:26 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Jun 02 00:06:26 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:26 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 02 00:06:27 volumio volumio[1105]: info: Adding plugin bluetooth to MyMusic Plugins Jun 02 00:06:27 volumio volumio[1105]: info: Adding plugin multiroom to MyMusic Plugins Jun 02 00:06:27 volumio volumio[1105]: info: Adding plugin metavolumio to MyMusic Plugins Jun 02 00:06:27 volumio volumio[1105]: info: Adding plugin cd_controller to MyMusic Plugins Jun 02 00:06:27 volumio volumio[1105]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 02 00:06:27 volumio volumio[1105]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 02 00:06:27 volumio volumio[1105]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 02 00:06:27 volumio volumio[1105]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 02 00:06:27 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:27 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:28 volumio volumio[1105]: info: Starting MyVolumio Remote Streaming Endpoints Jun 02 00:06:28 volumio volumio[1105]: info: MyVolumio login type: Token Jun 02 00:06:28 volumio volumio[1105]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 02 00:06:28 volumio volumio[1105]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 02 00:06:28 volumio volumio[1105]: info: Starting Streaming Service Transparent Proxy Jun 02 00:06:28 volumio volumio[1105]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 02 00:06:28 volumio volumio[1105]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 02 00:06:28 volumio volumio[1105]: info: Streaming services startup Jun 02 00:06:28 volumio volumio[1105]: info: Starting Streaming Daemon Jun 02 00:06:28 volumio sudo[2047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 02 00:06:28 volumio sudo[2047]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:28 volumio sudo[2047]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:28 volumio volumio[1105]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 02 00:06:28 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 02 00:06:28 volumio volumio[1105]: error: Cannot start Volumio Streaming Daemon Jun 02 00:06:28 volumio volumio[1105]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 02 00:06:28 volumio volumio[1105]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 02 00:06:29 volumio volumio[1105]: STREAMING PROXY: Starting server on port 3245 Jun 02 00:06:29 volumio volumio[1105]: Node JS runtime: 14 Jun 02 00:06:29 volumio volumio[1105]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 02 00:06:29 volumio sudo[2054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=HPAdmin,password=HP2020,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.44/e\_Music /mnt/NAS/HPMicroServer10 Jun 02 00:06:29 volumio sudo[2054]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:29 volumio kernel: CIFS: Attempting to mount //192.168.1.44/e/_Music Jun 02 00:06:29 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 00:06:29 volumio volumio[1105]: info: Received Get System Info Jun 02 00:06:29 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 00:06:29 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 00:06:29 volumio volumio[1105]: info: Discovery: Getting this device information Jun 02 00:06:29 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Jun 02 00:06:29 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:29 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 00:06:30 volumio volumio[1105]: info: MyVolumio token set successfully Jun 02 00:06:30 volumio volumio[1105]: info: MYVOLUMIO: Adding device Jun 02 00:06:30 volumio volumio[1105]: info: MYVOLUMIO: Evaluating Server Jun 02 00:06:31 volumio volumio[1105]: info: MyVolumio status changed Jun 02 00:06:31 volumio volumio[1105]: info: Streaming services startup Jun 02 00:06:31 volumio volumio[1105]: info: Starting Streaming Daemon Jun 02 00:06:31 volumio volumio[1105]: info: Removing browser output: myVolumio user plan is not superstar Jun 02 00:06:31 volumio volumio[1105]: info: Removing audio output: Jun 02 00:06:31 volumio volumio[1105]: info: Stoppping Tunnel 1 Jun 02 00:06:31 volumio sudo[2076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 02 00:06:31 volumio sudo[2076]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:31 volumio sudo[2076]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:31 volumio volumio[1105]: error: Cannot start Volumio Streaming Daemon Jun 02 00:06:31 volumio volumio[1105]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 02 00:06:31 volumio volumio[1105]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 02 00:06:31 volumio sudo[2079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 02 00:06:31 volumio sudo[2079]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:31 volumio sudo[2079]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:31 volumio volumio[1105]: info: Remote SSH Stopped Jun 02 00:06:31 volumio volumio[1105]: info: Setting Geolocation for MyVolumio to eu7 Jun 02 00:06:31 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:31 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:31 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:31 volumio volumio[1105]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 02 00:06:32 volumio volumio[1105]: info: Updating MyVolumio device info Jun 02 00:06:32 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:32 volumio volumio[1105]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 02 00:06:35 volumio systemd[1]: systemd-timedated.service: Succeeded. Jun 02 00:06:35 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 02 00:06:35 volumio sudo[2054]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:35 volumio volumio[1105]: info: Cannot mount NAS HPMicroServer10 at system boot, trial number 3 ,retrying in 5 seconds Jun 02 00:06:35 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 02 00:06:35 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jun 02 00:06:35 volumio volumio[1105]: info: MYVOLUMIO: Adding device Jun 02 00:06:35 volumio volumio[1105]: info: MYVOLUMIO: Evaluating Server Jun 02 00:06:36 volumio volumio[1105]: info: Setting Geolocation for MyVolumio to eu6 Jun 02 00:06:36 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:36 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:36 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:36 volumio volumio[1105]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 02 00:06:37 volumio volumio[1105]: info: Updating MyVolumio device info Jun 02 00:06:37 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:37 volumio volumio[1105]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 02 00:06:40 volumio sudo[2119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=HPAdmin,password=HP2020,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.44/e\_Music /mnt/NAS/HPMicroServer10 Jun 02 00:06:40 volumio sudo[2119]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:40 volumio kernel: CIFS: Attempting to mount //192.168.1.44/e/_Music Jun 02 00:06:42 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 02 00:06:42 volumio volumio[1105]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 02 00:06:42 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 02 00:06:42 volumio volumio[1105]: info: Received Get System Version Jun 02 00:06:42 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 00:06:42 volumio volumio[1105]: info: Received Get System Info Jun 02 00:06:42 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 00:06:42 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 00:06:42 volumio volumio[1105]: info: Discovery: Getting this device information Jun 02 00:06:42 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Jun 02 00:06:42 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:42 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 00:06:43 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 02 00:06:43 volumio volumio[1105]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 02 00:06:43 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Jun 02 00:06:43 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Jun 02 00:06:43 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 00:06:43 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:43 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 02 00:06:43 volumio volumio-remote-updater[783]: No test mode Jun 02 00:06:43 volumio volumio-remote-updater[783]: No alpha test mode Jun 02 00:06:43 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 02 00:06:44 volumio volumio[1105]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 02 00:06:44 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 02 00:06:46 volumio sudo[2119]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:46 volumio volumio[1105]: info: Cannot mount NAS HPMicroServer10 at system boot, trial number 4 ,retrying in 5 seconds Jun 02 00:06:46 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 02 00:06:46 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jun 02 00:06:48 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:48 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 02 00:06:48 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 02 00:06:48 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 02 00:06:48 volumio volumio[1105]: info: CoreCommandRouter::volumioGetBrowseSources Jun 02 00:06:48 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 00:06:48 volumio volumio[1105]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 02 00:06:48 volumio volumio[1105]: info: Completed starting MyVolumio Plugin Jun 02 00:06:48 volumio volumio[1105]: [Metrics] CommandRouter: 48s 854.18ms Jun 02 00:06:48 volumio volumio[1105]: info: CoreCommandRouter::volumiosetStartupVolume Jun 02 00:06:48 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 00:06:48 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 00:06:48 volumio volumio[1105]: info: CoreCommandRouter::Close All Modals sent Jun 02 00:06:48 volumio volumio[1105]: info: CoreCommandRouter::Close All Modals sent Jun 02 00:06:49 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 02 00:06:49 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 00:06:49 volumio volumio[1105]: Home directory not accessible: Permission denied Jun 02 00:06:49 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 02 00:06:51 volumio sudo[2145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=HPAdmin,password=HP2020,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.44/e\_Music /mnt/NAS/HPMicroServer10 Jun 02 00:06:51 volumio sudo[2145]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 00:06:51 volumio kernel: CIFS: Attempting to mount //192.168.1.44/e/_Music Jun 02 00:06:55 volumio volumio[1105]: info: BOOT COMPLETED Jun 02 00:06:57 volumio sudo[2145]: pam_unix(sudo:session): session closed for user root Jun 02 00:06:57 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 02 00:06:57 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jun 02 00:06:57 volumio volumio[1105]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jun 02 00:06:58 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 02 00:06:58 volumio volumio[1105]: info: Preload queue cleared Jun 02 00:07:00 volumio ntpd[1064]: Soliciting pool server 62.205.159.155 Jun 02 00:07:01 volumio ntpd[1064]: Soliciting pool server 194.54.161.215 Jun 02 00:07:02 volumio ntpd[1064]: Soliciting pool server 62.149.0.30 Jun 02 00:07:03 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 02 00:07:03 volumio ntpd[1064]: Soliciting pool server 193.84.22.254 Jun 02 00:07:03 volumio ntpd[1064]: Soliciting pool server 109.110.82.19 Jun 02 00:07:03 volumio ntpd[1064]: Soliciting pool server 91.236.251.234 Jun 02 00:07:03 volumio volumio[1105]: info: Preload queue cleared Jun 02 00:07:04 volumio ntpd[1064]: Soliciting pool server 193.106.144.7 Jun 02 00:07:05 volumio ntpd[1064]: Soliciting pool server 94.158.46.150 Jun 02 00:07:08 volumio volumio[1105]: error: error Jun 02 00:07:08 volumio volumio[1105]: error: error Jun 02 00:07:09 volumio volumio[1105]: error: error Jun 02 00:07:09 volumio volumio[1105]: error: error Jun 02 00:07:09 volumio volumio[1105]: error: error Jun 02 00:07:10 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 02 00:07:10 volumio volumio[1105]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 00:07:10 volumio volumio[1105]: TypeError: Cannot read property 'length' of undefined Jun 02 00:07:10 volumio volumio[1105]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 02 00:07:10 volumio volumio[1105]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 02 00:07:10 volumio volumio[1105]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 02 00:07:10 volumio volumio[1105]: at Parser.emit (events.js:400:28) Jun 02 00:07:10 volumio volumio[1105]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 02 00:07:10 volumio volumio[1105]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 02 00:07:10 volumio volumio[1105]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 02 00:07:10 volumio volumio[1105]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 02 00:07:10 volumio volumio[1105]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 02 00:07:10 volumio volumio[1105]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 02 00:07:10 volumio volumio[1105]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 02 00:07:10 volumio volumio[1105]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 02 00:07:10 volumio volumio[1105]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 02 00:07:10 volumio volumio[1105]: at IncomingMessage.emit (events.js:412:35) Jun 02 00:07:10 volumio volumio[1105]: at endReadableNT (internal/streams/readable.js:1333:12) Jun 02 00:07:10 volumio volumio[1105]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jun 02 00:07:10 volumio volumio[1105]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 00:07:10 volumio sudo[2240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-02 00:06 Jun 02 00:07:10 volumio sudo[2240]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"