-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2025-06-27 22:56:48 CEST. -- Jun 27 22:55:00 volumio-pi3 volumio-time-update[590]: volumio-time-update-util: Setting system time to: 2025-06-27 22:55:24 Jun 27 22:55:00 volumio-pi3 sudo[903]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-27 22:55:24 Jun 27 22:55:00 volumio-pi3 sudo[903]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:00 volumio-pi3 bthelper[708]: [CHG] Controller B8:27:EB:F7:FD:74 Class: 0x0000041c Jun 27 22:55:00 volumio-pi3 bthelper[708]: Changing power on succeeded Jun 27 22:55:00 volumio-pi3 bthelper[708]: [CHG] Controller B8:27:EB:F7:FD:74 Powered: yes Jun 27 22:55:00 volumio-pi3 dbus-daemon[598]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=907 comm="timedatectl set-time 2025-06-27 22:55:24 ") Jun 27 22:55:00 volumio-pi3 systemd[1]: Starting Time & Date Service... Jun 27 22:55:00 volumio-pi3 dbus-daemon[598]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 27 22:55:00 volumio-pi3 systemd[1]: Started Time & Date Service. Jun 27 22:55:24 volumio-pi3 systemd-timedated[908]: Changed local time to Fri Jun 27 22:55:24 2025 Jun 27 22:55:24 volumio-pi3 sudo[903]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:24 volumio-pi3 volumio-time-update[590]: volumio-time-update-util: System time updated successfully. Jun 27 22:55:24 volumio-pi3 systemd[1]: Started Volumio Time Update Utility. Jun 27 22:55:24 volumio-pi3 ntpd[863]: Soliciting pool server 185.252.140.125 Jun 27 22:55:24 volumio-pi3 mpd[792]: Jun 27 22:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 27 22:55:24 volumio-pi3 ntpd[863]: Soliciting pool server 141.144.246.224 Jun 27 22:55:25 volumio-pi3 ntpd[863]: Soliciting pool server 45.157.234.88 Jun 27 22:55:25 volumio-pi3 systemd[1]: Started Music Player Daemon. Jun 27 22:55:25 volumio-pi3 mpd_monitor.sh[595]: MPD Monitor Service: MPD restarted due to no mpc output. Jun 27 22:55:25 volumio-pi3 ntpd[863]: Soliciting pool server 91.202.42.83 Jun 27 22:55:25 volumio-pi3 ntpd[863]: Soliciting pool server 162.159.200.1 Jun 27 22:55:26 volumio-pi3 ntpd[863]: Soliciting pool server 217.91.44.17 Jun 27 22:55:26 volumio-pi3 nmbd[709]: [2025/06/27 22:55:26.191961, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 27 22:55:26 volumio-pi3 nmbd[709]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 27 22:55:26 volumio-pi3 systemd[1]: Started Samba NMB Daemon. Jun 27 22:55:26 volumio-pi3 systemd[1]: Starting Samba Winbind Daemon... Jun 27 22:55:26 volumio-pi3 winbindd[912]: [2025/06/27 22:55:26.587604, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 27 22:55:26 volumio-pi3 winbindd[912]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 27 22:55:26 volumio-pi3 winbindd[912]: [2025/06/27 22:55:26.651332, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 27 22:55:26 volumio-pi3 winbindd[912]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 27 22:55:26 volumio-pi3 systemd[1]: Started Samba Winbind Daemon. Jun 27 22:55:26 volumio-pi3 systemd[1]: Starting Samba SMB Daemon... Jun 27 22:55:26 volumio-pi3 ntpd[863]: Soliciting pool server 128.140.109.119 Jun 27 22:55:26 volumio-pi3 ntpd[863]: Soliciting pool server 85.214.83.151 Jun 27 22:55:26 volumio-pi3 ntpd[863]: Soliciting pool server 185.194.239.35 Jun 27 22:55:27 volumio-pi3 ntpd[863]: Soliciting pool server 152.53.15.80 Jun 27 22:55:27 volumio-pi3 volumio[843]: info: ------------------------------------------- Jun 27 22:55:27 volumio-pi3 volumio[843]: info: ----- Volumio3 ---- Jun 27 22:55:27 volumio-pi3 volumio[843]: info: ------------------------------------------- Jun 27 22:55:27 volumio-pi3 volumio[843]: info: ----- System startup ---- Jun 27 22:55:27 volumio-pi3 volumio[843]: info: ------------------------------------------- Jun 27 22:55:27 volumio-pi3 smbd[925]: [2025/06/27 22:55:27.545897, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 27 22:55:27 volumio-pi3 systemd[1]: Started Samba SMB Daemon. Jun 27 22:55:27 volumio-pi3 smbd[925]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 27 22:55:27 volumio-pi3 systemd[1]: Reached target Multi-User System. Jun 27 22:55:27 volumio-pi3 systemd[1]: Reached target Graphical Interface. Jun 27 22:55:27 volumio-pi3 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 27 22:55:27 volumio-pi3 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 27 22:55:27 volumio-pi3 systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 27 22:55:27 volumio-pi3 systemd[1]: Startup finished in 14.336s (kernel) + 18.158s (userspace) = 32.494s. Jun 27 22:55:27 volumio-pi3 ntpd[863]: Soliciting pool server 130.162.220.39 Jun 27 22:55:27 volumio-pi3 ntpd[863]: Soliciting pool server 185.255.121.15 Jun 27 22:55:27 volumio-pi3 ntpd[863]: Soliciting pool server 49.13.14.46 Jun 27 22:55:27 volumio-pi3 ntpd[863]: Soliciting pool server 217.160.19.219 Jun 27 22:55:28 volumio-pi3 volumio[843]: info: MYVOLUMIO Environment detected Jun 27 22:55:28 volumio-pi3 volumio[843]: info: Plugin folders cleanup Jun 27 22:55:28 volumio-pi3 volumio[843]: info: Scanning into folder /volumio/app/plugins/ Jun 27 22:55:28 volumio-pi3 volumio[843]: info: Scanning category audio_interface Jun 27 22:55:28 volumio-pi3 volumio[843]: info: Scanning category miscellanea Jun 27 22:55:28 volumio-pi3 volumio[843]: info: Scanning category music_service Jun 27 22:55:28 volumio-pi3 volumio[843]: info: Scanning category plugins.json Jun 27 22:55:28 volumio-pi3 volumio[843]: info: Scanning category system_controller Jun 27 22:55:28 volumio-pi3 volumio[843]: info: Scanning category user_interface Jun 27 22:55:28 volumio-pi3 volumio[843]: info: Scanning into folder /data/plugins/ Jun 27 22:55:28 volumio-pi3 volumio[843]: info: Plugin folders cleanup completed Jun 27 22:55:28 volumio-pi3 volumio[843]: info: ------------------------------------------- Jun 27 22:55:28 volumio-pi3 volumio[843]: info: ----- Core plugins startup ---- Jun 27 22:55:28 volumio-pi3 volumio[843]: info: ------------------------------------------- Jun 27 22:55:28 volumio-pi3 volumio[843]: info: Loading plugins from folder /volumio/app/plugins/ Jun 27 22:55:28 volumio-pi3 volumio[843]: info: Adding plugin upnp to MyMusic Plugins Jun 27 22:55:28 volumio-pi3 volumio[843]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 27 22:55:28 volumio-pi3 volumio[843]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 27 22:55:28 volumio-pi3 volumio[843]: info: Loading plugins from folder /data/plugins/ Jun 27 22:55:28 volumio-pi3 volumio[843]: info: Loading plugin "system"... Jun 27 22:55:28 volumio-pi3 volumio[843]: info: Loading plugin "appearance"... Jun 27 22:55:28 volumio-pi3 ntpd[863]: Soliciting pool server 93.241.86.156 Jun 27 22:55:28 volumio-pi3 ntpd[863]: Soliciting pool server 5.75.181.179 Jun 27 22:55:28 volumio-pi3 ntpd[863]: Soliciting pool server 129.70.132.36 Jun 27 22:55:30 volumio-pi3 volumio[843]: info: Loading plugin "network"... Jun 27 22:55:30 volumio-pi3 volumio[843]: info: Refreshing Cached IP Addresses Jun 27 22:55:30 volumio-pi3 volumio-remote-updater[567]: [2025-06-27 22:55:30] [connect] Successful connection Jun 27 22:55:30 volumio-pi3 volumio[843]: info: Loading plugin "services"... Jun 27 22:55:30 volumio-pi3 volumio[843]: info: Loading plugin "alsa_controller"... Jun 27 22:55:30 volumio-pi3 sudo[940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 27 22:55:30 volumio-pi3 sudo[942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 22:55:30 volumio-pi3 sudo[940]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:30 volumio-pi3 sudo[942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:30 volumio-pi3 sudo[942]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:30 volumio-pi3 sudo[940]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:30 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 22:55:30 volumio-pi3 volumio[843]: info: Loading plugin "wizard"... Jun 27 22:55:30 volumio-pi3 volumio[843]: info: Loading plugin "networkfs"... Jun 27 22:55:30 volumio-pi3 volumio[843]: info: Starting Udev Watcher for removable devices Jun 27 22:55:30 volumio-pi3 sudo[965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.1 //192.168.1.253/Musik /mnt/NAS/Musik Jun 27 22:55:30 volumio-pi3 sudo[965]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:30 volumio-pi3 sudo[967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=metzler,password=U5jWjfAUehlHT8VNOk8I,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.1/fritz.nas/Data/Musik /mnt/NAS/Musik Jun 27 22:55:30 volumio-pi3 sudo[967]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:30 volumio-pi3 volumio[843]: info: Ignoring mount for partition: boot Jun 27 22:55:30 volumio-pi3 volumio[843]: info: Ignoring mount for partition: volumio Jun 27 22:55:30 volumio-pi3 volumio[843]: info: Ignoring mount for partition: volumio_data Jun 27 22:55:30 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 22:55:30 volumio-pi3 volumio[843]: info: Loading plugin "volumio_command_line_client"... Jun 27 22:55:30 volumio-pi3 volumio[843]: info: Loading plugin "upnp"... Jun 27 22:55:31 volumio-pi3 volumio[843]: info: [1751057731026] Starting Upmpd Daemon Jun 27 22:55:31 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 22:55:31 volumio-pi3 volumio[843]: info: Loading plugin "my_music"... Jun 27 22:55:31 volumio-pi3 volumio[843]: info: Loading plugin "mpd"... Jun 27 22:55:31 volumio-pi3 kernel: Key type cifs.spnego registered Jun 27 22:55:31 volumio-pi3 kernel: Key type cifs.idmap registered Jun 27 22:55:31 volumio-pi3 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 27 22:55:31 volumio-pi3 kernel: CIFS: Attempting to mount //192.168.1.1/fritz.nas/Data/Musik Jun 27 22:55:31 volumio-pi3 kernel: cryptd: max_cpu_qlen set to 1000 Jun 27 22:55:31 volumio-pi3 volumio[843]: info: Loading plugin "upnp_browser"... Jun 27 22:55:32 volumio-pi3 volumio[843]: info: Loading plugin "alarm-clock"... Jun 27 22:55:32 volumio-pi3 volumio[843]: info: Loading plugin "airplay_emulation"... Jun 27 22:55:32 volumio-pi3 volumio[843]: info: Starting Shairport Sync Jun 27 22:55:32 volumio-pi3 volumio[843]: info: Loading plugin "last_100"... Jun 27 22:55:32 volumio-pi3 volumio[843]: info: Loading plugin "webradio"... Jun 27 22:55:32 volumio-pi3 volumio[843]: info: Loading plugin "i2s_dacs"... Jun 27 22:55:32 volumio-pi3 volumio[843]: info: Loading plugin "volumiodiscovery"... Jun 27 22:55:32 volumio-pi3 volumio[843]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 22:55:32 volumio-pi3 volumio[843]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 22:55:32 volumio-pi3 volumio[843]: *** WARNING *** For more information see Jun 27 22:55:32 volumio-pi3 volumio[843]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 22:55:32 volumio-pi3 volumio[843]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 22:55:32 volumio-pi3 volumio[843]: *** WARNING *** For more information see Jun 27 22:55:32 volumio-pi3 node[843]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 22:55:32 volumio-pi3 node[843]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 22:55:32 volumio-pi3 node[843]: *** WARNING *** For more information see Jun 27 22:55:32 volumio-pi3 node[843]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 22:55:32 volumio-pi3 node[843]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 22:55:32 volumio-pi3 node[843]: *** WARNING *** For more information see Jun 27 22:55:32 volumio-pi3 volumio[843]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 27 22:55:32 volumio-pi3 volumio[843]: info: Discovery: Started advertising with name: Volumio-pi3 Jun 27 22:55:32 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 22:55:32 volumio-pi3 volumio[843]: info: Loading plugin "outputs"... Jun 27 22:55:32 volumio-pi3 sudo[967]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:32 volumio-pi3 kernel: CIFS: Attempting to mount //192.168.1.253/Musik Jun 27 22:55:32 volumio-pi3 volumio[843]: info: Loading plugin "albumart"... Jun 27 22:55:32 volumio-pi3 volumio[843]: info: Plugin example_plugin is not enabled Jun 27 22:55:32 volumio-pi3 volumio[843]: info: Loading plugin "inputs"... Jun 27 22:55:32 volumio-pi3 volumio[843]: info: Loading plugin "updater_comm"... Jun 27 22:55:34 volumio-pi3 ntpd[863]: receive: Unexpected origin timestamp 0xec0983c4.faf78c4b does not match aorg 0000000000.00000000 from server@185.255.121.15 xmt 0xec0983c6.1b277970 Jun 27 22:55:34 volumio-pi3 ntpd[863]: receive: Unexpected origin timestamp 0xec0983c4.faee1f37 does not match aorg 0000000000.00000000 from server@129.70.132.36 xmt 0xec0983c6.1a4aef0f Jun 27 22:55:34 volumio-pi3 volumio[843]: info: Plugin mpdemulation is not enabled Jun 27 22:55:34 volumio-pi3 volumio[843]: info: Loading plugin "rest_api"... Jun 27 22:55:34 volumio-pi3 volumio[843]: info: Loading plugin "websocket"... Jun 27 22:55:34 volumio-pi3 volumio[843]: info: Starting Socket.io Server version 2.3.0 Jun 27 22:55:34 volumio-pi3 volumio[843]: Forking 3 albumart workers Jun 27 22:55:34 volumio-pi3 volumio[843]: info: Loading i18n strings for locale de Jun 27 22:55:34 volumio-pi3 volumio[843]: Updating browse sources language Jun 27 22:55:34 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 22:55:34 volumio-pi3 sudo[965]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:34 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 22:55:34 volumio-pi3 volumio[843]: info: CoreCommandRouter::initPlayerControls Jun 27 22:55:34 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:34 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:34 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:34 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:34 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:34 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:34 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:34 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:34 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 22:55:34 volumio-pi3 volumio[843]: Express server listening on port 3000 Jun 27 22:55:34 volumio-pi3 volumio[843]: [Metrics] WebUI: 8s 134.93ms Jun 27 22:55:34 volumio-pi3 volumio[843]: info: CoreStateMachine::resetVolumioState Jun 27 22:55:34 volumio-pi3 volumio[843]: info: CoreStateMachine::getcurrentVolume Jun 27 22:55:34 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 22:55:34 volumio-pi3 volumio[843]: info: Volumio Network Manager: Network status updated: 1 Jun 27 22:55:35 volumio-pi3 volumio[843]: verbose: New Socket.io Connection to 192.168.1.242 from 192.168.1.14 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 27 22:55:35 volumio-pi3 volumio-remote-updater[567]: [2025-06-27 22:55:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751057730 101 Jun 27 22:55:35 volumio-pi3 volumio[843]: 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: 2 Jun 27 22:55:35 volumio-pi3 volumio[843]: Starting albumart workers Jun 27 22:55:35 volumio-pi3 volumio[843]: Starting albumart workers Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:55:35 volumio-pi3 volumio[843]: info: Reloading queue from file Jun 27 22:55:35 volumio-pi3 volumio[843]: Starting albumart workers Jun 27 22:55:35 volumio-pi3 volumio[843]: info: VolumeController:: Volume=30 Mute =false Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreStateMachine::pushState Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioPushState Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreStateMachine::updateTrackBlock Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrackBlock Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreStateMachine::setRepeat null single undefined Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreStateMachine::pushState Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioPushState Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreStateMachine::setRandom null Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreStateMachine::pushState Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioPushState Jun 27 22:55:35 volumio-pi3 volumio[843]: info: Setting Device type: Raspberry PI Jun 27 22:55:35 volumio-pi3 volumio[843]: info: Completed loading Core Plugins Jun 27 22:55:35 volumio-pi3 volumio[843]: info: Preparing to generate the ALSA configuration file Jun 27 22:55:35 volumio-pi3 volumio[843]: verbose: New Socket.io Connection to 192.168.1.242 from 192.168.1.14 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 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 27 22:55:35 volumio-pi3 volumio[843]: info: Discovery: adding 3dc5d0d8-ad10-4b34-aee6-03fd2263fabf Jun 27 22:55:35 volumio-pi3 volumio[843]: info: Discovery: Found device Volumio-pi3 Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioGetState Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioGetVisibleSources Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioGetState Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioGetState Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 27 22:55:35 volumio-pi3 volumio[843]: info: Received Get System Info Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 27 22:55:35 volumio-pi3 volumio[843]: info: Discovery: Getting this device information Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioGetState Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioGetState Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:55:35 volumio-pi3 volumio[843]: info: Listing playlists Jun 27 22:55:35 volumio-pi3 volumio[843]: info: VolumeController:: Volume=30 Mute =false Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreStateMachine::pushState Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioPushState Jun 27 22:55:35 volumio-pi3 volumio[843]: info: Asound.conf file written Jun 27 22:55:35 volumio-pi3 sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 27 22:55:35 volumio-pi3 sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:35 volumio-pi3 sudo[1053]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:35 volumio-pi3 volumio[843]: info: Output device has changed, restarting MPD Jun 27 22:55:35 volumio-pi3 volumio[843]: info: Output device has changed, restarting Shairport Sync Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:35 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:55:35 volumio-pi3 sudo[1066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 22:55:35 volumio-pi3 sudo[1066]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:35 volumio-pi3 sudo[1066]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:35 volumio-pi3 sudo[1068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 22:55:35 volumio-pi3 sudo[1068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:35 volumio-pi3 volumio[843]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 22:55:35 volumio-pi3 volumio[843]: info: ___________ START PLUGINS ___________ Jun 27 22:55:35 volumio-pi3 systemd[1]: Stopping Music Player Daemon... Jun 27 22:55:36 volumio-pi3 volumio[843]: info: ControllerMpd::onStart: Initializing MPD Jun 27 22:55:36 volumio-pi3 volumio[843]: info: Creating MPD Configuration file Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 22:55:36 volumio-pi3 volumio[843]: info: [1751057736055] CoreMusicLibrary::Adding element Medienserver Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 22:55:36 volumio-pi3 sudo[1091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 22:55:36 volumio-pi3 sudo[1091]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:36 volumio-pi3 sudo[1093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 22:55:36 volumio-pi3 sudo[1091]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:36 volumio-pi3 sudo[1093]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:55:36 volumio-pi3 volumio[843]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:55:36 volumio-pi3 volumio[843]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 22:55:36 volumio-pi3 volumio[843]: info: [1751057736202] CoreMusicLibrary::Adding element Last_100 Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 22:55:36 volumio-pi3 volumio[843]: info: [1751057736206] CoreMusicLibrary::Adding element Webradio Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 22:55:36 volumio-pi3 volumio[843]: info: Initializing BBC Radios Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:55:36 volumio-pi3 volumio[843]: info: Volumio Calling Home Jun 27 22:55:36 volumio-pi3 volumio[843]: info: MPD Permissions set Jun 27 22:55:36 volumio-pi3 volumio[843]: info: MPD Permissions set Jun 27 22:55:36 volumio-pi3 volumio[843]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:55:36 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:37 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:37 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:37 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:37 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:37 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:37 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:37 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:37 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:37 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:55:37 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:37 volumio-pi3 systemd[1]: mpd.service: Succeeded. Jun 27 22:55:37 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioGetState Jun 27 22:55:37 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:55:37 volumio-pi3 systemd[1]: Stopped Music Player Daemon. Jun 27 22:55:37 volumio-pi3 systemd[1]: Starting Music Player Daemon... Jun 27 22:55:37 volumio-pi3 volumio[843]: info: Volumio called home Jun 27 22:55:37 volumio-pi3 volumio[843]: info: Starting Shairport Sync Jun 27 22:55:37 volumio-pi3 volumio[843]: info: Starting Shairport Sync Jun 27 22:55:37 volumio-pi3 sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 27 22:55:37 volumio-pi3 sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:37 volumio-pi3 volumio[843]: info: Starting Shairport Sync Jun 27 22:55:37 volumio-pi3 sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 27 22:55:37 volumio-pi3 sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:37 volumio-pi3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 27 22:55:37 volumio-pi3 systemd[1]: shairport-sync.service: Succeeded. Jun 27 22:55:37 volumio-pi3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 27 22:55:37 volumio-pi3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 27 22:55:37 volumio-pi3 sudo[1131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 27 22:55:37 volumio-pi3 sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:37 volumio-pi3 sudo[1125]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:37 volumio-pi3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 27 22:55:37 volumio-pi3 volumio[843]: info: Shairport-Sync Started Jun 27 22:55:37 volumio-pi3 volumio[843]: Error adding Membership: Error: addMembership EINVAL Jun 27 22:55:37 volumio-pi3 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 27 22:55:37 volumio-pi3 systemd[1]: shairport-sync.service: Succeeded. Jun 27 22:55:37 volumio-pi3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 27 22:55:37 volumio-pi3 sudo[1123]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 27 22:55:37 volumio-pi3 sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:37 volumio-pi3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 27 22:55:37 volumio-pi3 sudo[1127]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:37 volumio-pi3 volumio[843]: info: Shairport-Sync Started Jun 27 22:55:37 volumio-pi3 sudo[1123]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:37 volumio-pi3 sudo[1131]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:37 volumio-pi3 volumio[843]: info: Shairport-Sync Started Jun 27 22:55:39 volumio-pi3 mpd[1136]: Jun 27 22:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 27 22:55:39 volumio-pi3 systemd[1]: Started Music Player Daemon. Jun 27 22:55:39 volumio-pi3 sudo[1093]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:39 volumio-pi3 sudo[1068]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:39 volumio-pi3 volumio[843]: info: Completed starting Core Plugins Jun 27 22:55:39 volumio-pi3 volumio[843]: info: ------------------------------------------- Jun 27 22:55:39 volumio-pi3 volumio[843]: info: ----- MyVolumio plugins startup ---- Jun 27 22:55:39 volumio-pi3 volumio[843]: info: ------------------------------------------- Jun 27 22:55:39 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 27 22:55:39 volumio-pi3 volumio[843]: info: MPD running with PID1136 Jun 27 22:55:39 volumio-pi3 volumio[843]: ,establishing connection Jun 27 22:55:39 volumio-pi3 volumio[843]: error: MPD error: The expression evaluated to a falsy value: Jun 27 22:55:39 volumio-pi3 volumio[843]: assert.ok(self.idling) Jun 27 22:55:39 volumio-pi3 volumio[843]: error: The expression evaluated to a falsy value: Jun 27 22:55:39 volumio-pi3 volumio[843]: assert.ok(self.idling) Jun 27 22:55:39 volumio-pi3 volumio[843]: error: MPD error: The expression evaluated to a falsy value: Jun 27 22:55:39 volumio-pi3 volumio[843]: assert.ok(self.idling) Jun 27 22:55:39 volumio-pi3 volumio[843]: error: The expression evaluated to a falsy value: Jun 27 22:55:39 volumio-pi3 volumio[843]: assert.ok(self.idling) Jun 27 22:55:39 volumio-pi3 volumio[843]: error: updateQueue error: null Jun 27 22:55:42 volumio-pi3 sudo[1159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 27 22:55:42 volumio-pi3 sudo[1159]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:42 volumio-pi3 sudo[1159]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:42 volumio-pi3 sudo[1161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 22:55:42 volumio-pi3 sudo[1161]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:42 volumio-pi3 sudo[1161]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:42 volumio-pi3 sudo[1165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 27 22:55:42 volumio-pi3 sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:42 volumio-pi3 systemd[1]: Started UPnP Renderer front-end to MPD. Jun 27 22:55:42 volumio-pi3 sudo[1165]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:42 volumio-pi3 volumio[843]: info: Upmpdcli Daemon Started Jun 27 22:55:42 volumio-pi3 volumio[1167]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 27 22:55:44 volumio-pi3 volumio[843]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 27 22:55:45 volumio-pi3 volumio[1167]: ......................++++ Jun 27 22:55:45 volumio-pi3 systemd[1]: systemd-fsckd.service: Succeeded. Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 27 22:55:47 volumio-pi3 volumio[843]: info: Adding plugin bluetooth to MyMusic Plugins Jun 27 22:55:47 volumio-pi3 volumio[843]: info: Adding plugin multiroom to MyMusic Plugins Jun 27 22:55:47 volumio-pi3 volumio[843]: info: Adding plugin metavolumio to MyMusic Plugins Jun 27 22:55:47 volumio-pi3 volumio[843]: info: Adding plugin cd_controller to MyMusic Plugins Jun 27 22:55:47 volumio-pi3 volumio[843]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 27 22:55:47 volumio-pi3 volumio[843]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 27 22:55:47 volumio-pi3 volumio[843]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 27 22:55:47 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 27 22:55:49 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 27 22:55:49 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 27 22:55:49 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:55:49 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:55:49 volumio-pi3 volumio[843]: info: Starting MyVolumio Remote Streaming Endpoints Jun 27 22:55:49 volumio-pi3 volumio[843]: info: MyVolumio login type: Token Jun 27 22:55:49 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 27 22:55:49 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 27 22:55:51 volumio-pi3 volumio[843]: info: Starting Streaming Service Transparent Proxy Jun 27 22:55:51 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 27 22:55:51 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 27 22:55:51 volumio-pi3 volumio[843]: info: Streaming services startup Jun 27 22:55:51 volumio-pi3 volumio[843]: info: Starting Streaming Daemon Jun 27 22:55:51 volumio-pi3 systemd[1]: systemd-hostnamed.service: Succeeded. Jun 27 22:55:51 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 27 22:55:51 volumio-pi3 sudo[1196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 27 22:55:51 volumio-pi3 sudo[1196]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:51 volumio-pi3 sudo[1196]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:51 volumio-pi3 volumio[843]: error: Cannot start Volumio Streaming Daemon Jun 27 22:55:51 volumio-pi3 volumio[843]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 27 22:55:51 volumio-pi3 volumio[843]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 27 22:55:51 volumio-pi3 volumio[843]: error: MyVolumio Custom Token format not valid, refreshing it Jun 27 22:55:52 volumio-pi3 volumio[843]: info: MyVolumio login type: Token Jun 27 22:55:52 volumio-pi3 volumio[843]: STREAMING PROXY: Starting server on port 3245 Jun 27 22:55:52 volumio-pi3 volumio[843]: Node JS runtime: 14 Jun 27 22:55:53 volumio-pi3 volumio[843]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 27 22:55:54 volumio-pi3 volumio[843]: info: MyVolumio token set successfully Jun 27 22:55:54 volumio-pi3 volumio[843]: info: MYVOLUMIO: Adding device Jun 27 22:55:54 volumio-pi3 volumio[843]: info: MYVOLUMIO: Evaluating Server Jun 27 22:55:54 volumio-pi3 volumio[843]: info: MyVolumio Plan changed: premium Jun 27 22:55:54 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jun 27 22:55:54 volumio-pi3 volumio[843]: info: Removing browser output: myVolumio user plan is not superstar Jun 27 22:55:54 volumio-pi3 volumio[843]: info: Removing audio output: Jun 27 22:55:54 volumio-pi3 volumio[843]: info: MYVOLUMIO: Adding device Jun 27 22:55:54 volumio-pi3 volumio[843]: info: MYVOLUMIO: Evaluating Server Jun 27 22:55:54 volumio-pi3 volumio[843]: info: Remote config written successfully Jun 27 22:55:54 volumio-pi3 volumio[843]: info: Starting Tunnel 1 Jun 27 22:55:54 volumio-pi3 volumio[843]: info: Starting Tunnel Connection Checker Jun 27 22:55:54 volumio-pi3 volumio[843]: info: Completed starting MyVolumio Plugin Jun 27 22:55:54 volumio-pi3 volumio[843]: info: MYVolumio Device enabled Jun 27 22:55:54 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jun 27 22:55:54 volumio-pi3 volumio[843]: info: MyVolumio status changed Jun 27 22:55:54 volumio-pi3 volumio[843]: info: Streaming services startup Jun 27 22:55:54 volumio-pi3 volumio[843]: info: Starting Streaming Daemon Jun 27 22:55:54 volumio-pi3 sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 27 22:55:54 volumio-pi3 sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:54 volumio-pi3 volumio[843]: info: Setting Geolocation for MyVolumio to eu6 Jun 27 22:55:54 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:55:54 volumio-pi3 sudo[1251]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:54 volumio-pi3 volumio[843]: error: Cannot start Volumio Streaming Daemon Jun 27 22:55:54 volumio-pi3 volumio[843]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 27 22:55:54 volumio-pi3 volumio[843]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 27 22:55:54 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 27 22:55:54 volumio-pi3 volumio[843]: error: [MyVolumio PluginManager] Cache data is invalid! Jun 27 22:55:55 volumio-pi3 systemd[1]: systemd-timedated.service: Succeeded. Jun 27 22:55:55 volumio-pi3 volumio[843]: info: Setting Geolocation for MyVolumio to eu6 Jun 27 22:55:55 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:55:55 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:55:55 volumio-pi3 volumio[1167]: ........................................................................................++++ Jun 27 22:55:55 volumio-pi3 volumio[1167]: e is 65537 (0x010001) Jun 27 22:55:55 volumio-pi3 volumio[1167]: writing RSA key Jun 27 22:55:55 volumio-pi3 volumio[843]: info: Successfully Added MyVolumio device Jun 27 22:55:55 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jun 27 22:55:56 volumio-pi3 volumio[843]: info: [1751057756053] Starting BluetoothController Jun 27 22:55:56 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jun 27 22:55:57 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jun 27 22:55:58 volumio-pi3 sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jun 27 22:55:58 volumio-pi3 sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:58 volumio-pi3 sudo[1271]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:58 volumio-pi3 volumio[843]: info: MRS: MultiRoom plugin initialized Jun 27 22:55:58 volumio-pi3 volumio[843]: info: MRS: STOPPING SNAPCLIENT Jun 27 22:55:58 volumio-pi3 volumio[843]: info: MRS: Snap server stop Jun 27 22:55:58 volumio-pi3 sudo[1287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jun 27 22:55:58 volumio-pi3 sudo[1287]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:58 volumio-pi3 volumio[843]: info: MRS: STOPPING volumioStreaming Jun 27 22:55:58 volumio-pi3 sudo[1290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jun 27 22:55:58 volumio-pi3 sudo[1290]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:58 volumio-pi3 sudo[1292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jun 27 22:55:58 volumio-pi3 sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:58 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jun 27 22:55:58 volumio-pi3 sudo[1295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 27 22:55:58 volumio-pi3 sudo[1295]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:58 volumio-pi3 sudo[1295]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:58 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jun 27 22:55:58 volumio-pi3 sudo[1292]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:58 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jun 27 22:55:58 volumio-pi3 sudo[1287]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:58 volumio-pi3 sudo[1290]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:58 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jun 27 22:55:58 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jun 27 22:55:58 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jun 27 22:55:58 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jun 27 22:55:58 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jun 27 22:55:58 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jun 27 22:55:58 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Jun 27 22:55:58 volumio-pi3 volumio[843]: info: Preparing to generate the ALSA configuration file Jun 27 22:55:58 volumio-pi3 volumio[843]: info: Updating MyVolumio device info Jun 27 22:55:58 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:55:58 volumio-pi3 volumio[843]: info: Updating MyVolumio device info Jun 27 22:55:58 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:55:58 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:55:58 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:55:58 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 27 22:55:58 volumio-pi3 volumio[843]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 27 22:55:58 volumio-pi3 volumio[843]: info: Reading ALSA contributions from plugins. Jun 27 22:55:58 volumio-pi3 volumio[843]: info: Successfully Added MyVolumio device Jun 27 22:55:59 volumio-pi3 volumio[843]: info: MRS: Removed streaming files Jun 27 22:55:59 volumio-pi3 volumio[843]: info: MRS: volumioStreaming STOPPED Jun 27 22:55:59 volumio-pi3 volumio[843]: info: MRS: SNAPSERVER STOPPED Jun 27 22:55:59 volumio-pi3 volumio[843]: info: MRS: SNAPCLIENT STOPPED Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Asound.conf file written Jun 27 22:55:59 volumio-pi3 sudo[1299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 27 22:55:59 volumio-pi3 sudo[1299]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:59 volumio-pi3 sudo[1299]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Output device has changed, restarting MPD Jun 27 22:55:59 volumio-pi3 sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Output device has changed, restarting Shairport Sync Jun 27 22:55:59 volumio-pi3 sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:59 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:59 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:55:59 volumio-pi3 sudo[1304]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:59 volumio-pi3 sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 22:55:59 volumio-pi3 sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:59 volumio-pi3 systemd[1]: Stopping Music Player Daemon... Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 22:55:59 volumio-pi3 volumio[843]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 27 22:55:59 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:59 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jun 27 22:55:59 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 22:55:59 volumio-pi3 systemd[1]: mpd.service: Succeeded. Jun 27 22:55:59 volumio-pi3 systemd[1]: Stopped Music Player Daemon. Jun 27 22:55:59 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jun 27 22:55:59 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jun 27 22:55:59 volumio-pi3 volumio[843]: info: MRS: Pushing multiroomSync output for this device Jun 27 22:55:59 volumio-pi3 volumio[843]: info: MRS: Pushing multiroomSync output Jun 27 22:55:59 volumio-pi3 sudo[1316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio-pi3 Jun 27 22:55:59 volumio-pi3 sudo[1316]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:59 volumio-pi3 systemd[1]: Starting Music Player Daemon... Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Adding audio output: Jun 27 22:55:59 volumio-pi3 volumio[843]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 27 22:55:59 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:55:59 volumio-pi3 sudo[1316]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:59 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Adding METAVOLUMIO REST API Endpoints Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jun 27 22:55:59 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jun 27 22:55:59 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Preparing CD Folders Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Adding CD REST API Endpoints Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Starting UDEV Watcher for CD Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Detecting CD presence with UDEV Jun 27 22:55:59 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jun 27 22:55:59 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Adding inputs REST Endpoints Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Scanning Audio Inputs Jun 27 22:55:59 volumio-pi3 sudo[1314]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 27 22:55:59 volumio-pi3 sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:59 volumio-pi3 sudo[1314]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Checking against Known Cards name Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Adding Server instance for streaming Jun 27 22:55:59 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jun 27 22:55:59 volumio-pi3 volumio[843]: error: Hi Res Audio Failed Login: Missing Login Data Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Adding HIGHRESAUDIO REST API Endpoints Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jun 27 22:55:59 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jun 27 22:55:59 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Refreshing QOBUZ token Jun 27 22:55:59 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jun 27 22:55:59 volumio-pi3 volumio[843]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jun 27 22:55:59 volumio-pi3 sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 27 22:55:59 volumio-pi3 sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:59 volumio-pi3 volumio[843]: info: QobuzConnect: Starting Qobuz Connect socket and service Jun 27 22:55:59 volumio-pi3 sudo[1326]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:59 volumio-pi3 sudo[1333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 27 22:55:59 volumio-pi3 sudo[1333]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:55:59 volumio-pi3 volumio[843]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jun 27 22:55:59 volumio-pi3 sudo[1333]: pam_unix(sudo:session): session closed for user root Jun 27 22:55:59 volumio-pi3 volumio[843]: info: Stopping AccessToken refresher cron Jun 27 22:56:00 volumio-pi3 sudo[1335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 27 22:56:00 volumio-pi3 sudo[1335]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:00 volumio-pi3 volumio[843]: info: AccessToken refresher cron started Jun 27 22:56:00 volumio-pi3 volumio[843]: info: Adding TIDAL REST API Endpoints Jun 27 22:56:00 volumio-pi3 volumio[843]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jun 27 22:56:00 volumio-pi3 volumio[843]: info: Bluetooth name changed to Volumio-pi3 Jun 27 22:56:00 volumio-pi3 volumio[843]: info: MPD Permissions set Jun 27 22:56:00 volumio-pi3 systemd[1]: Started Volumio Qobuz Connect Service. Jun 27 22:56:00 volumio-pi3 sudo[1335]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:00 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:00 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:00 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:00 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:56:00 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:00 volumio-pi3 volumio[843]: info: Starting Shairport Sync Jun 27 22:56:00 volumio-pi3 sudo[1347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 27 22:56:00 volumio-pi3 sudo[1347]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:00 volumio-pi3 volumio[843]: info: Access Token successfully retrieved Jun 27 22:56:00 volumio-pi3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 27 22:56:00 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 22:56:00 volumio-pi3 systemd[1]: shairport-sync.service: Succeeded. Jun 27 22:56:00 volumio-pi3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 27 22:56:00 volumio-pi3 volumio[843]: info: [1751057760399] CoreMusicLibrary::Adding element QOBUZ Jun 27 22:56:00 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 22:56:00 volumio-pi3 volumio[843]: Cannot find translation for source QOBUZ Jun 27 22:56:00 volumio-pi3 volumio[843]: info: Stopping AccessToken refresher cron for QOBUZ Jun 27 22:56:00 volumio-pi3 volumio[843]: info: AccessToken refresher cron started for QOBUZ Jun 27 22:56:00 volumio-pi3 volumio[843]: info: Adding QOBUZ REST API Endpoints Jun 27 22:56:00 volumio-pi3 volumio[843]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jun 27 22:56:00 volumio-pi3 volumio[843]: info: Successfully Updated MyVolumio device Jun 27 22:56:00 volumio-pi3 volumio[843]: info: Successfully Updated MyVolumio device Jun 27 22:56:00 volumio-pi3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 27 22:56:00 volumio-pi3 sudo[1347]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:00 volumio-pi3 volumio[843]: info: Shairport-Sync Started Jun 27 22:56:00 volumio-pi3 sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jun 27 22:56:00 volumio-pi3 sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:00 volumio-pi3 systemd[1]: Started MyVolumio SSH Tunnel. Jun 27 22:56:00 volumio-pi3 sudo[1351]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:00 volumio-pi3 volumio[843]: info: Executing endpoint qc_getconfig Jun 27 22:56:00 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 27 22:56:00 volumio-pi3 qobuz-connect[1337]: 20250627 22:56:00.821 [1337.1337] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 27 22:56:00 volumio-pi3 volumio[843]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 27 22:56:00 volumio-pi3 volumio[843]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 27 22:56:00 volumio-pi3 volumio[843]: info: Remote SSH Started Jun 27 22:56:00 volumio-pi3 autossh[1357]: port set to 0, monitoring disabled Jun 27 22:56:00 volumio-pi3 autossh[1357]: starting ssh (count 1) Jun 27 22:56:00 volumio-pi3 autossh[1357]: ssh child pid is 1360 Jun 27 22:56:01 volumio-pi3 volumio[843]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 27 22:56:01 volumio-pi3 qobuz-connect[1337]: 20250627 22:56:01.163 [1337.1337] INFO VolumeManager: [0x1db6910]: Setting new playback volume: 75 Jun 27 22:56:01 volumio-pi3 qobuz-connect[1337]: 20250627 22:56:01.164 [1337.1337] INFO VolumeManager: [0x1db6910]: Setting new mute state: 0 Jun 27 22:56:01 volumio-pi3 qobuz-connect[1337]: 20250627 22:56:01.165 [1337.1337] INFO QobuzConnect: [0x1db72e0]: Client initialized! Jun 27 22:56:01 volumio-pi3 qobuz-connect[1337]: 20250627 22:56:01.165 [1337.1337] INFO SampleApp: Starting Avahi advertising, name: Volumio-pi3, service name: _qobuz-connect._tcp Jun 27 22:56:01 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioGetState Jun 27 22:56:01 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:01 volumio-pi3 qobuz-connect[1337]: 20250627 22:56:01.179 [1337.1337] INFO LocalConfigManager: [0x1db61f0]: Starting Local Configuration server Jun 27 22:56:01 volumio-pi3 qobuz-connect[1337]: 20250627 22:56:01.179 [1337.1337] INFO SampleApp: Starting Local configuration server Jun 27 22:56:01 volumio-pi3 qobuz-connect[1337]: 20250627 22:56:01.179 [1337.1337] INFO SampleApp: Playback volume changed: 75 Jun 27 22:56:01 volumio-pi3 qobuz-connect[1337]: 20250627 22:56:01.180 [1337.1337] INFO SampleApp: Connected to UNIX socket client 0x1dabf60 Jun 27 22:56:01 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioGetState Jun 27 22:56:01 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:01 volumio-pi3 volumiossh-tunnel[1355]: Warning: Permanently added '[eu6.myvolumio.org]:2222,[207.154.233.230]:2222' (RSA) to the list of known hosts. Jun 27 22:56:02 volumio-pi3 sudo[1377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jun 27 22:56:02 volumio-pi3 sudo[1377]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:02 volumio-pi3 systemd[1]: Started Volumio Bluetooth Module. Jun 27 22:56:02 volumio-pi3 sudo[1377]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:02 volumio-pi3 volumio[843]: info: Volumio BT Module successfully started Jun 27 22:56:02 volumio-pi3 sudo[1380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jun 27 22:56:02 volumio-pi3 sudo[1380]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:02 volumio-pi3 volumiobt[1379]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jun 27 22:56:02 volumio-pi3 sudo[1380]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:02 volumio-pi3 mpd[1324]: Jun 27 22:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 27 22:56:02 volumio-pi3 volumio[843]: info: TidalConnect service stoped! Jun 27 22:56:03 volumio-pi3 volumio[843]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 27 22:56:03 volumio-pi3 volumio[843]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 27 22:56:03 volumio-pi3 sudo[1392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 27 22:56:03 volumio-pi3 sudo[1392]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:03 volumio-pi3 systemd[1]: Started Volumio Tidal Connect Service. Jun 27 22:56:03 volumio-pi3 systemd[1]: Started Music Player Daemon. Jun 27 22:56:03 volumio-pi3 sudo[1306]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:03 volumio-pi3 sudo[1392]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:03 volumio-pi3 volumio[843]: error: updateQueue error: null Jun 27 22:56:03 volumio-pi3 sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jun 27 22:56:03 volumio-pi3 sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:03 volumio-pi3 volumiobt[1379]: pulseaudio: no process found Jun 27 22:56:03 volumio-pi3 sudo[1399]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:04 volumio-pi3 volumio[843]: info: Executing endpoint tc_getconfig Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 27 22:56:04 volumio-pi3 volumio[843]: info: Executing endpoint tc_connect Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 27 22:56:04 volumio-pi3 vtcs[1394]: STARTING TidalConnect services, version: 1.5.1-beta1 Jun 27 22:56:04 volumio-pi3 vtcs[1394]: STARTED TidalConnect services. Jun 27 22:56:04 volumio-pi3 volumio[843]: info: Connecting to TidalConnect Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CoreCommandRouter::servicePushState Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CoreStateMachine::pushState Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioPushState Jun 27 22:56:04 volumio-pi3 volumio[843]: info: MRS: Pushing multiroomSync output update for this device Jun 27 22:56:04 volumio-pi3 dbus-daemon[598]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.21' (uid=1000 pid=1413 comm="/usr/bin/pulseaudio --high-priority=true ") Jun 27 22:56:04 volumio-pi3 volumio[843]: info: MRS: Pushing multiroomSync output Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioGetState Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:04 volumio-pi3 volumio[843]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CoreCommandRouter::servicePushState Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CoreStateMachine::pushState Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioPushState Jun 27 22:56:04 volumio-pi3 volumio[843]: info: MRS: Pushing multiroomSync output update for this device Jun 27 22:56:04 volumio-pi3 volumio[843]: info: MRS: Pushing multiroomSync output Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioGetState Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:04 volumio-pi3 volumio[843]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jun 27 22:56:04 volumio-pi3 systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jun 27 22:56:04 volumio-pi3 volumio[843]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 27 22:56:04 volumio-pi3 dbus-daemon[598]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jun 27 22:56:04 volumio-pi3 systemd[1]: Started RealtimeKit Scheduling Policy Service. Jun 27 22:56:04 volumio-pi3 rtkit-daemon[1414]: Successfully called chroot. Jun 27 22:56:04 volumio-pi3 rtkit-daemon[1414]: Successfully dropped privileges. Jun 27 22:56:04 volumio-pi3 rtkit-daemon[1414]: Successfully limited resources. Jun 27 22:56:04 volumio-pi3 rtkit-daemon[1414]: Running. Jun 27 22:56:04 volumio-pi3 rtkit-daemon[1414]: Canary thread running. Jun 27 22:56:04 volumio-pi3 rtkit-daemon[1414]: Watchdog thread running. Jun 27 22:56:04 volumio-pi3 dbus-daemon[598]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.23' (uid=0 pid=1414 comm="/usr/lib/rtkit/rtkit-daemon ") Jun 27 22:56:04 volumio-pi3 systemd[1]: Starting Authorization Manager... Jun 27 22:56:04 volumio-pi3 volumio[843]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 27 22:56:04 volumio-pi3 polkitd[1424]: started daemon version 0.105 using authority implementation `local' version `0.105' Jun 27 22:56:04 volumio-pi3 dbus-daemon[598]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jun 27 22:56:04 volumio-pi3 systemd[1]: Started Authorization Manager. Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 27 22:56:04 volumio-pi3 volumio[843]: info: MRS: Getting audio outputs on start Jun 27 22:56:04 volumio-pi3 volumio[843]: info: MRS: Requesting all other devices output Jun 27 22:56:04 volumio-pi3 volumio[843]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioGetState Jun 27 22:56:04 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:04 volumio-pi3 pulseaudio[1413]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jun 27 22:56:04 volumio-pi3 pulseaudio[1413]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jun 27 22:56:04 volumio-pi3 bluetoothd[764]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSource Jun 27 22:56:04 volumio-pi3 volumiobt[1379]: Applying permissions Jun 27 22:56:04 volumio-pi3 volumiobt[1379]: Setting BT discoverable and pairable Jun 27 22:56:04 volumio-pi3 bluetoothd[764]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSink Jun 27 22:56:04 volumio-pi3 pulseaudio[1413]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jun 27 22:56:05 volumio-pi3 kernel: Bluetooth: RFCOMM TTY layer initialized Jun 27 22:56:05 volumio-pi3 kernel: Bluetooth: RFCOMM socket layer initialized Jun 27 22:56:05 volumio-pi3 kernel: Bluetooth: RFCOMM ver 1.11 Jun 27 22:56:05 volumio-pi3 volumiobt[1379]: Agent registered Jun 27 22:56:05 volumio-pi3 volumiobt[1379]: [bluetooth]# -e power on Jun 27 22:56:05 volumio-pi3 volumiobt[1379]: Invalid command in menu main: -e Jun 27 22:56:05 volumio-pi3 volumiobt[1379]: Jun 27 22:56:05 volumio-pi3 volumiobt[1379]: Use "help" for a list of available commands in a menu. Jun 27 22:56:05 volumio-pi3 volumiobt[1379]: Use "menu " if you want to enter any submenu. Jun 27 22:56:05 volumio-pi3 volumiobt[1379]: Use "back" if you want to return to menu main. Jun 27 22:56:05 volumio-pi3 volumiobt[1379]: [bluetooth]# agent on Jun 27 22:56:05 volumio-pi3 volumiobt[1379]: Agent is already registered Jun 27 22:56:05 volumio-pi3 volumiobt[1379]: [bluetooth]# discoverable on Jun 27 22:56:05 volumio-pi3 volumiobt[1379]: [bluetooth]# pairable on Jun 27 22:56:05 volumio-pi3 volumiobt[1379]: [bluetooth]# agent NoInputNoOutput Jun 27 22:56:05 volumio-pi3 volumiobt[1379]: Agent is already registered Jun 27 22:56:05 volumio-pi3 volumiobt[1379]: [bluetooth]# default-agent Jun 27 22:56:05 volumio-pi3 volumiobt[1379]: [bluetooth]# quit Jun 27 22:56:05 volumio-pi3 volumiobt[1379]: [59B blob data] Jun 27 22:56:06 volumio-pi3 volumio[843]: info: TidalConnect service started! Jun 27 22:56:06 volumio-pi3 volumio[843]: [Metrics] CommandRouter: 38s 2.71ms Jun 27 22:56:06 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumiosetStartupVolume Jun 27 22:56:06 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:06 volumio-pi3 volumio[843]: info: VolumeController:: Setting startup Volume 30 Jun 27 22:56:06 volumio-pi3 volumio[843]: info: VolumeController::SetAlsaVolume30 Jun 27 22:56:06 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:56:06 volumio-pi3 volumio[843]: info: CoreCommandRouter::Close All Modals sent Jun 27 22:56:06 volumio-pi3 volumio[843]: info: CoreCommandRouter::Close All Modals sent Jun 27 22:56:06 volumio-pi3 volumio[843]: info: CoreStateMachine::pushState Jun 27 22:56:06 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:06 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioPushState Jun 27 22:56:06 volumio-pi3 volumio[843]: info: MRS: Pushing multiroomSync output update for this device Jun 27 22:56:06 volumio-pi3 volumio[843]: info: MRS: Pushing multiroomSync output Jun 27 22:56:06 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioGetState Jun 27 22:56:06 volumio-pi3 volumio[843]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:06 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 22:56:06 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:56:06 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 27 22:56:06 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 27 22:56:06 volumio-pi3 volumio-remote-updater[567]: No test mode Jun 27 22:56:06 volumio-pi3 volumio-remote-updater[567]: No alpha test mode Jun 27 22:56:06 volumio-pi3 volumio[843]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 27 22:56:06 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 27 22:56:07 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 27 22:56:07 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 27 22:56:07 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 27 22:56:08 volumio-pi3 volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 22:56:08 volumio-pi3 volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 22:56:08 volumio-pi3 volumio[843]: info: Retrieving Cloud Streaming UI Jun 27 22:56:08 volumio-pi3 volumio[843]: info: Getting Tidal Cloud Configuration Jun 27 22:56:08 volumio-pi3 volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 22:56:08 volumio-pi3 volumio[843]: info: Getting Qobuz Cloud Configuration Jun 27 22:56:08 volumio-pi3 volumio[843]: info: Asking plugin for UI Config Jun 27 22:56:08 volumio-pi3 volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 22:56:08 volumio-pi3 volumio[843]: info: Getting Spotify Cloud Configuration Jun 27 22:56:08 volumio-pi3 volumio[843]: info: Asking plugin for UI Config Jun 27 22:56:08 volumio-pi3 volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 22:56:08 volumio-pi3 volumio[843]: info: Saving Spotify Acccount Jun 27 22:56:08 volumio-pi3 volumio[843]: info: Got it Jun 27 22:56:08 volumio-pi3 volumio[843]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 27 22:56:09 volumio-pi3 volumio[843]: info: Got Tidal Cloud Configuration Jun 27 22:56:09 volumio-pi3 volumio[843]: info: Got it Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioGetBrowseSources Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioGetBrowseSources Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioGetBrowseSources Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::volumioGetBrowseSources Jun 27 22:56:09 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 22:56:13 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 27 22:56:13 volumio-pi3 volumio[843]: info: BOOT COMPLETED Jun 27 22:56:15 volumio-pi3 volumio[843]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jun 27 22:56:15 volumio-pi3 sudo[1464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Musik Jun 27 22:56:15 volumio-pi3 sudo[1464]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:15 volumio-pi3 sudo[1464]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:15 volumio-pi3 volumio[843]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 22:56:15 volumio-pi3 volumio[843]: Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/Musik' Jun 27 22:56:15 volumio-pi3 volumio[843]: at Object.rmdirSync (fs.js:890:10) Jun 27 22:56:15 volumio-pi3 volumio[843]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Jun 27 22:56:15 volumio-pi3 volumio[843]: at ChildProcess.exithandler (child_process.js:299:7) Jun 27 22:56:15 volumio-pi3 volumio[843]: at ChildProcess.emit (events.js:315:20) Jun 27 22:56:15 volumio-pi3 volumio[843]: at maybeClose (internal/child_process.js:1048:16) Jun 27 22:56:15 volumio-pi3 volumio[843]: at Socket. (internal/child_process.js:439:11) Jun 27 22:56:15 volumio-pi3 volumio[843]: at Socket.emit (events.js:315:20) Jun 27 22:56:15 volumio-pi3 volumio[843]: at Pipe. (net.js:673:12) { Jun 27 22:56:15 volumio-pi3 volumio[843]: errno: -16, Jun 27 22:56:15 volumio-pi3 volumio[843]: syscall: 'rmdir', Jun 27 22:56:15 volumio-pi3 volumio[843]: code: 'EBUSY', Jun 27 22:56:15 volumio-pi3 volumio[843]: path: '/mnt/NAS/Musik' Jun 27 22:56:15 volumio-pi3 volumio[843]: } Jun 27 22:56:15 volumio-pi3 volumio[843]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 22:56:16 volumio-pi3 sudo[1476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-27 22:55 Jun 27 22:56:16 volumio-pi3 sudo[1476]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:16 volumio-pi3 sudo[1476]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:16 volumio-pi3 volumio-remote-updater[567]: [2025-06-27 22:56:16] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 27 22:56:16 volumio-pi3 volumio-remote-updater[567]: [2025-06-27 22:56:16] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 27 22:56:16 volumio-pi3 qobuz-connect[1337]: 20250627 22:56:16.915 [1337.1337] INFO SampleApp: Socket closed Jun 27 22:56:16 volumio-pi3 vtcs[1394]: [2025-06-27 22:56:16.914] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jun 27 22:56:16 volumio-pi3 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 27 22:56:16 volumio-pi3 systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 27 22:56:16 volumio-pi3 systemd[1]: Started dynamicswap service. Jun 27 22:56:17 volumio-pi3 systemd[1]: dynamicswap.service: Succeeded. Jun 27 22:56:17 volumio-pi3 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 27 22:56:17 volumio-pi3 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jun 27 22:56:17 volumio-pi3 systemd[1]: Started dynamicswap service. Jun 27 22:56:17 volumio-pi3 systemd[1]: Stopped Volumio Backend Module. Jun 27 22:56:17 volumio-pi3 systemd[1]: Started Volumio Backend Module. Jun 27 22:56:17 volumio-pi3 systemd[1]: dynamicswap.service: Succeeded. Jun 27 22:56:19 volumio-pi3 volumio[1490]: info: ------------------------------------------- Jun 27 22:56:19 volumio-pi3 volumio[1490]: info: ----- Volumio3 ---- Jun 27 22:56:19 volumio-pi3 volumio[1490]: info: ------------------------------------------- Jun 27 22:56:19 volumio-pi3 volumio[1490]: info: ----- System startup ---- Jun 27 22:56:19 volumio-pi3 volumio[1490]: info: ------------------------------------------- Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: MYVOLUMIO Environment detected Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: Plugin folders cleanup Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: Scanning into folder /volumio/app/plugins/ Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: Scanning category audio_interface Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: Scanning category miscellanea Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: Scanning category music_service Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: Scanning category plugins.json Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: Scanning category system_controller Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: Scanning category user_interface Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: Scanning into folder /data/plugins/ Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: Plugin folders cleanup completed Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: ------------------------------------------- Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: ----- Core plugins startup ---- Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: ------------------------------------------- Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: Loading plugins from folder /volumio/app/plugins/ Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: Adding plugin upnp to MyMusic Plugins Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: Loading plugins from folder /data/plugins/ Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: Loading plugin "system"... Jun 27 22:56:20 volumio-pi3 volumio[1490]: info: Loading plugin "appearance"... Jun 27 22:56:21 volumio-pi3 volumio-remote-updater[567]: [2025-06-27 22:56:21] [connect] Successful connection Jun 27 22:56:21 volumio-pi3 volumio[1490]: info: Loading plugin "network"... Jun 27 22:56:21 volumio-pi3 volumio[1490]: info: Refreshing Cached IP Addresses Jun 27 22:56:22 volumio-pi3 sudo[1554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 27 22:56:22 volumio-pi3 sudo[1554]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:22 volumio-pi3 sudo[1554]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: Loading plugin "services"... Jun 27 22:56:22 volumio-pi3 sudo[1556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 22:56:22 volumio-pi3 sudo[1556]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:22 volumio-pi3 sudo[1556]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: Loading plugin "alsa_controller"... Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: Loading plugin "wizard"... Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: Loading plugin "networkfs"... Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: Cannot mount NAS Musik at system boot, trial number 1 ,retrying in 5 seconds Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: Cannot mount NAS Musik at system boot, trial number 1 ,retrying in 5 seconds Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: Starting Udev Watcher for removable devices Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: Ignoring mount for partition: boot Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: Ignoring mount for partition: volumio Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: Ignoring mount for partition: volumio_data Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: Loading plugin "volumio_command_line_client"... Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: Loading plugin "upnp"... Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: [1751057782263] Starting Upmpd Daemon Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: Loading plugin "my_music"... Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: Loading plugin "mpd"... Jun 27 22:56:22 volumio-pi3 volumio[1490]: info: Loading plugin "upnp_browser"... Jun 27 22:56:23 volumio-pi3 volumio[1490]: info: Loading plugin "alarm-clock"... Jun 27 22:56:23 volumio-pi3 volumio[1490]: info: Loading plugin "airplay_emulation"... Jun 27 22:56:23 volumio-pi3 volumio[1490]: info: Starting Shairport Sync Jun 27 22:56:23 volumio-pi3 volumio[1490]: info: Loading plugin "last_100"... Jun 27 22:56:23 volumio-pi3 volumio[1490]: info: Loading plugin "webradio"... Jun 27 22:56:23 volumio-pi3 volumio[1490]: info: Loading plugin "i2s_dacs"... Jun 27 22:56:23 volumio-pi3 volumio[1490]: info: Loading plugin "volumiodiscovery"... Jun 27 22:56:23 volumio-pi3 volumio[1490]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 22:56:23 volumio-pi3 volumio[1490]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 22:56:23 volumio-pi3 volumio[1490]: *** WARNING *** For more information see Jun 27 22:56:23 volumio-pi3 node[1490]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 22:56:23 volumio-pi3 volumio[1490]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 22:56:23 volumio-pi3 volumio[1490]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 22:56:23 volumio-pi3 volumio[1490]: *** WARNING *** For more information see Jun 27 22:56:23 volumio-pi3 node[1490]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 22:56:23 volumio-pi3 node[1490]: *** WARNING *** For more information see Jun 27 22:56:23 volumio-pi3 node[1490]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 22:56:23 volumio-pi3 node[1490]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 22:56:23 volumio-pi3 node[1490]: *** WARNING *** For more information see Jun 27 22:56:23 volumio-pi3 volumio[1490]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 27 22:56:23 volumio-pi3 volumio[1490]: info: Discovery: Started advertising with name: Volumio-pi3 Jun 27 22:56:23 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 22:56:23 volumio-pi3 volumio[1490]: info: Loading plugin "outputs"... Jun 27 22:56:23 volumio-pi3 volumio[1490]: info: Loading plugin "albumart"... Jun 27 22:56:23 volumio-pi3 volumio[1490]: info: Plugin example_plugin is not enabled Jun 27 22:56:23 volumio-pi3 volumio[1490]: info: Loading plugin "inputs"... Jun 27 22:56:23 volumio-pi3 volumio[1490]: info: Loading plugin "updater_comm"... Jun 27 22:56:23 volumio-pi3 volumio[1490]: info: Plugin mpdemulation is not enabled Jun 27 22:56:23 volumio-pi3 volumio[1490]: info: Loading plugin "rest_api"... Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: Loading plugin "websocket"... Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: Starting Socket.io Server version 2.3.0 Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: Loading i18n strings for locale de Jun 27 22:56:24 volumio-pi3 volumio[1490]: Forking 3 albumart workers Jun 27 22:56:24 volumio-pi3 volumio[1490]: Updating browse sources language Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::initPlayerControls Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 22:56:24 volumio-pi3 volumio[1490]: Express server listening on port 3000 Jun 27 22:56:24 volumio-pi3 volumio[1490]: [Metrics] WebUI: 5s 750.24ms Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreStateMachine::resetVolumioState Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreStateMachine::getcurrentVolume Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: Volumio Network Manager: Network status updated: 1 Jun 27 22:56:24 volumio-pi3 volumio[1490]: verbose: New Socket.io Connection to 192.168.1.242 from 192.168.1.14 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 27 22:56:24 volumio-pi3 volumio[1490]: verbose: New Socket.io Connection to 192.168.1.242 from 192.168.1.14 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 27 22:56:24 volumio-pi3 volumio-remote-updater[567]: [2025-06-27 22:56:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751057781 101 Jun 27 22:56:24 volumio-pi3 volumio[1490]: 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: 3 Jun 27 22:56:24 volumio-pi3 volumio[1490]: verbose: New Socket.io Connection to 192.168.1.242 from 192.168.1.14 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 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: Reloading queue from file Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::volumioGetVisibleSources Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::volumioGetState Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::volumioGetState Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: Received Get System Info Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: Discovery: Getting this device information Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::volumioGetState Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CoreCommandRouter::volumioGetState Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:24 volumio-pi3 volumio[1490]: info: Listing playlists Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: VolumeController:: Volume=30 Mute =false Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreStateMachine::pushState Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::volumioPushState Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreStateMachine::updateTrackBlock Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CorePlayQueue::getTrackBlock Jun 27 22:56:25 volumio-pi3 volumio[1490]: Starting albumart workers Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 22:56:25 volumio-pi3 volumio[1490]: Starting albumart workers Jun 27 22:56:25 volumio-pi3 volumio[1490]: Starting albumart workers Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreStateMachine::setRepeat null single undefined Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreStateMachine::pushState Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::volumioPushState Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreStateMachine::setRandom null Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreStateMachine::pushState Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::volumioPushState Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: Setting Device type: Raspberry PI Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: Completed loading Core Plugins Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: Preparing to generate the ALSA configuration file Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: VolumeController:: Volume=30 Mute =false Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreStateMachine::pushState Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::volumioPushState Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: Discovery: adding 3dc5d0d8-ad10-4b34-aee6-03fd2263fabf Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: Discovery: Found device Volumio-pi3 Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::volumioGetState Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: Asound.conf file written Jun 27 22:56:25 volumio-pi3 sudo[1621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 27 22:56:25 volumio-pi3 sudo[1621]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:25 volumio-pi3 sudo[1621]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: Output device has changed, restarting MPD Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: Output device has changed, restarting Shairport Sync Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:56:25 volumio-pi3 sudo[1628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 22:56:25 volumio-pi3 sudo[1628]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:25 volumio-pi3 sudo[1627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 22:56:25 volumio-pi3 sudo[1627]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:25 volumio-pi3 sudo[1627]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:25 volumio-pi3 systemd[1]: Stopping Music Player Daemon... Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: ___________ START PLUGINS ___________ Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: ControllerMpd::onStart: Initializing MPD Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: Creating MPD Configuration file Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: [1751057785748] CoreMusicLibrary::Adding element Medienserver Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 22:56:25 volumio-pi3 sudo[1636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 22:56:25 volumio-pi3 sudo[1636]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:25 volumio-pi3 sudo[1636]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:25 volumio-pi3 sudo[1645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 22:56:25 volumio-pi3 sudo[1645]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: [1751057785957] CoreMusicLibrary::Adding element Last_100 Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: [1751057785960] CoreMusicLibrary::Adding element Webradio Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 22:56:25 volumio-pi3 volumio[1490]: info: Initializing BBC Radios Jun 27 22:56:25 volumio-pi3 systemd[1]: mpd.service: Succeeded. Jun 27 22:56:25 volumio-pi3 systemd[1]: Stopped Music Player Daemon. Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: Volumio Calling Home Jun 27 22:56:26 volumio-pi3 systemd[1]: Starting Music Player Daemon... Jun 27 22:56:26 volumio-pi3 sudo[1673]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 27 22:56:26 volumio-pi3 sudo[1673]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:26 volumio-pi3 sudo[1673]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: MPD Permissions set Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: MPD Permissions set Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: Volumio called home Jun 27 22:56:26 volumio-pi3 volumio[1490]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: Starting Shairport Sync Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: Starting Shairport Sync Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: Starting Shairport Sync Jun 27 22:56:26 volumio-pi3 sudo[1697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 27 22:56:26 volumio-pi3 sudo[1697]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:26 volumio-pi3 sudo[1702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 27 22:56:26 volumio-pi3 sudo[1702]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:26 volumio-pi3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 27 22:56:26 volumio-pi3 sudo[1699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 27 22:56:26 volumio-pi3 systemd[1]: shairport-sync.service: Succeeded. Jun 27 22:56:26 volumio-pi3 sudo[1699]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:26 volumio-pi3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CoreCommandRouter::volumioGetState Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: CorePlayQueue::getTrack 0 Jun 27 22:56:26 volumio-pi3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 27 22:56:26 volumio-pi3 sudo[1697]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:26 volumio-pi3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: Shairport-Sync Started Jun 27 22:56:26 volumio-pi3 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 27 22:56:26 volumio-pi3 systemd[1]: shairport-sync.service: Succeeded. Jun 27 22:56:26 volumio-pi3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 27 22:56:26 volumio-pi3 volumio[1490]: Error adding Membership: Error: addMembership EINVAL Jun 27 22:56:26 volumio-pi3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 27 22:56:26 volumio-pi3 sudo[1702]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: Shairport-Sync Started Jun 27 22:56:26 volumio-pi3 sudo[1699]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:26 volumio-pi3 volumio[1490]: info: Shairport-Sync Started Jun 27 22:56:27 volumio-pi3 volumio[1490]: info: Cannot mount NAS Musik at system boot, trial number 2 ,retrying in 5 seconds Jun 27 22:56:27 volumio-pi3 volumio[1490]: info: Cannot mount NAS Musik at system boot, trial number 2 ,retrying in 5 seconds Jun 27 22:56:28 volumio-pi3 mpd[1685]: Jun 27 22:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 27 22:56:28 volumio-pi3 systemd[1]: Started Music Player Daemon. Jun 27 22:56:28 volumio-pi3 sudo[1628]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:28 volumio-pi3 sudo[1645]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:28 volumio-pi3 volumio[1490]: info: Completed starting Core Plugins Jun 27 22:56:28 volumio-pi3 volumio[1490]: info: ------------------------------------------- Jun 27 22:56:28 volumio-pi3 volumio[1490]: info: ----- MyVolumio plugins startup ---- Jun 27 22:56:28 volumio-pi3 volumio[1490]: info: ------------------------------------------- Jun 27 22:56:28 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 27 22:56:28 volumio-pi3 volumio[1490]: error: MPD error: The expression evaluated to a falsy value: Jun 27 22:56:28 volumio-pi3 volumio[1490]: assert.ok(self.idling) Jun 27 22:56:28 volumio-pi3 volumio[1490]: error: The expression evaluated to a falsy value: Jun 27 22:56:28 volumio-pi3 volumio[1490]: assert.ok(self.idling) Jun 27 22:56:28 volumio-pi3 volumio[1490]: error: updateQueue error: null Jun 27 22:56:28 volumio-pi3 volumio[1490]: info: MPD running with PID1685 Jun 27 22:56:28 volumio-pi3 volumio[1490]: ,establishing connection Jun 27 22:56:28 volumio-pi3 volumio[1490]: error: updateQueue error: null Jun 27 22:56:29 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 27 22:56:32 volumio-pi3 volumio[1490]: info: Cannot mount NAS Musik at system boot, trial number 3 ,retrying in 5 seconds Jun 27 22:56:32 volumio-pi3 volumio[1490]: info: Cannot mount NAS Musik at system boot, trial number 3 ,retrying in 5 seconds Jun 27 22:56:32 volumio-pi3 sudo[1730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 27 22:56:32 volumio-pi3 sudo[1732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 22:56:32 volumio-pi3 sudo[1730]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:32 volumio-pi3 sudo[1732]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:32 volumio-pi3 sudo[1730]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:32 volumio-pi3 sudo[1732]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:32 volumio-pi3 sudo[1734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 27 22:56:32 volumio-pi3 sudo[1734]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:32 volumio-pi3 sudo[1734]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:32 volumio-pi3 volumio[1490]: info: Upmpdcli Daemon Started Jun 27 22:56:33 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 27 22:56:33 volumio-pi3 volumio[1490]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: Adding plugin bluetooth to MyMusic Plugins Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: Adding plugin multiroom to MyMusic Plugins Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: Adding plugin metavolumio to MyMusic Plugins Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: Adding plugin cd_controller to MyMusic Plugins Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 27 22:56:36 volumio-pi3 volumio[1490]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 27 22:56:37 volumio-pi3 volumio[1490]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 27 22:56:37 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 27 22:56:38 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 27 22:56:38 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 27 22:56:38 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:56:38 volumio-pi3 volumio[1490]: info: Starting MyVolumio Remote Streaming Endpoints Jun 27 22:56:38 volumio-pi3 volumio[1490]: info: MyVolumio login type: Token Jun 27 22:56:38 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 27 22:56:38 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 27 22:56:39 volumio-pi3 volumio[1490]: info: Starting Streaming Service Transparent Proxy Jun 27 22:56:39 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 27 22:56:39 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 27 22:56:39 volumio-pi3 volumio[1490]: info: Streaming services startup Jun 27 22:56:39 volumio-pi3 volumio[1490]: info: Starting Streaming Daemon Jun 27 22:56:40 volumio-pi3 sudo[1745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 27 22:56:40 volumio-pi3 sudo[1745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:40 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 27 22:56:40 volumio-pi3 sudo[1745]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:40 volumio-pi3 volumio[1490]: info: Cannot mount NAS Musik at system boot, trial number 4 ,retrying in 5 seconds Jun 27 22:56:40 volumio-pi3 volumio[1490]: info: Cannot mount NAS Musik at system boot, trial number 4 ,retrying in 5 seconds Jun 27 22:56:40 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 27 22:56:40 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jun 27 22:56:40 volumio-pi3 volumio[1490]: error: Cannot start Volumio Streaming Daemon Jun 27 22:56:40 volumio-pi3 volumio[1490]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 27 22:56:40 volumio-pi3 volumio[1490]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 27 22:56:40 volumio-pi3 sudo[1753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Musik Jun 27 22:56:40 volumio-pi3 sudo[1753]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:40 volumio-pi3 volumio[1490]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 27 22:56:40 volumio-pi3 systemd[1]: mnt-NAS-Musik.mount: Succeeded. Jun 27 22:56:40 volumio-pi3 sudo[1753]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:40 volumio-pi3 volumio[1490]: info: Share Musik successfully unmounted Jun 27 22:56:41 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 27 22:56:41 volumio-pi3 volumio[1490]: STREAMING PROXY: Starting server on port 3245 Jun 27 22:56:41 volumio-pi3 volumio[1490]: Node JS runtime: 14 Jun 27 22:56:41 volumio-pi3 volumio[1490]: info: MyVolumio token set successfully Jun 27 22:56:41 volumio-pi3 volumio[1490]: info: MYVOLUMIO: Adding device Jun 27 22:56:41 volumio-pi3 volumio[1490]: info: MYVOLUMIO: Evaluating Server Jun 27 22:56:41 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: MyVolumio Plan changed: premium Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: Removing browser output: myVolumio user plan is not superstar Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: Removing audio output: Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: MYVOLUMIO: Adding device Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: MYVOLUMIO: Evaluating Server Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: Remote config written successfully Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: Starting Tunnel 1 Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: Starting Tunnel Connection Checker Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: Completed starting MyVolumio Plugin Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: MYVolumio Device enabled Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: MyVolumio status changed Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: Streaming services startup Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: Starting Streaming Daemon Jun 27 22:56:42 volumio-pi3 sudo[1806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 27 22:56:42 volumio-pi3 sudo[1806]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: Setting Geolocation for MyVolumio to eu6 Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:56:42 volumio-pi3 sudo[1806]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:42 volumio-pi3 volumio[1490]: error: Cannot start Volumio Streaming Daemon Jun 27 22:56:42 volumio-pi3 volumio[1490]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 27 22:56:42 volumio-pi3 volumio[1490]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 27 22:56:42 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jun 27 22:56:43 volumio-pi3 volumio[1490]: info: [1751057803100] Starting BluetoothController Jun 27 22:56:43 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jun 27 22:56:44 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jun 27 22:56:44 volumio-pi3 sudo[1809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jun 27 22:56:44 volumio-pi3 sudo[1809]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:44 volumio-pi3 sudo[1809]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:44 volumio-pi3 volumio[1490]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Jun 27 22:56:44 volumio-pi3 volumio[1490]: info: MRS: MultiRoom plugin initialized Jun 27 22:56:44 volumio-pi3 volumio[1490]: info: MRS: STOPPING SNAPCLIENT Jun 27 22:56:44 volumio-pi3 volumio[1490]: info: MRS: Snap server stop Jun 27 22:56:45 volumio-pi3 sudo[1825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jun 27 22:56:45 volumio-pi3 sudo[1825]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:45 volumio-pi3 volumio[1490]: info: MRS: STOPPING volumioStreaming Jun 27 22:56:45 volumio-pi3 sudo[1827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jun 27 22:56:45 volumio-pi3 sudo[1827]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:45 volumio-pi3 sudo[1831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jun 27 22:56:45 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jun 27 22:56:45 volumio-pi3 sudo[1833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 27 22:56:45 volumio-pi3 sudo[1833]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:45 volumio-pi3 sudo[1831]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:45 volumio-pi3 sudo[1825]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:45 volumio-pi3 sudo[1833]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:45 volumio-pi3 sudo[1827]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:45 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jun 27 22:56:45 volumio-pi3 sudo[1831]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:45 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jun 27 22:56:45 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jun 27 22:56:45 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jun 27 22:56:45 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jun 27 22:56:45 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jun 27 22:56:45 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jun 27 22:56:45 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jun 27 22:56:45 volumio-pi3 volumio[1490]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Jun 27 22:56:45 volumio-pi3 volumio[1490]: info: Preparing to generate the ALSA configuration file Jun 27 22:56:45 volumio-pi3 volumio[1490]: info: Updating MyVolumio device info Jun 27 22:56:45 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:56:45 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:56:45 volumio-pi3 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 22:56:45 volumio-pi3 volumio[1490]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 22:56:45 volumio-pi3 volumio[1490]: TypeError: Cannot read property 'replace' of undefined Jun 27 22:56:45 volumio-pi3 volumio[1490]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Jun 27 22:56:45 volumio-pi3 volumio[1490]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Jun 27 22:56:45 volumio-pi3 volumio[1490]: at listOnTimeout (internal/timers.js:554:17) Jun 27 22:56:45 volumio-pi3 volumio[1490]: at processTimers (internal/timers.js:497:7) Jun 27 22:56:45 volumio-pi3 volumio[1490]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 22:56:45 volumio-pi3 sudo[1840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.1 //192.168.1.253/Musik /mnt/NAS/Musik Jun 27 22:56:45 volumio-pi3 sudo[1840]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 22:56:45 volumio-pi3 kernel: CIFS: Attempting to mount //192.168.1.253/Musik Jun 27 22:56:45 volumio-pi3 sudo[1840]: pam_unix(sudo:session): session closed for user root Jun 27 22:56:48 volumio-pi3 sudo[1858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-27 22:55 Jun 27 22:56:48 volumio-pi3 sudo[1858]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"