Dec 16 21:26:02 volumiovoicehat volumio[1347]: Starting albumart workers Dec 16 21:26:03 volumiovoicehat volumio[1345]: Starting albumart workers Dec 16 21:26:03 volumiovoicehat volumio[1346]: Starting albumart workers Dec 16 21:26:05 volumiovoicehat mpd[1468]: 2025-12-16T21:26:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 21:26:05 volumiovoicehat systemd[1]: Started mpd.service - Music Player Daemon. Dec 16 21:26:05 volumiovoicehat sudo[1429]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:05 volumiovoicehat sudo[1441]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:05 volumiovoicehat systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 16 21:26:06 volumiovoicehat volumio[1195]: info: Completed starting Core Plugins Dec 16 21:26:06 volumiovoicehat volumio[1195]: info: ------------------------------------------- Dec 16 21:26:06 volumiovoicehat volumio[1195]: info: ----- MyVolumio plugins startup ---- Dec 16 21:26:06 volumiovoicehat volumio[1195]: info: ------------------------------------------- Dec 16 21:26:06 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 16 21:26:06 volumiovoicehat volumio[1195]: info: MPD Permissions set Dec 16 21:26:06 volumiovoicehat volumio[1195]: info: MPD Permissions set Dec 16 21:26:06 volumiovoicehat volumio[1195]: info: Upmpdcli Daemon Started Dec 16 21:26:06 volumiovoicehat volumio[1195]: info: MPD running with PID1468 Dec 16 21:26:06 volumiovoicehat volumio[1195]: ,establishing connection Dec 16 21:26:06 volumiovoicehat volumio[1195]: info: Volumio called home Dec 16 21:26:07 volumiovoicehat systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 16 21:26:07 volumiovoicehat systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 16 21:26:07 volumiovoicehat systemd[1]: setdatetime-helper.service: Consumed 1.052s CPU time. Dec 16 21:26:09 volumiovoicehat volumio[1195]: error: MPD error: The expression evaluated to a falsy value: Dec 16 21:26:09 volumiovoicehat volumio[1195]: assert.ok(self.idling) Dec 16 21:26:09 volumiovoicehat volumio[1195]: error: The expression evaluated to a falsy value: Dec 16 21:26:09 volumiovoicehat volumio[1195]: assert.ok(self.idling) Dec 16 21:26:09 volumiovoicehat volumio[1195]: error: MPD error: The expression evaluated to a falsy value: Dec 16 21:26:09 volumiovoicehat volumio[1195]: assert.ok(self.idling) Dec 16 21:26:09 volumiovoicehat volumio[1195]: error: The expression evaluated to a falsy value: Dec 16 21:26:09 volumiovoicehat volumio[1195]: assert.ok(self.idling) Dec 16 21:26:09 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:09 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:09 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:09 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:09 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:09 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:09 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:09 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:09 volumiovoicehat sudo[1552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 16 21:26:09 volumiovoicehat sudo[1552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:09 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:26:09 volumiovoicehat sudo[1554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 16 21:26:09 volumiovoicehat sudo[1554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:09 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:09 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:09 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:09 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:09 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:09 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:09 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:09 volumiovoicehat systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 16 21:26:09 volumiovoicehat mpd_monitor.sh[1563]: MPD Monitor Service: Starting MPD Monitor Service Dec 16 21:26:10 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:10 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:10 volumiovoicehat sudo[1554]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:10 volumiovoicehat systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 16 21:26:10 volumiovoicehat systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 16 21:26:10 volumiovoicehat systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 16 21:26:10 volumiovoicehat systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 16 21:26:10 volumiovoicehat mpd_monitor.sh[1569]: MPD Monitor Service: Starting MPD Monitor Service Dec 16 21:26:10 volumiovoicehat sudo[1552]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:10 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:26:10 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:10 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:26:10 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:26:10 volumiovoicehat volumio[1195]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 16 21:26:10 volumiovoicehat volumio[1195]: error: updateQueue error: null Dec 16 21:26:10 volumiovoicehat volumio[1195]: info: Successfully started MPD Monitor Dec 16 21:26:10 volumiovoicehat volumio[1195]: info: Successfully started MPD Monitor Dec 16 21:26:10 volumiovoicehat volumio[1195]: info: Listing playlists Dec 16 21:26:10 volumiovoicehat volumio[1195]: info: Listing playlists Dec 16 21:26:11 volumiovoicehat volumio[1195]: info: Starting Shairport Sync Dec 16 21:26:11 volumiovoicehat volumio[1195]: info: Starting Shairport Sync Dec 16 21:26:11 volumiovoicehat volumio[1195]: info: Starting Shairport Sync Dec 16 21:26:11 volumiovoicehat sudo[1580]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 21:26:11 volumiovoicehat sudo[1580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:11 volumiovoicehat sudo[1584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 21:26:11 volumiovoicehat sudo[1584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:11 volumiovoicehat sudo[1582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 21:26:11 volumiovoicehat sudo[1582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:11 volumiovoicehat systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 16 21:26:11 volumiovoicehat systemd[1]: shairport-sync.service: Deactivated successfully. Dec 16 21:26:11 volumiovoicehat systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 16 21:26:11 volumiovoicehat systemd[1]: shairport-sync.service: Consumed 2.152s CPU time. Dec 16 21:26:11 volumiovoicehat systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 16 21:26:11 volumiovoicehat sudo[1580]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:11 volumiovoicehat systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 16 21:26:11 volumiovoicehat systemd[1]: shairport-sync.service: Deactivated successfully. Dec 16 21:26:11 volumiovoicehat systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 16 21:26:11 volumiovoicehat systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 16 21:26:11 volumiovoicehat systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 16 21:26:11 volumiovoicehat systemd[1]: shairport-sync.service: Deactivated successfully. Dec 16 21:26:11 volumiovoicehat systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 16 21:26:11 volumiovoicehat systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 16 21:26:11 volumiovoicehat sudo[1584]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:11 volumiovoicehat sudo[1582]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:11 volumiovoicehat volumio[1195]: info: Shairport-Sync Started Dec 16 21:26:12 volumiovoicehat volumio[1195]: Error adding Membership: Error: addMembership EINVAL Dec 16 21:26:12 volumiovoicehat volumio[1195]: info: Shairport-Sync Started Dec 16 21:26:12 volumiovoicehat volumio[1195]: info: Shairport-Sync Started Dec 16 21:26:12 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:26:12 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:26:14 volumiovoicehat volumio[1195]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: Adding plugin bluetooth to MyMusic Plugins Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: Adding plugin multiroom to MyMusic Plugins Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: Adding plugin metavolumio to MyMusic Plugins Dec 16 21:26:15 volumiovoicehat volumio[1195]: info: Adding plugin cd_controller to MyMusic Plugins Dec 16 21:26:16 volumiovoicehat volumio[1195]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 16 21:26:16 volumiovoicehat volumio[1195]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 16 21:26:16 volumiovoicehat volumio[1195]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 16 21:26:16 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 16 21:26:23 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 16 21:26:23 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 16 21:26:23 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:26:23 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:26:23 volumiovoicehat volumio[1195]: info: Starting MyVolumio Remote Streaming Endpoints Dec 16 21:26:23 volumiovoicehat volumio[1195]: info: MyVolumio login type: Token Dec 16 21:26:23 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 16 21:26:23 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 16 21:26:30 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 16 21:26:30 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 16 21:26:30 volumiovoicehat volumio[1195]: info: Streaming services startup Dec 16 21:26:30 volumiovoicehat volumio[1195]: info: Starting Streaming Daemon Dec 16 21:26:30 volumiovoicehat volumio[1195]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 16 21:26:30 volumiovoicehat sudo[1643]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 16 21:26:30 volumiovoicehat sudo[1643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:30 volumiovoicehat sudo[1643]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:30 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 21:26:30 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:26:30 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 21:26:31 volumiovoicehat volumio[1195]: error: Cannot start Volumio Streaming Daemon Dec 16 21:26:31 volumiovoicehat volumio[1195]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 16 21:26:31 volumiovoicehat volumio[1195]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 16 21:26:31 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:26:31 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:26:31 volumiovoicehat volumio[1195]: info: Listing playlists Dec 16 21:26:31 volumiovoicehat volumio[1195]: info: Listing playlists Dec 16 21:26:31 volumiovoicehat volumio[1195]: error: MyVolumio Custom Token format not valid, refreshing it Dec 16 21:26:32 volumiovoicehat volumio-remote-updater[699]: Test mode disabled Dec 16 21:26:32 volumiovoicehat volumio-remote-updater[699]: Alpha mode disabled Dec 16 21:26:32 volumiovoicehat volumio-remote-updater[699]: Alpha legacy test mode disabled Dec 16 21:26:32 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 16 21:26:33 volumiovoicehat volumio[1195]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 16 21:26:33 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 16 21:26:33 volumiovoicehat volumio[1195]: info: MyVolumio login type: Token Dec 16 21:26:34 volumiovoicehat sudo[1656]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 21:26:34 volumiovoicehat sudo[1656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:34 volumiovoicehat sudo[1656]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:34 volumiovoicehat sudo[1658]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 21:26:34 volumiovoicehat sudo[1658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:34 volumiovoicehat sudo[1658]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:34 volumiovoicehat volumio[1195]: verbose: New Socket.io Connection to 192.168.8.120 from 192.168.8.113 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: Received Get System Info Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: Discovery: Getting this device information Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: Listing playlists Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 21:26:35 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 21:26:36 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 16 21:26:36 volumiovoicehat volumio[1195]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 16 21:26:37 volumiovoicehat volumio[1195]: info: MyVolumio token set successfully Dec 16 21:26:37 volumiovoicehat volumio[1195]: info: MYVOLUMIO: Adding device Dec 16 21:26:37 volumiovoicehat volumio[1195]: info: MYVOLUMIO: Evaluating Server Dec 16 21:26:37 volumiovoicehat sudo[1688]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 21:26:37 volumiovoicehat sudo[1688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:37 volumiovoicehat sudo[1690]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 21:26:37 volumiovoicehat sudo[1690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:37 volumiovoicehat sudo[1688]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:37 volumiovoicehat sudo[1690]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:38 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 21:26:38 volumiovoicehat volumio[1195]: info: MyVolumio status changed Dec 16 21:26:38 volumiovoicehat volumio[1195]: info: Streaming services startup Dec 16 21:26:38 volumiovoicehat volumio[1195]: info: Starting Streaming Daemon Dec 16 21:26:38 volumiovoicehat volumio[1195]: info: Removing browser output: myVolumio user plan is not superstar Dec 16 21:26:38 volumiovoicehat volumio[1195]: info: Removing audio output: Dec 16 21:26:38 volumiovoicehat volumio[1195]: info: Stoppping Tunnel 1 Dec 16 21:26:38 volumiovoicehat sudo[1712]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 16 21:26:38 volumiovoicehat sudo[1712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:39 volumiovoicehat sudo[1714]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 16 21:26:39 volumiovoicehat sudo[1714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:39 volumiovoicehat sudo[1712]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:39 volumiovoicehat 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 16 21:26:39 volumiovoicehat 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 16 21:26:39 volumiovoicehat 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 16 21:26:39 volumiovoicehat 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 16 21:26:39 volumiovoicehat 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 16 21:26:39 volumiovoicehat 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 16 21:26:39 volumiovoicehat 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 16 21:26:39 volumiovoicehat 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 16 21:26:39 volumiovoicehat sudo[1714]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:39 volumiovoicehat volumio[1195]: info: Setting Geolocation for MyVolumio to eu3 Dec 16 21:26:39 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:26:39 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:26:39 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:26:39 volumiovoicehat volumio[1195]: error: Cannot start Volumio Streaming Daemon Dec 16 21:26:39 volumiovoicehat volumio[1195]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 16 21:26:39 volumiovoicehat volumio[1195]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 16 21:26:39 volumiovoicehat volumio[1195]: verbose: New Socket.io Connection to 192.168.8.120 from 192.168.8.113 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 16 21:26:39 volumiovoicehat volumio[1195]: 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 16 21:26:39 volumiovoicehat volumio[1195]: info: Remote SSH Stopped Dec 16 21:26:39 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:26:39 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 21:26:40 volumiovoicehat volumio[1195]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: Received Get System Info Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: Discovery: Getting this device information Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: Listing playlists Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: Updating MyVolumio device info Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:26:40 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 16 21:26:41 volumiovoicehat volumio[1195]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 16 21:26:41 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 21:26:41 volumiovoicehat volumio[1195]: info: Received Get System Info Dec 16 21:26:41 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 21:26:41 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 21:26:41 volumiovoicehat volumio[1195]: info: Discovery: Getting this device information Dec 16 21:26:41 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:26:41 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:26:41 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 21:26:41 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 16 21:26:41 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:26:41 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:26:42 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 16 21:26:43 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:43 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:43 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 16 21:26:43 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 16 21:26:43 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 16 21:26:43 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 16 21:26:43 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetBrowseSources Dec 16 21:26:43 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 21:26:44 volumiovoicehat volumio[1195]: info: MYVOLUMIO: Adding device Dec 16 21:26:44 volumiovoicehat volumio[1195]: info: MYVOLUMIO: Evaluating Server Dec 16 21:26:45 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 21:26:45 volumiovoicehat volumio[1195]: info: Received Get System Info Dec 16 21:26:45 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 21:26:45 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 21:26:45 volumiovoicehat volumio[1195]: info: Discovery: Getting this device information Dec 16 21:26:45 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:26:45 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:26:45 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 21:26:46 volumiovoicehat volumio[1195]: info: Setting Geolocation for MyVolumio to eu5 Dec 16 21:26:46 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:26:46 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:26:46 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:26:46 volumiovoicehat volumio[1195]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 21:26:46 volumiovoicehat volumio[1195]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 16 21:26:46 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 16 21:26:47 volumiovoicehat volumio[1195]: info: Updating MyVolumio device info Dec 16 21:26:47 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:26:47 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:26:47 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:26:47 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 16 21:26:47 volumiovoicehat sudo[1757]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 16 21:26:47 volumiovoicehat sudo[1757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:47 volumiovoicehat sudo[1762]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 16 21:26:47 volumiovoicehat sudo[1762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:47 volumiovoicehat sudo[1762]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:47 volumiovoicehat sudo[1768]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 16 21:26:47 volumiovoicehat sudo[1768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:47 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 16 21:26:47 volumiovoicehat sudo[1757]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:47 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 16 21:26:47 volumiovoicehat sudo[1768]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:47 volumiovoicehat sudo[1774]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 16 21:26:47 volumiovoicehat sudo[1774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:47 volumiovoicehat sudo[1779]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 21:26:47 volumiovoicehat sudo[1779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:47 volumiovoicehat sudo[1783]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 21:26:47 volumiovoicehat sudo[1786]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 21:26:47 volumiovoicehat sudo[1786]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:47 volumiovoicehat sudo[1783]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:26:47 volumiovoicehat sudo[1774]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:47 volumiovoicehat sudo[1779]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:47 volumiovoicehat sudo[1783]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:48 volumiovoicehat volumio[1195]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 16 21:26:48 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:26:48 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:26:48 volumiovoicehat volumio[1195]: info: Listing playlists Dec 16 21:26:48 volumiovoicehat volumio[1195]: info: Listing playlists Dec 16 21:26:48 volumiovoicehat sudo[1786]: pam_unix(sudo:session): session closed for user root Dec 16 21:26:49 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 16 21:26:50 volumiovoicehat volumio[1195]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 16 21:26:50 volumiovoicehat volumio[1195]: info: Completed starting MyVolumio Plugin Dec 16 21:26:50 volumiovoicehat volumio[1195]: [Metrics] CommandRouter: 71s 280.73ms Dec 16 21:26:50 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumiosetStartupVolume Dec 16 21:26:50 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:26:50 volumiovoicehat volumio[1195]: info: VolumeController:: Setting startup Volume 5 Dec 16 21:26:50 volumiovoicehat volumio[1195]: info: VolumeController::SetAlsaVolume5 Dec 16 21:26:50 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:26:50 volumiovoicehat volumio[1195]: info: CoreCommandRouter::Close All Modals sent Dec 16 21:26:50 volumiovoicehat volumio[1195]: info: CoreCommandRouter::Close All Modals sent Dec 16 21:26:50 volumiovoicehat volumio[1195]: info: CoreStateMachine::pushState Dec 16 21:26:50 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:26:50 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 21:26:50 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioPushState Dec 16 21:26:50 volumiovoicehat kernel: voicehat-codec voicehat-codec: Enabling audio amp... Dec 16 21:26:51 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 16 21:26:51 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 21:26:51 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 16 21:26:55 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 21:26:55 volumiovoicehat volumio[1195]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 16 21:26:55 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 16 21:26:55 volumiovoicehat volumio[1195]: info: Received Get System Version Dec 16 21:26:55 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 21:26:55 volumiovoicehat volumio[1195]: info: Received Get System Info Dec 16 21:26:56 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 21:26:56 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 21:26:56 volumiovoicehat volumio[1195]: info: Discovery: Getting this device information Dec 16 21:26:56 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:26:56 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:26:56 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 21:26:56 volumiovoicehat kernel: voicehat-codec voicehat-codec: Disabling audio amp... Dec 16 21:26:56 volumiovoicehat upmpdcli[1812]: writing RSA key Dec 16 21:26:57 volumiovoicehat volumio[1195]: info: BOOT COMPLETED Dec 16 21:26:57 volumiovoicehat volumio[1195]: info: mpdhttpout ---Boot completed detected! Patching mpd now! Dec 16 21:26:58 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:26:58 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:26:58 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioPause Dec 16 21:26:58 volumiovoicehat volumio[1195]: info: CoreStateMachine::pause Dec 16 21:26:58 volumiovoicehat volumio[1195]: info: mpdhttpout --- Volumio set on pause Dec 16 21:26:58 volumiovoicehat volumio[1195]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Dec 16 21:26:58 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:26:58 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:27:00 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 16 21:27:00 volumiovoicehat sudo[1821]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 21:27:00 volumiovoicehat sudo[1821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:27:00 volumiovoicehat systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 16 21:27:00 volumiovoicehat systemd[1]: mpd.service: Deactivated successfully. Dec 16 21:27:00 volumiovoicehat systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 16 21:27:00 volumiovoicehat systemd[1]: mpd.service: Consumed 5.718s CPU time. Dec 16 21:27:00 volumiovoicehat systemd[1]: mpd.socket: Deactivated successfully. Dec 16 21:27:00 volumiovoicehat systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 16 21:27:00 volumiovoicehat systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 16 21:27:01 volumiovoicehat systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 16 21:27:01 volumiovoicehat systemd[1]: Starting mpd.service - Music Player Daemon... Dec 16 21:27:01 volumiovoicehat sudo[1824]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 21:27:01 volumiovoicehat sudo[1824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 21:27:01 volumiovoicehat sudo[1824]: pam_unix(sudo:session): session closed for user root Dec 16 21:27:04 volumiovoicehat mpd[1826]: 2025-12-16T21:27:04 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 21:27:04 volumiovoicehat systemd[1]: Started mpd.service - Music Player Daemon. Dec 16 21:27:04 volumiovoicehat sudo[1821]: pam_unix(sudo:session): session closed for user root Dec 16 21:27:04 volumiovoicehat volumio[1195]: error: updateQueue error: null Dec 16 21:27:07 volumiovoicehat sudo[1844]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 16 21:27:07 volumiovoicehat sudo[1844]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:27:07 volumiovoicehat systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 16 21:27:07 volumiovoicehat systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 16 21:27:07 volumiovoicehat systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 16 21:27:07 volumiovoicehat systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 16 21:27:07 volumiovoicehat mpd_monitor.sh[1846]: MPD Monitor Service: Starting MPD Monitor Service Dec 16 21:27:07 volumiovoicehat sudo[1844]: pam_unix(sudo:session): session closed for user root Dec 16 21:27:07 volumiovoicehat volumio[1195]: info: Successfully started MPD Monitor Dec 16 21:27:08 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:27:08 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:27:08 volumiovoicehat volumio[1195]: info: Listing playlists Dec 16 21:27:08 volumiovoicehat volumio[1195]: info: Listing playlists Dec 16 21:27:12 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Dec 16 21:27:12 volumiovoicehat volumio[1195]: info: Saving new wireless network Dec 16 21:27:12 volumiovoicehat sudo[1853]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Dec 16 21:27:12 volumiovoicehat sudo[1853]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:27:12 volumiovoicehat sudo[1853]: pam_unix(sudo:session): session closed for user root Dec 16 21:27:12 volumiovoicehat volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 16 21:27:12 volumiovoicehat volumio[1195]: info: Discovery: Restarting Advertising due to device name change Dec 16 21:27:12 volumiovoicehat sudo[1856]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 16 21:27:12 volumiovoicehat sudo[1856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:27:13 volumiovoicehat systemd[1]: Stopping wireless.service - Wireless Services... Dec 16 21:27:13 volumiovoicehat systemd[1]: wireless.service: Deactivated successfully. Dec 16 21:27:13 volumiovoicehat systemd[1]: Stopped wireless.service - Wireless Services. Dec 16 21:27:13 volumiovoicehat systemd[1]: wireless.service: Consumed 3min 4.963s CPU time. Dec 16 21:27:13 volumiovoicehat systemd[1]: Starting wireless.service - Wireless Services... Dec 16 21:27:13 volumiovoicehat volumio[1195]: info: Discovery: A device disappeared from network Dec 16 21:27:13 volumiovoicehat volumio[1195]: info: Discovery: Device volumiovoicehat disappeared from network Dec 16 21:27:13 volumiovoicehat volumio[1195]: info: Discovery: A device disappeared from network Dec 16 21:27:14 volumiovoicehat sudo[1871]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 21:27:14 volumiovoicehat sudo[1871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:27:14 volumiovoicehat sudo[1871]: pam_unix(sudo:session): session closed for user root Dec 16 21:27:14 volumiovoicehat wireless.js[1860]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 16 21:27:14 volumiovoicehat wireless.js[1860]: WIRELESS.JS: Wired network status changed to: ---connected--- Dec 16 21:27:14 volumiovoicehat wireless.js[1860]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 16 21:27:14 volumiovoicehat wireless.js[1860]: WIRELESS.JS: Cleaning previous... Dec 16 21:27:14 volumiovoicehat sudo[1881]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 16 21:27:14 volumiovoicehat sudo[1881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 21:27:14 volumiovoicehat sudo[1881]: pam_unix(sudo:session): session closed for user root Dec 16 21:27:14 volumiovoicehat sudo[1884]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 16 21:27:14 volumiovoicehat sudo[1884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 21:27:15 volumiovoicehat sudo[1884]: pam_unix(sudo:session): session closed for user root Dec 16 21:27:15 volumiovoicehat sudo[1885]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 21:27:15 volumiovoicehat sudo[1885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:27:15 volumiovoicehat sudo[1885]: pam_unix(sudo:session): session closed for user root Dec 16 21:27:15 volumiovoicehat wireless.js[1860]: WIRELESS.JS: Stopped aP Dec 16 21:27:16 volumiovoicehat sudo[1896]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 16 21:27:16 volumiovoicehat sudo[1896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:27:16 volumiovoicehat sudo[1896]: pam_unix(sudo:session): session closed for user root Dec 16 21:27:16 volumiovoicehat kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 16 21:27:16 volumiovoicehat sudo[1893]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 21:27:16 volumiovoicehat sudo[1893]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:27:16 volumiovoicehat sudo[1898]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 16 21:27:16 volumiovoicehat sudo[1898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:27:16 volumiovoicehat sudo[1893]: pam_unix(sudo:session): session closed for user root Dec 16 21:27:16 volumiovoicehat sudo[1898]: pam_unix(sudo:session): session closed for user root Dec 16 21:27:16 volumiovoicehat sudo[1915]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 16 21:27:16 volumiovoicehat sudo[1915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:27:16 volumiovoicehat sudo[1915]: pam_unix(sudo:session): session closed for user root Dec 16 21:27:16 volumiovoicehat sudo[1922]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 16 21:27:16 volumiovoicehat sudo[1922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:27:16 volumiovoicehat sudo[1922]: pam_unix(sudo:session): session closed for user root Dec 16 21:27:16 volumiovoicehat wireless.js[1860]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: GB Dec 16 21:27:17 volumiovoicehat sudo[1931]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 16 21:27:17 volumiovoicehat sudo[1931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:27:17 volumiovoicehat sudo[1931]: pam_unix(sudo:session): session closed for user root Dec 16 21:27:17 volumiovoicehat sudo[1928]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 21:27:17 volumiovoicehat sudo[1928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:27:17 volumiovoicehat sudo[1933]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set GB Dec 16 21:27:17 volumiovoicehat sudo[1933]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:27:17 volumiovoicehat sudo[1928]: pam_unix(sudo:session): session closed for user root Dec 16 21:27:17 volumiovoicehat sudo[1933]: pam_unix(sudo:session): session closed for user root Dec 16 21:27:17 volumiovoicehat wireless.js[1860]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: GB Dec 16 21:27:17 volumiovoicehat wireless.js[1860]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow Dec 16 21:27:17 volumiovoicehat wireless.js[1860]: WIRELESS.JS: Notified systemd about wireless ready Dec 16 21:27:17 volumiovoicehat systemd[1]: Started wireless.service - Wireless Services. Dec 16 21:27:17 volumiovoicehat kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 16 21:27:17 volumiovoicehat sudo[1856]: pam_unix(sudo:session): session closed for user root Dec 16 21:27:17 volumiovoicehat volumio[1195]: info: Discovery: Started advertising with name: VolumioVoiceHAT Dec 16 21:27:18 volumiovoicehat sudo[1943]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 21:27:18 volumiovoicehat sudo[1943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 21:27:18 volumiovoicehat sudo[1943]: pam_unix(sudo:session): session closed for user root Dec 16 21:27:18 volumiovoicehat volumio[1195]: info: CoreCommandRouter::volumioGetState Dec 16 21:27:18 volumiovoicehat volumio[1195]: info: CorePlayQueue::getTrack 0 Dec 16 21:27:18 volumiovoicehat volumio[1195]: compat.c: read() failed: Resource temporarily unavailable Dec 16 21:27:18 volumiovoicehat volumio[1195]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 21:27:18 volumiovoicehat volumio[1195]: Error: dns service error: unknown Dec 16 21:27:18 volumiovoicehat volumio[1195]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 16 21:27:18 volumiovoicehat volumio[1195]: errorCode: -65537 Dec 16 21:27:18 volumiovoicehat volumio[1195]: } Dec 16 21:27:18 volumiovoicehat volumio[1195]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 21:27:25 volumiovoicehat sudo[1961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-16 21:26' Dec 16 21:27:25 volumiovoicehat sudo[1961]: 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"