Dec 27 15:08:00 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 27 15:08:00 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 27 15:08:00 volumio volumio[1202]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 15:08:00 volumio systemd[1]: setdatetime-helper.service: Consumed 1.001s CPU time. Dec 27 15:08:00 volumio volumio[1202]: info: [1766819280053] CoreMusicLibrary::Adding element Last_100 Dec 27 15:08:00 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 15:08:00 volumio volumio[1202]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 15:08:00 volumio volumio[1202]: info: [1766819280144] CoreMusicLibrary::Adding element Webradio Dec 27 15:08:00 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 15:08:00 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 15:08:00 volumio volumio[1202]: info: Initializing BBC Radios Dec 27 15:08:00 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 15:08:00 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:08:00 volumio volumio[1202]: info: Volumio Calling Home Dec 27 15:08:03 volumio volumio[1335]: Starting albumart workers Dec 27 15:08:03 volumio volumio[1327]: Starting albumart workers Dec 27 15:08:04 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 27 15:08:04 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 27 15:08:04 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 27 15:08:04 volumio sudo[1394]: pam_unix(sudo:session): session closed for user root Dec 27 15:08:04 volumio volumio[1326]: Starting albumart workers Dec 27 15:08:07 volumio mpd[1444]: 2025-12-27T15:08:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 15:08:07 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 27 15:08:07 volumio sudo[1424]: pam_unix(sudo:session): session closed for user root Dec 27 15:08:07 volumio sudo[1399]: pam_unix(sudo:session): session closed for user root Dec 27 15:08:08 volumio volumio[1202]: info: Completed starting Core Plugins Dec 27 15:08:08 volumio volumio[1202]: info: ------------------------------------------- Dec 27 15:08:08 volumio volumio[1202]: info: ----- MyVolumio plugins startup ---- Dec 27 15:08:08 volumio volumio[1202]: info: ------------------------------------------- Dec 27 15:08:08 volumio volumio[1202]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 27 15:08:08 volumio volumio[1202]: info: MPD Permissions set Dec 27 15:08:08 volumio volumio[1202]: info: MPD Permissions set Dec 27 15:08:08 volumio volumio[1202]: info: Upmpdcli Daemon Started Dec 27 15:08:08 volumio volumio[1202]: info: MPD running with PID1444 Dec 27 15:08:08 volumio volumio[1202]: ,establishing connection Dec 27 15:08:08 volumio volumio[1202]: info: Volumio called home Dec 27 15:08:10 volumio volumio[1202]: error: MPD error: The expression evaluated to a falsy value: Dec 27 15:08:10 volumio volumio[1202]: assert.ok(self.idling) Dec 27 15:08:10 volumio volumio[1202]: error: The expression evaluated to a falsy value: Dec 27 15:08:10 volumio volumio[1202]: assert.ok(self.idling) Dec 27 15:08:10 volumio volumio[1202]: error: MPD error: The expression evaluated to a falsy value: Dec 27 15:08:10 volumio volumio[1202]: assert.ok(self.idling) Dec 27 15:08:10 volumio volumio[1202]: error: The expression evaluated to a falsy value: Dec 27 15:08:10 volumio volumio[1202]: assert.ok(self.idling) Dec 27 15:08:10 volumio volumio[1202]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:08:10 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:11 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.115 from 192.168.1.152 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Dec 27 15:08:11 volumio volumio[1202]: error: updateQueue error: null Dec 27 15:08:11 volumio sudo[1533]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 27 15:08:11 volumio sudo[1533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:08:11 volumio sudo[1535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 27 15:08:11 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.115 from 192.168.1.152 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Dec 27 15:08:11 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.115 from 192.168.1.152 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 8 Dec 27 15:08:11 volumio sudo[1535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:08:11 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 27 15:08:11 volumio mpd_monitor.sh[1538]: MPD Monitor Service: Starting MPD Monitor Service Dec 27 15:08:11 volumio sudo[1533]: pam_unix(sudo:session): session closed for user root Dec 27 15:08:11 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 27 15:08:11 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 27 15:08:11 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 27 15:08:11 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 27 15:08:11 volumio mpd_monitor.sh[1542]: MPD Monitor Service: Starting MPD Monitor Service Dec 27 15:08:11 volumio sudo[1535]: pam_unix(sudo:session): session closed for user root Dec 27 15:08:11 volumio volumio[1202]: info: Starting Shairport Sync Dec 27 15:08:12 volumio volumio[1202]: info: Starting Shairport Sync Dec 27 15:08:12 volumio volumio[1202]: info: Starting Shairport Sync Dec 27 15:08:12 volumio sudo[1548]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 15:08:12 volumio sudo[1548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:08:12 volumio volumio[1202]: info: Successfully started MPD Monitor Dec 27 15:08:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 27 15:08:12 volumio sudo[1551]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 15:08:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 27 15:08:12 volumio sudo[1551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:08:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 15:08:12 volumio volumio[1202]: info: Successfully started MPD Monitor Dec 27 15:08:12 volumio systemd[1]: shairport-sync.service: Consumed 1.459s CPU time. Dec 27 15:08:12 volumio sudo[1553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 15:08:12 volumio sudo[1553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:08:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 15:08:12 volumio sudo[1548]: pam_unix(sudo:session): session closed for user root Dec 27 15:08:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 27 15:08:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 27 15:08:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 15:08:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 15:08:12 volumio sudo[1553]: pam_unix(sudo:session): session closed for user root Dec 27 15:08:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 27 15:08:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 27 15:08:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 15:08:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 15:08:12 volumio sudo[1551]: pam_unix(sudo:session): session closed for user root Dec 27 15:08:12 volumio volumio[1202]: info: Shairport-Sync Started Dec 27 15:08:12 volumio volumio[1202]: Error adding Membership: Error: addMembership EINVAL Dec 27 15:08:12 volumio volumio[1202]: info: Shairport-Sync Started Dec 27 15:08:12 volumio volumio[1202]: info: Shairport-Sync Started Dec 27 15:08:12 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Dec 27 15:08:12 volumio volumio[1202]: info: CorePlayQueue::getTrack 0 Dec 27 15:08:14 volumio volumio[1202]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 27 15:08:19 volumio sudo[1609]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 15:08:19 volumio sudo[1609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:08:19 volumio sudo[1609]: pam_unix(sudo:session): session closed for user root Dec 27 15:08:19 volumio sudo[1611]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 15:08:19 volumio sudo[1611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:08:19 volumio sudo[1611]: pam_unix(sudo:session): session closed for user root Dec 27 15:08:19 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.115 from 192.168.1.152 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 27 15:08:19 volumio volumio[1202]: info: Adding plugin bluetooth to MyMusic Plugins Dec 27 15:08:19 volumio volumio[1202]: info: Adding plugin multiroom to MyMusic Plugins Dec 27 15:08:19 volumio volumio[1202]: info: Adding plugin metavolumio to MyMusic Plugins Dec 27 15:08:19 volumio volumio[1202]: info: Adding plugin cd_controller to MyMusic Plugins Dec 27 15:08:19 volumio volumio[1202]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 27 15:08:19 volumio volumio[1202]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 27 15:08:19 volumio volumio[1202]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 27 15:08:19 volumio volumio[1202]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 27 15:08:29 volumio volumio[1202]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 27 15:08:29 volumio volumio[1202]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 27 15:08:29 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:08:29 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:08:29 volumio volumio[1202]: info: Starting MyVolumio Remote Streaming Endpoints Dec 27 15:08:30 volumio volumio[1202]: info: MyVolumio login type: Token Dec 27 15:08:30 volumio volumio[1202]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 27 15:08:30 volumio volumio[1202]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 27 15:08:38 volumio volumio[1202]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 27 15:08:38 volumio volumio[1202]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 27 15:08:38 volumio volumio[1202]: info: Streaming services startup Dec 27 15:08:38 volumio volumio[1202]: info: Starting Streaming Daemon Dec 27 15:08:38 volumio volumio[1202]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 27 15:08:38 volumio sudo[1647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 27 15:08:38 volumio sudo[1647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:08:38 volumio sudo[1647]: pam_unix(sudo:session): session closed for user root Dec 27 15:08:38 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 15:08:38 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:08:38 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 27 15:08:39 volumio volumio[1202]: error: Cannot start Volumio Streaming Daemon Dec 27 15:08:39 volumio volumio[1202]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 27 15:08:39 volumio volumio[1202]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 27 15:08:41 volumio sudo[1658]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 15:08:41 volumio sudo[1658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:08:41 volumio sudo[1660]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 15:08:41 volumio sudo[1658]: pam_unix(sudo:session): session closed for user root Dec 27 15:08:41 volumio sudo[1660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:08:41 volumio sudo[1660]: pam_unix(sudo:session): session closed for user root Dec 27 15:08:42 volumio volumio-remote-updater[659]: Test mode disabled Dec 27 15:08:42 volumio volumio-remote-updater[659]: Alpha mode disabled Dec 27 15:08:42 volumio volumio-remote-updater[659]: Alpha legacy test mode disabled Dec 27 15:08:42 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 27 15:08:42 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.115 from 192.168.1.152 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 10 Dec 27 15:08:43 volumio volumio[1202]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 27 15:08:43 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 27 15:08:43 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.115 from 192.168.1.152 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 11 Dec 27 15:08:43 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 27 15:08:43 volumio volumio[1202]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 27 15:08:44 volumio upmpdcli[1664]: writing RSA key Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Dec 27 15:08:44 volumio volumio[1202]: info: CorePlayQueue::getTrack 0 Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 27 15:08:44 volumio volumio[1202]: info: Received Get System Info Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 15:08:44 volumio volumio[1202]: info: Discovery: Getting this device information Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Dec 27 15:08:44 volumio volumio[1202]: info: CorePlayQueue::getTrack 0 Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Dec 27 15:08:44 volumio volumio[1202]: info: CorePlayQueue::getTrack 0 Dec 27 15:08:44 volumio volumio[1202]: info: Listing playlists Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 15:08:44 volumio volumio[1202]: info: Received Get System Info Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 15:08:44 volumio volumio[1202]: info: Discovery: Getting this device information Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Dec 27 15:08:44 volumio volumio[1202]: info: CorePlayQueue::getTrack 0 Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Dec 27 15:08:44 volumio volumio[1202]: info: CorePlayQueue::getTrack 0 Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Dec 27 15:08:44 volumio volumio[1202]: info: CorePlayQueue::getTrack 0 Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Dec 27 15:08:44 volumio volumio[1202]: info: CorePlayQueue::getTrack 0 Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 27 15:08:44 volumio volumio[1202]: info: Received Get System Info Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 15:08:44 volumio volumio[1202]: info: Discovery: Getting this device information Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Dec 27 15:08:44 volumio volumio[1202]: info: CorePlayQueue::getTrack 0 Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Dec 27 15:08:44 volumio volumio[1202]: info: CorePlayQueue::getTrack 0 Dec 27 15:08:44 volumio volumio[1202]: info: Listing playlists Dec 27 15:08:44 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 27 15:08:45 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 27 15:08:45 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 27 15:08:45 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 27 15:08:45 volumio volumio[1202]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Dec 27 15:08:45 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Dec 27 15:08:45 volumio volumio[1202]: info: CorePlayQueue::getTrack 0 Dec 27 15:08:46 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 27 15:08:46 volumio volumio[1202]: info: MyVolumio token set successfully Dec 27 15:08:46 volumio volumio[1202]: info: MYVOLUMIO: Adding device Dec 27 15:08:46 volumio volumio[1202]: info: MYVOLUMIO: Evaluating Server Dec 27 15:08:48 volumio volumio[1202]: info: MyVolumio Plan changed: premium Dec 27 15:08:48 volumio volumio[1202]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 27 15:08:48 volumio volumio[1202]: info: Removing browser output: myVolumio user plan is not superstar Dec 27 15:08:48 volumio volumio[1202]: info: Removing audio output: Dec 27 15:08:48 volumio volumio[1202]: info: MYVOLUMIO: Adding device Dec 27 15:08:48 volumio volumio[1202]: info: MYVOLUMIO: Evaluating Server Dec 27 15:08:48 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 15:08:48 volumio volumio[1202]: info: Received Get System Info Dec 27 15:08:48 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 15:08:48 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 15:08:48 volumio volumio[1202]: info: Discovery: Getting this device information Dec 27 15:08:48 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Dec 27 15:08:48 volumio volumio[1202]: info: CorePlayQueue::getTrack 0 Dec 27 15:08:48 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 15:08:48 volumio volumio[1202]: info: Setting Geolocation for MyVolumio to eu8 Dec 27 15:08:48 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:08:48 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:08:48 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:08:48 volumio volumio[1202]: info: Remote config written successfully Dec 27 15:08:48 volumio volumio[1202]: info: Starting Tunnel 1 Dec 27 15:08:48 volumio volumio[1202]: info: Starting Tunnel Connection Checker Dec 27 15:08:48 volumio volumio[1202]: info: Completed starting MyVolumio Plugin Dec 27 15:08:49 volumio volumio[1202]: info: MYVolumio Device enabled Dec 27 15:08:49 volumio volumio[1202]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 27 15:08:49 volumio volumio[1202]: info: MyVolumio status changed Dec 27 15:08:49 volumio volumio[1202]: info: Streaming services startup Dec 27 15:08:49 volumio volumio[1202]: info: Starting Streaming Daemon Dec 27 15:08:49 volumio sudo[1723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 27 15:08:49 volumio sudo[1723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:08:49 volumio sudo[1723]: pam_unix(sudo:session): session closed for user root Dec 27 15:08:49 volumio volumio[1202]: info: Successfully Added MyVolumio device Dec 27 15:08:50 volumio volumio[1202]: info: Updating MyVolumio device info Dec 27 15:08:50 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:08:50 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:08:50 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:08:50 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:50 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:08:50 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 27 15:08:50 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 27 15:08:50 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 27 15:08:50 volumio volumio[1202]: info: CoreCommandRouter::volumioGetBrowseSources Dec 27 15:08:50 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 15:08:50 volumio volumio[1202]: info: Setting Geolocation for MyVolumio to us2 Dec 27 15:08:50 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:08:50 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:08:50 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:08:50 volumio volumio[1202]: error: Cannot start Volumio Streaming Daemon Dec 27 15:08:50 volumio volumio[1202]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 27 15:08:50 volumio volumio[1202]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 27 15:08:50 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 27 15:08:50 volumio volumio[1202]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 27 15:08:51 volumio volumio[1202]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Dec 27 15:08:51 volumio volumio[1202]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 27 15:09:01 volumio volumio[1202]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 27 15:09:02 volumio sudo[1746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 27 15:09:02 volumio sudo[1746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:09:02 volumio sudo[1746]: pam_unix(sudo:session): session closed for user root Dec 27 15:09:02 volumio volumio[1202]: info: MRS: MultiRoom plugin initialized Dec 27 15:09:02 volumio volumio[1202]: info: MRS: STOPPING SNAPCLIENT Dec 27 15:09:02 volumio volumio[1202]: info: MRS: Snap server stop Dec 27 15:09:02 volumio volumio[1202]: info: MRS: STOPPING volumioStreaming Dec 27 15:09:02 volumio sudo[1763]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 27 15:09:02 volumio sudo[1763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:09:02 volumio volumio[1202]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 27 15:09:02 volumio sudo[1766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 27 15:09:02 volumio sudo[1766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:09:02 volumio sudo[1767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 27 15:09:02 volumio sudo[1769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 27 15:09:02 volumio sudo[1769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:09:02 volumio sudo[1767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:09:02 volumio sudo[1769]: pam_unix(sudo:session): session closed for user root Dec 27 15:09:02 volumio sudo[1763]: pam_unix(sudo:session): session closed for user root Dec 27 15:09:02 volumio volumio[1202]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 27 15:09:02 volumio sudo[1766]: pam_unix(sudo:session): session closed for user root Dec 27 15:09:03 volumio sudo[1767]: pam_unix(sudo:session): session closed for user root Dec 27 15:09:03 volumio volumio[1202]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 27 15:09:03 volumio volumio[1202]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 27 15:09:03 volumio volumio[1202]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 27 15:09:03 volumio volumio[1202]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 27 15:09:03 volumio volumio[1202]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 27 15:09:03 volumio volumio[1202]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 27 15:09:03 volumio volumio[1202]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 27 15:09:03 volumio volumio[1202]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 27 15:09:03 volumio volumio[1202]: info: Preparing to generate the ALSA configuration file Dec 27 15:09:03 volumio volumio[1202]: info: Updating MyVolumio device info Dec 27 15:09:03 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:09:03 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:09:03 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:09:03 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 15:09:03 volumio volumio[1202]: info: Received Get System Info Dec 27 15:09:03 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 15:09:03 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 15:09:03 volumio volumio[1202]: info: Discovery: Getting this device information Dec 27 15:09:03 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Dec 27 15:09:03 volumio volumio[1202]: info: CorePlayQueue::getTrack 0 Dec 27 15:09:03 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 15:09:03 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 15:09:03 volumio volumio[1202]: info: Received Get System Info Dec 27 15:09:03 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 15:09:03 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 15:09:03 volumio volumio[1202]: info: Discovery: Getting this device information Dec 27 15:09:03 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Dec 27 15:09:03 volumio volumio[1202]: info: CorePlayQueue::getTrack 0 Dec 27 15:09:03 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 15:09:03 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 27 15:09:03 volumio volumio[1202]: info: Successfully Added MyVolumio device Dec 27 15:09:03 volumio volumio[1202]: info: Successfully Updated MyVolumio device Dec 27 15:09:03 volumio volumio[1202]: info: MRS: Removed streaming files Dec 27 15:09:03 volumio volumio[1202]: info: MRS: volumioStreaming STOPPED Dec 27 15:09:03 volumio volumio[1202]: info: MRS: SNAPSERVER STOPPED Dec 27 15:09:03 volumio volumio[1202]: info: MRS: SNAPCLIENT STOPPED Dec 27 15:09:04 volumio volumio[1202]: info: Asound.conf file unchanged, so no further update is needed Dec 27 15:09:04 volumio volumio[1202]: info: Output device has changed, restarting MPD Dec 27 15:09:04 volumio volumio[1202]: info: Output device has changed, restarting Shairport Sync Dec 27 15:09:04 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:09:04 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:09:04 volumio sudo[1780]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 15:09:04 volumio sudo[1780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:09:04 volumio sudo[1780]: pam_unix(sudo:session): session closed for user root Dec 27 15:09:04 volumio sudo[1782]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 15:09:04 volumio sudo[1782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:09:04 volumio volumio[1202]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 15:09:04 volumio volumio[1202]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 27 15:09:04 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:09:04 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 27 15:09:04 volumio volumio[1202]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 27 15:09:04 volumio volumio[1202]: ------------------------------------ BT MESSAGE: [FUNC] onStart Dec 27 15:09:04 volumio volumio[1202]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Dec 27 15:09:05 volumio volumio[1202]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Dec 27 15:09:05 volumio volumio[1202]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Dec 27 15:09:05 volumio volumio[1202]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Dec 27 15:09:05 volumio volumio[1202]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 27 15:09:05 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 27 15:09:05 volumio volumio[1202]: info: MRS: Pushing multiroomSync output for this device Dec 27 15:09:05 volumio volumio[1202]: info: MRS: Pushing multiroomSync output Dec 27 15:09:05 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 27 15:09:05 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 27 15:09:05 volumio systemd[1]: mpd.service: Consumed 5.741s CPU time. Dec 27 15:09:05 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 27 15:09:05 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 27 15:09:05 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 27 15:09:05 volumio volumio[1202]: info: Adding audio output: Dec 27 15:09:05 volumio volumio[1202]: info: Adding audio output: Dec 27 15:09:05 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 27 15:09:05 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 27 15:09:05 volumio bluetoothd[790]: Path / reserved for Adv Monitor app :1.21 Dec 27 15:09:05 volumio volumio[1202]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 27 15:09:05 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:09:05 volumio bluetoothd[790]: Adv Monitor app :1.21 disconnected from D-Bus Dec 27 15:09:05 volumio volumio[1202]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 27 15:09:05 volumio volumio[1202]: info: Adding METAVOLUMIO REST API Endpoints Dec 27 15:09:05 volumio volumio[1202]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 27 15:09:05 volumio volumio[1202]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 27 15:09:05 volumio volumio[1202]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 27 15:09:05 volumio volumio[1202]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 27 15:09:05 volumio volumio[1202]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 27 15:09:05 volumio volumio[1202]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 27 15:09:05 volumio volumio[1202]: info: Preparing CD Folders Dec 27 15:09:05 volumio sudo[1796]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 27 15:09:05 volumio sudo[1796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 27 15:09:05 volumio sudo[1796]: pam_unix(sudo:session): session closed for user root Dec 27 15:09:05 volumio volumio[1202]: info: Adding CD REST API Endpoints Dec 27 15:09:05 volumio volumio[1202]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 27 15:09:05 volumio volumio[1202]: info: Starting UDEV Watcher for CD Dec 27 15:09:05 volumio volumio[1202]: info: Detecting CD presence with UDEV Dec 27 15:09:05 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 27 15:09:08 volumio mpd[1801]: 2025-12-27T15:09:08 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 15:09:08 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 27 15:09:08 volumio sudo[1782]: pam_unix(sudo:session): session closed for user root Dec 27 15:09:11 volumio volumio[1202]: warn: [cd-plugin] cdspeedctl: device or media not ready Dec 27 15:09:11 volumio volumio[1202]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 27 15:09:11 volumio volumio[1202]: info: Adding inputs REST Endpoints Dec 27 15:09:11 volumio volumio[1202]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 27 15:09:11 volumio volumio[1202]: info: Scanning Audio Inputs Dec 27 15:09:11 volumio volumio[1202]: info: Checking against Known Cards name Dec 27 15:09:11 volumio volumio[1202]: info: Adding Server instance for streaming Dec 27 15:09:11 volumio volumio[1202]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 27 15:09:11 volumio volumio[1202]: error: Hi Res Audio Failed Login: Missing Login Data Dec 27 15:09:11 volumio volumio[1202]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 27 15:09:11 volumio volumio[1202]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 27 15:09:11 volumio volumio[1202]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 27 15:09:11 volumio volumio[1202]: info: Refreshing TIDAL token Dec 27 15:09:11 volumio volumio[1202]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 27 15:09:11 volumio volumio[1202]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 27 15:09:11 volumio volumio[1202]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Dec 27 15:09:11 volumio volumio[1202]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 27 15:09:11 volumio sudo[1825]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 15:09:11 volumio sudo[1825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:09:11 volumio volumio[1202]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 27 15:09:11 volumio sudo[1825]: pam_unix(sudo:session): session closed for user root Dec 27 15:09:11 volumio sudo[1833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 27 15:09:11 volumio sudo[1833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:09:11 volumio volumio[1202]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 27 15:09:11 volumio sudo[1833]: pam_unix(sudo:session): session closed for user root Dec 27 15:09:11 volumio volumio[1202]: info: Stopping AccessToken refresher cron for QOBUZ Dec 27 15:09:12 volumio sudo[1835]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 27 15:09:12 volumio sudo[1835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:09:12 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 27 15:09:12 volumio sudo[1835]: pam_unix(sudo:session): session closed for user root Dec 27 15:09:12 volumio volumio[1202]: info: AccessToken refresher cron started for QOBUZ Dec 27 15:09:12 volumio volumio[1202]: info: Adding QOBUZ REST API Endpoints Dec 27 15:09:12 volumio volumio[1202]: error: error Dec 27 15:09:12 volumio volumio[1202]: info: MRS: Getting audio outputs on start Dec 27 15:09:12 volumio volumio[1202]: info: MRS: Requesting all other devices output Dec 27 15:09:12 volumio volumio[1202]: info: Successfully Updated MyVolumio device Dec 27 15:09:13 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 27 15:09:13 volumio volumio[1202]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 27 15:09:13 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 27 15:09:13 volumio volumio[1202]: info: Received Get System Version Dec 27 15:09:13 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 15:09:13 volumio volumio[1202]: info: Received Get System Info Dec 27 15:09:13 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 15:09:13 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 15:09:13 volumio volumio[1202]: info: Discovery: Getting this device information Dec 27 15:09:13 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Dec 27 15:09:13 volumio volumio[1202]: info: CorePlayQueue::getTrack 0 Dec 27 15:09:13 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 15:09:13 volumio volumio[1202]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Dec 27 15:09:13 volumio sudo[1842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 27 15:09:13 volumio sudo[1842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:09:13 volumio volumio[1202]: info: MPD Permissions set Dec 27 15:09:13 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Dec 27 15:09:13 volumio sudo[1842]: pam_unix(sudo:session): session closed for user root Dec 27 15:09:14 volumio volumiobt[1848]: INFO [BTSTART] Ensuring Bluetooth directory exists... Dec 27 15:09:14 volumio sudo[1849]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Dec 27 15:09:14 volumio sudo[1849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:09:14 volumio sudo[1849]: pam_unix(sudo:session): session closed for user root Dec 27 15:09:14 volumio sudo[1846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 27 15:09:14 volumio sudo[1846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:09:14 volumio sudo[1852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Dec 27 15:09:14 volumio sudo[1852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:09:14 volumio sudo[1852]: pam_unix(sudo:session): session closed for user root Dec 27 15:09:14 volumio volumiobt[1854]: INFO [BTSTART] Powering on Bluetooth if needed... Dec 27 15:09:14 volumio bluetoothd[790]: Adv Monitor app :1.23 disconnected from D-Bus Dec 27 15:09:14 volumio volumiobt[1857]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Dec 27 15:09:14 volumio volumiobt[1858]: [176B blob data] Dec 27 15:09:14 volumio volumiobt[1858]: [157B blob data] Dec 27 15:09:14 volumio volumiobt[1858]: [157B blob data] Dec 27 15:09:14 volumio volumiobt[1858]: [157B blob data] Dec 27 15:09:14 volumio volumiobt[1858]: [113B blob data] Dec 27 15:09:14 volumio volumiobt[1858]: [bluetoothctl]> discoverable on Dec 27 15:09:14 volumio volumiobt[1858]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Dec 27 15:09:14 volumio volumiobt[1858]: [bluetoothctl]> pairable on Dec 27 15:09:14 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 27 15:09:14 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 27 15:09:14 volumio volumio[1202]: info: Executing endpoint qc_getconfig Dec 27 15:09:14 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 27 15:09:14 volumio volumiobt[1858]: [bluetoothctl]> Dec 27 15:09:14 volumio volumiobt[1860]: INFO [BTSTART] Registering Bluetooth agent... Dec 27 15:09:14 volumio bluetoothd[790]: Adv Monitor app :1.24 disconnected from D-Bus Dec 27 15:09:14 volumio volumiobt[1861]: [NEW] Media /org/bluez/hci0 Dec 27 15:09:14 volumio volumiobt[1861]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 27 15:09:14 volumio volumiobt[1861]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 27 15:09:14 volumio volumiobt[1861]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 27 15:09:14 volumio bluetoothd[790]: Adv Monitor app :1.25 disconnected from D-Bus Dec 27 15:09:14 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 27 15:09:14 volumio sudo[1846]: pam_unix(sudo:session): session closed for user root Dec 27 15:09:14 volumio volumiobt[1864]: No agent is registered Dec 27 15:09:14 volumio volumiobt[1864]: [NEW] Media /org/bluez/hci0 Dec 27 15:09:14 volumio volumiobt[1864]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 27 15:09:14 volumio volumiobt[1864]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 27 15:09:14 volumio volumiobt[1864]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 27 15:09:14 volumio autossh[1865]: port set to 0, monitoring disabled Dec 27 15:09:14 volumio autossh[1865]: starting ssh (count 1) Dec 27 15:09:14 volumio autossh[1865]: ssh child pid is 1869 Dec 27 15:09:14 volumio bluetoothd[790]: Adv Monitor app :1.26 disconnected from D-Bus Dec 27 15:09:14 volumio volumiobt[1870]: INFO [BTSTART] Agent registered successfully. Dec 27 15:09:14 volumio volumiobt[1871]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Dec 27 15:09:14 volumio qobuz-connect[1840]: 20251227 15:09:14.584 [1840.1840] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 27 15:09:14 volumio qobuz-connect[1840]: 20251227 15:09:14.744 [1840.1840] INFO VolumeManager: [0x386de8]: Setting new playback volume: 75 Dec 27 15:09:14 volumio qobuz-connect[1840]: 20251227 15:09:14.744 [1840.1840] INFO VolumeManager: [0x386de8]: Setting new mute state: 0 Dec 27 15:09:14 volumio qobuz-connect[1840]: 20251227 15:09:14.744 [1840.1840] INFO QobuzConnect: [0x3877b8]: Client initialized! Dec 27 15:09:14 volumio qobuz-connect[1840]: 20251227 15:09:14.744 [1840.1840] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 27 15:09:14 volumio qobuz-connect[1840]: 20251227 15:09:14.785 [1840.1840] INFO LocalConfigManager: [0x3866c8]: Starting Local Configuration server Dec 27 15:09:14 volumio qobuz-connect[1840]: 20251227 15:09:14.785 [1840.1840] INFO SampleApp: Starting Local configuration server Dec 27 15:09:14 volumio qobuz-connect[1840]: 20251227 15:09:14.786 [1840.1840] INFO SampleApp: Playback volume changed: 75 Dec 27 15:09:14 volumio qobuz-connect[1840]: 20251227 15:09:14.786 [1840.1840] INFO SampleApp: Connected to UNIX socket client 0x371818 Dec 27 15:09:15 volumio volumiobt[1872]: INFO:a2dp-agent:Connecting to system bus. Dec 27 15:09:15 volumio volumiobt[1872]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Dec 27 15:09:15 volumio volumiobt[1872]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Dec 27 15:09:15 volumio volumiobt[1872]: INFO:a2dp-agent:Registering agent. Dec 27 15:09:15 volumio volumiobt[1872]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Dec 27 15:09:15 volumio volumiobt[1872]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Dec 27 15:09:15 volumio volumio[1202]: info: Adding audio output: Dec 27 15:09:15 volumio volumiossh-tunnel[1869]: Warning: Permanently added '[us2.myvolumio.org]:2222' (RSA) to the list of known hosts. Dec 27 15:09:16 volumio volumio[1202]: info: Adding audio output: Dec 27 15:09:16 volumio volumio[1202]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 27 15:09:16 volumio volumio[1202]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 27 15:09:16 volumio volumio[1202]: info: Remote SSH Started Dec 27 15:09:17 volumio volumio[1202]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Dec 27 15:09:17 volumio volumio[1202]: info: CoreCommandRouter::volumioStop Dec 27 15:09:17 volumio volumio[1202]: info: CoreStateMachine::stop Dec 27 15:09:17 volumio volumio[1202]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 15:09:17 volumio volumio[1202]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Dec 27 15:09:17 volumio volumio[1202]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 15:09:17 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:09:17 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:09:17 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:09:17 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:09:17 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:09:17 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:09:17 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:09:17 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:09:17 volumio sudo[1881]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 27 15:09:17 volumio sudo[1881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 15:09:17 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 15:09:17 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 15:09:17 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 27 15:09:17 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 27 15:09:17 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 27 15:09:17 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 27 15:09:17 volumio sudo[1881]: pam_unix(sudo:session): session closed for user root Dec 27 15:09:17 volumio mpd_monitor.sh[1892]: MPD Monitor Service: Starting MPD Monitor Service Dec 27 15:09:18 volumio volumio[1202]: info: Access Token successfully retrieved Dec 27 15:09:18 volumio volumio[1202]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 15:09:18 volumio volumio[1202]: Error: upnp: Request failed with status code 403/// Dec 27 15:09:18 volumio volumio[1202]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/sonos/lib/services/Service.js:90:25 Dec 27 15:09:18 volumio volumio[1202]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 27 15:09:18 volumio volumio[1202]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 15:09:24 volumio sudo[1920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-27 15:08' Dec 27 15:09:24 volumio sudo[1920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"