Jan 25 13:45:03 garage kernel: vc4-drm soc:gpu: [drm] *ERROR* flip_done timed out Jan 25 13:45:03 garage kernel: vc4-drm soc:gpu: [drm] *ERROR* [PLANE:96:plane-4] commit wait timed out Jan 25 13:45:06 garage volumio[1181]: info: PLUGIN START: now_playing Jan 25 13:45:07 garage volumio[1181]: info: [now-playing] ConfigUpdater: config is up to date. Jan 25 13:45:07 garage volumio[1181]: info: [now-playing] App is listening on port 4004. Jan 25 13:45:07 garage volumio[1181]: info: [now-playing] Backing up /opt/volumiokiosk.sh to /home/volumio/.now_playing/volumiokiosk.sh.bak Jan 25 13:45:07 garage sudo[1761]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:3000/localhost:4004/g /opt/volumiokiosk.sh Jan 25 13:45:07 garage sudo[1761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 13:45:08 garage sudo[1761]: pam_unix(sudo:session): session closed for user root Jan 25 13:45:08 garage volumio[1181]: [sudo] password for volumio: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Jan 25 13:45:08 garage sudo[1764]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Jan 25 13:45:08 garage sudo[1764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 13:45:08 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 13:45:08 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:45:08 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 25 13:45:08 garage volumio[1181]: error: Plugin system_controller volumio5onboarding failed to complete 'onStart' in a timely fashion Jan 25 13:45:08 garage volumio[1181]: error: Plugin music_service mpd failed to complete 'onStart' in a timely fashion Jan 25 13:45:08 garage volumio[1181]: info: ------------------------------------------- Jan 25 13:45:08 garage volumio[1181]: info: ----- MyVolumio plugins startup ---- Jan 25 13:45:08 garage volumio[1181]: info: ------------------------------------------- Jan 25 13:45:08 garage volumio[1181]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 25 13:45:08 garage volumio[1181]: info: Disabling plugin touch_display Jan 25 13:45:08 garage volumio[1181]: info: Done. Jan 25 13:45:08 garage volumio[1181]: info: CoreCommandRouter::volumioGetState Jan 25 13:45:08 garage volumio[1181]: info: CorePlayQueue::getTrack 0 Jan 25 13:45:08 garage volumio[1181]: info: Listing playlists Jan 25 13:45:08 garage volumio[1181]: info: Listing playlists Jan 25 13:45:11 garage volumio[1181]: info: CoreCommandRouter::volumioPlay Jan 25 13:45:11 garage volumio[1181]: info: CoreStateMachine::play index 2 Jan 25 13:45:11 garage volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:45:11 garage volumio[1181]: info: CoreStateMachine::stop Jan 25 13:45:11 garage volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:45:11 garage volumio[1181]: info: CoreStateMachine::play index undefined Jan 25 13:45:11 garage volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:45:11 garage volumio[1181]: info: CorePlayQueue::getTrack 2 Jan 25 13:45:11 garage volumio[1181]: info: CoreStateMachine::startPlaybackTimer Jan 25 13:45:11 garage volumio[1181]: info: CorePlayQueue::getTrack 2 Jan 25 13:45:11 garage volumio[1181]: info: [1769345111070] ControllerWebradio::clearAddPlayTrack Jan 25 13:45:11 garage volumio[1181]: verbose: ControllerMpd::sendMpdCommand stop Jan 25 13:45:12 garage volumio[1181]: info: CoreCommandRouter::volumioGetState Jan 25 13:45:12 garage volumio[1181]: info: CorePlayQueue::getTrack 2 Jan 25 13:45:13 garage kernel: vc4-drm soc:gpu: [drm] *ERROR* [CRTC:152:crtc-4] flip_done timed out Jan 25 13:45:16 garage volumio[1181]: info: CoreCommandRouter::volumioPlay Jan 25 13:45:16 garage volumio[1181]: info: CoreStateMachine::play index 2 Jan 25 13:45:16 garage volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:45:16 garage volumio[1181]: info: CoreStateMachine::stop Jan 25 13:45:16 garage volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:45:16 garage volumio[1181]: info: CoreStateMachine::play index undefined Jan 25 13:45:16 garage volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:45:16 garage volumio[1181]: info: CorePlayQueue::getTrack 2 Jan 25 13:45:16 garage volumio[1181]: info: CoreStateMachine::startPlaybackTimer Jan 25 13:45:16 garage volumio[1181]: info: CorePlayQueue::getTrack 2 Jan 25 13:45:16 garage volumio[1181]: info: [1769345116349] ControllerWebradio::clearAddPlayTrack Jan 25 13:45:16 garage volumio[1181]: verbose: ControllerMpd::sendMpdCommand stop Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 25 13:45:17 garage volumio[1181]: info: Adding plugin bluetooth to MyMusic Plugins Jan 25 13:45:17 garage volumio[1181]: info: Adding plugin multiroom to MyMusic Plugins Jan 25 13:45:17 garage volumio[1181]: info: Adding plugin metavolumio to MyMusic Plugins Jan 25 13:45:17 garage volumio[1181]: info: Adding plugin cd_controller to MyMusic Plugins Jan 25 13:45:17 garage volumio[1181]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 25 13:45:17 garage volumio[1181]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 25 13:45:17 garage volumio[1181]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 25 13:45:17 garage volumio[1181]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 25 13:45:23 garage volumio[1181]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 25 13:45:23 garage volumio[1181]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 25 13:45:23 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:45:23 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:45:23 garage volumio[1181]: info: Starting MyVolumio Remote Streaming Endpoints Jan 25 13:45:23 garage volumio[1181]: info: MyVolumio login type: Token Jan 25 13:45:23 garage volumio[1181]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 25 13:45:23 garage volumio[1181]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 25 13:45:23 garage kernel: vc4-drm soc:gpu: [drm] *ERROR* flip_done timed out Jan 25 13:45:23 garage kernel: vc4-drm soc:gpu: [drm] *ERROR* [CRTC:152:crtc-4] commit wait timed out Jan 25 13:45:29 garage volumio[1181]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 25 13:45:29 garage volumio[1181]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 25 13:45:29 garage volumio[1181]: info: Streaming services startup Jan 25 13:45:29 garage volumio[1181]: info: Starting Streaming Daemon Jan 25 13:45:29 garage volumio[1181]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 25 13:45:29 garage sudo[1810]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 25 13:45:29 garage sudo[1810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 13:45:29 garage volumio[1181]: info: CoreCommandRouter::volumioPlay Jan 25 13:45:29 garage volumio[1181]: info: CoreStateMachine::play index 2 Jan 25 13:45:29 garage volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:45:29 garage volumio[1181]: info: CoreCommandRouter::volumioPlay Jan 25 13:45:29 garage volumio[1181]: info: CoreStateMachine::play index 2 Jan 25 13:45:29 garage volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:45:29 garage volumio[1181]: info: CoreStateMachine::stop Jan 25 13:45:29 garage volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:45:29 garage volumio[1181]: info: CoreStateMachine::stop Jan 25 13:45:29 garage volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:45:29 garage volumio[1181]: info: CoreStateMachine::play index undefined Jan 25 13:45:29 garage volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:45:29 garage volumio[1181]: info: CoreStateMachine::play index undefined Jan 25 13:45:29 garage volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:45:29 garage volumio[1181]: info: CorePlayQueue::getTrack 2 Jan 25 13:45:29 garage volumio[1181]: info: CoreStateMachine::startPlaybackTimer Jan 25 13:45:29 garage volumio[1181]: info: CorePlayQueue::getTrack 2 Jan 25 13:45:29 garage volumio[1181]: info: [1769345129964] ControllerWebradio::clearAddPlayTrack Jan 25 13:45:29 garage volumio[1181]: verbose: ControllerMpd::sendMpdCommand stop Jan 25 13:45:29 garage volumio[1181]: info: CorePlayQueue::getTrack 2 Jan 25 13:45:29 garage volumio[1181]: info: CoreStateMachine::startPlaybackTimer Jan 25 13:45:29 garage volumio[1181]: info: CorePlayQueue::getTrack 2 Jan 25 13:45:29 garage volumio[1181]: info: [1769345129966] ControllerWebradio::clearAddPlayTrack Jan 25 13:45:29 garage volumio[1181]: verbose: ControllerMpd::sendMpdCommand stop Jan 25 13:45:30 garage volumio[1181]: info: CoreCommandRouter::volumioGetState Jan 25 13:45:30 garage volumio[1181]: info: CorePlayQueue::getTrack 2 Jan 25 13:45:30 garage volumio[1181]: info: Listing playlists Jan 25 13:45:30 garage volumio[1181]: info: Listing playlists Jan 25 13:45:31 garage volumio[1181]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 25 13:45:32 garage volumio[1181]: info: CoreCommandRouter::volumioGetState Jan 25 13:45:32 garage volumio[1181]: info: CorePlayQueue::getTrack 2 Jan 25 13:45:32 garage volumio[1181]: info: MyVolumio token set successfully Jan 25 13:45:32 garage volumio[1181]: info: MYVOLUMIO: Adding device Jan 25 13:45:32 garage volumio[1181]: info: MYVOLUMIO: Evaluating Server Jan 25 13:45:33 garage volumio[1181]: info: MyVolumio status changed Jan 25 13:45:33 garage volumio[1181]: info: Streaming services startup Jan 25 13:45:33 garage volumio[1181]: info: Starting Streaming Daemon Jan 25 13:45:34 garage volumio[1181]: info: Removing browser output: myVolumio user plan is not superstar Jan 25 13:45:34 garage volumio[1181]: info: Removing audio output: Jan 25 13:45:34 garage volumio[1181]: info: Stoppping Tunnel 1 Jan 25 13:45:34 garage sudo[1857]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 25 13:45:34 garage sudo[1857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 13:45:34 garage sudo[1860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 25 13:45:34 garage sudo[1860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 13:45:34 garage kernel: vc4-drm soc:gpu: [drm] *ERROR* flip_done timed out Jan 25 13:45:34 garage kernel: vc4-drm soc:gpu: [drm] *ERROR* [CONNECTOR:154:DSI-1] commit wait timed out Jan 25 13:45:34 garage volumio[1181]: info: Setting Geolocation for MyVolumio to eu10 Jan 25 13:45:34 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:45:34 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:45:34 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:45:34 garage volumio[1181]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 25 13:45:35 garage volumio[1181]: info: Updating MyVolumio device info Jan 25 13:45:35 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:45:35 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:45:35 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:45:35 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 13:45:35 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 13:45:37 garage volumio[1181]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 25 13:45:38 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 25 13:45:38 garage volumio[1181]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jan 25 13:45:38 garage volumio[1181]: info: CoreCommandRouter::volumioGetState Jan 25 13:45:38 garage volumio[1181]: info: CorePlayQueue::getTrack 2 Jan 25 13:45:38 garage volumio[1181]: info: MYVOLUMIO: Adding device Jan 25 13:45:38 garage volumio[1181]: info: MYVOLUMIO: Evaluating Server Jan 25 13:45:40 garage volumio[1181]: info: Setting Geolocation for MyVolumio to eu10 Jan 25 13:45:40 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:45:40 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:45:40 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:45:40 garage volumio[1181]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 25 13:45:41 garage volumio[1181]: info: Updating MyVolumio device info Jan 25 13:45:41 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:45:41 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:45:41 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:45:41 garage volumio[1181]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 25 13:45:42 garage volumio[1181]: info: CoreCommandRouter::volumioGetState Jan 25 13:45:42 garage volumio[1181]: info: CorePlayQueue::getTrack 2 Jan 25 13:45:43 garage volumio[1181]: info: Disabling plugin touch_display Jan 25 13:45:43 garage volumio[1181]: info: Done. Jan 25 13:45:43 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 13:45:43 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 13:45:43 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 25 13:45:43 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 25 13:45:43 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 25 13:45:43 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 25 13:45:43 garage volumio[1181]: info: CoreCommandRouter::volumioGetBrowseSources Jan 25 13:45:43 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 13:45:43 garage volumio[1181]: info: Disabling plugin touch_display Jan 25 13:45:43 garage volumio[1181]: info: Done. Jan 25 13:45:44 garage kernel: vc4-drm soc:gpu: [drm] *ERROR* flip_done timed out Jan 25 13:45:44 garage kernel: vc4-drm soc:gpu: [drm] *ERROR* [PLANE:96:plane-4] commit wait timed out Jan 25 13:45:44 garage volumio[1181]: info: Disabling plugin touch_display Jan 25 13:45:44 garage volumio[1181]: info: Done. Jan 25 13:45:45 garage volumio[1181]: info: Disabling plugin touch_display Jan 25 13:45:45 garage volumio[1181]: info: Done. Jan 25 13:45:45 garage volumio[1181]: info: Disabling plugin touch_display Jan 25 13:45:45 garage volumio[1181]: info: Done. Jan 25 13:45:46 garage volumio[1181]: info: Disabling plugin touch_display Jan 25 13:45:46 garage volumio[1181]: info: Done. Jan 25 13:45:47 garage sudo[1915]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:4004/localhost:3000/g /opt/volumiokiosk.sh Jan 25 13:45:47 garage sudo[1915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 13:45:47 garage sudo[1915]: pam_unix(sudo:session): session closed for user root Jan 25 13:45:47 garage volumio[1181]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Jan 25 13:45:48 garage sudo[1918]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Jan 25 13:45:48 garage sudo[1918]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 13:45:49 garage volumio[1181]: info: Disabling plugin touch_display Jan 25 13:45:49 garage volumio[1181]: info: Done. Jan 25 13:45:49 garage volumio[1181]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 25 13:45:49 garage volumio[1181]: info: Completed starting MyVolumio Plugin Jan 25 13:45:49 garage volumio[1181]: [Metrics] CommandRouter: 111s 312.30ms Jan 25 13:45:49 garage volumio[1181]: info: CoreCommandRouter::volumiosetStartupVolume Jan 25 13:45:49 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 13:45:49 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:45:49 garage volumio[1181]: info: CoreCommandRouter::Close All Modals sent Jan 25 13:45:49 garage volumio[1181]: info: CoreCommandRouter::Close All Modals sent Jan 25 13:45:50 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 25 13:45:50 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 13:45:50 garage volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 25 13:45:50 garage volumio[1181]: info: Disabling plugin touch_display Jan 25 13:45:50 garage volumio[1181]: info: Done. Jan 25 13:45:51 garage volumio[1181]: info: Enabling plugin now_playing Jan 25 13:45:51 garage volumio[1181]: info: Loading plugin "now_playing"... Jan 25 13:45:51 garage volumio[1181]: info: PLUGIN START: now_playing Jan 25 13:45:51 garage volumio[1181]: info: [now-playing] ConfigUpdater: config is up to date. Jan 25 13:45:51 garage volumio[1181]: info: [now-playing] App is listening on port 4004. Jan 25 13:45:51 garage volumio[1181]: info: [now-playing] Backing up /opt/volumiokiosk.sh to /home/volumio/.now_playing/volumiokiosk.sh.bak Jan 25 13:45:52 garage sudo[1937]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:3000/localhost:4004/g /opt/volumiokiosk.sh Jan 25 13:45:52 garage sudo[1937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 13:45:52 garage sudo[1937]: pam_unix(sudo:session): session closed for user root Jan 25 13:45:52 garage volumio[1181]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Jan 25 13:45:52 garage sudo[1940]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Jan 25 13:45:52 garage sudo[1940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 13:45:52 garage volumio[1181]: info: CoreCommandRouter::volumioGetState Jan 25 13:45:52 garage volumio[1181]: info: CorePlayQueue::getTrack 2 Jan 25 13:45:52 garage volumio[1181]: info: Listing playlists Jan 25 13:45:52 garage volumio[1181]: info: Listing playlists Jan 25 13:45:52 garage volumio[1181]: info: Disabling plugin touch_display Jan 25 13:45:52 garage volumio[1181]: info: Done. Jan 25 13:45:53 garage volumio[1181]: info: Disabling plugin touch_display Jan 25 13:45:53 garage volumio[1181]: info: Done. Jan 25 13:45:54 garage volumio[1181]: info: Disabling plugin touch_display Jan 25 13:45:54 garage volumio[1181]: info: Done. Jan 25 13:45:54 garage systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Jan 25 13:45:54 garage kernel: vc4-drm soc:gpu: [drm] *ERROR* [CRTC:152:crtc-4] flip_done timed out Jan 25 13:45:54 garage systemd[1]: Received SIGRTMIN+21 from PID 290 (plymouthd). Jan 25 13:45:54 garage systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Jan 25 13:45:54 garage systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 25 13:45:54 garage systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 25 13:45:55 garage sudo[1764]: pam_unix(sudo:session): session closed for user root Jan 25 13:45:55 garage sudo[1810]: pam_unix(sudo:session): session closed for user root Jan 25 13:45:55 garage sudo[1857]: pam_unix(sudo:session): session closed for user root Jan 25 13:45:55 garage 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. Jan 25 13:45:55 garage 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. Jan 25 13:45:55 garage volumio[1181]: error: [now-playing] Failed to execute systemctl command status on volumio-kiosk: Error: Command failed: /usr/bin/sudo /bin/systemctl status volumio-kiosk Jan 25 13:45:55 garage volumio[1181]: at ChildProcess.exithandler (node:child_process:421:12) Jan 25 13:45:55 garage volumio[1181]: at ChildProcess.emit (node:events:514:28) Jan 25 13:45:55 garage volumio[1181]: at maybeClose (node:internal/child_process:1105:16) Jan 25 13:45:55 garage volumio[1181]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Jan 25 13:45:55 garage sudo[1918]: pam_unix(sudo:session): session closed for user root Jan 25 13:45:55 garage volumio[1181]: info: Error: Error: Command failed: /usr/bin/sudo /bin/systemctl status volumio-kiosk Jan 25 13:45:55 garage volumio[1181]: error: Cannot start Volumio Streaming Daemon Jan 25 13:45:55 garage volumio[1181]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 25 13:45:55 garage volumio[1181]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 25 13:45:55 garage volumio[1181]: error: [now-playing] Failed to execute systemctl command status on volumio-kiosk: Error: Command failed: /usr/bin/sudo /bin/systemctl status volumio-kiosk Jan 25 13:45:55 garage volumio[1181]: at ChildProcess.exithandler (node:child_process:421:12) Jan 25 13:45:55 garage volumio[1181]: at ChildProcess.emit (node:events:514:28) Jan 25 13:45:55 garage volumio[1181]: at maybeClose (node:internal/child_process:1105:16) Jan 25 13:45:55 garage volumio[1181]: at Socket. (node:internal/child_process:457:11) Jan 25 13:45:55 garage volumio[1181]: at Socket.emit (node:events:514:28) Jan 25 13:45:55 garage volumio[1181]: at Pipe. (node:net:337:12) Jan 25 13:45:55 garage volumio[1181]: warn: [now-playing] MyBackgroundMonitor stopped Jan 25 13:45:55 garage volumio[1181]: info: Disabling plugin now_playing Jan 25 13:45:55 garage volumio[1181]: info: Done. Jan 25 13:45:55 garage systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 25 13:45:55 garage go-librespot[1962]: go-librespot daemon starting... Jan 25 13:45:55 garage sudo[1597]: pam_unix(sudo:session): session closed for user root Jan 25 13:45:55 garage systemd[1]: Starting lircd-setup.service - lircd(8) initialization helper tool... Jan 25 13:45:55 garage volumio[1181]: error: Cannot start Volumio Streaming Daemon Jan 25 13:45:55 garage volumio[1181]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 25 13:45:55 garage volumio[1181]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 25 13:45:55 garage systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 25 13:45:55 garage systemd[1]: Starting mpd.service - Music Player Daemon... Jan 25 13:45:55 garage systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 25 13:45:55 garage systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 25 13:45:55 garage systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 25 13:45:55 garage sudo[1520]: pam_unix(sudo:session): session closed for user root Jan 25 13:45:55 garage systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Jan 25 13:45:55 garage volumio[1181]: info: Disabling plugin touch_display Jan 25 13:45:55 garage volumio[1181]: info: Done. Jan 25 13:45:55 garage sudo[1533]: pam_unix(sudo:session): session closed for user root Jan 25 13:45:55 garage systemd[1]: shairport-sync.service: Deactivated successfully. Jan 25 13:45:55 garage systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 25 13:45:55 garage systemd[1]: shairport-sync.service: Consumed 1.764s CPU time. Jan 25 13:45:55 garage volumio[1181]: info: Upmpdcli Daemon Started Jan 25 13:45:55 garage sudo[1940]: pam_unix(sudo:session): session closed for user root Jan 25 13:45:55 garage 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. Jan 25 13:45:55 garage 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. Jan 25 13:45:55 garage lircd-setup[1964]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in Python 3.12. Use ConfigParser directly instead. Jan 25 13:45:55 garage lircd-setup[1964]: parser = configparser.SafeConfigParser() Jan 25 13:45:55 garage 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. Jan 25 13:45:55 garage 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. Jan 25 13:45:55 garage volumio[1181]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 13:45:55 garage systemd[1]: lircd-setup.service: Deactivated successfully. Jan 25 13:45:55 garage systemd[1]: Finished lircd-setup.service - lircd(8) initialization helper tool. Jan 25 13:45:55 garage sudo[1966]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 25 13:45:55 garage sudo[1966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 25 13:45:55 garage 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. Jan 25 13:45:55 garage 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. Jan 25 13:45:55 garage sudo[1860]: pam_unix(sudo:session): session closed for user root Jan 25 13:45:55 garage sudo[1984]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 25 13:45:55 garage sudo[1966]: pam_unix(sudo:session): session closed for user root Jan 25 13:45:55 garage volumio[1181]: TypeError: Cannot read properties of null (reading 'logger') Jan 25 13:45:55 garage volumio[1181]: at NowPlayingContext.getLogger (/data/plugins/user_interface/now_playing/dist/lib/NowPlayingContext.js:72:83) Jan 25 13:45:55 garage volumio[1181]: at /data/plugins/user_interface/now_playing/dist/lib/utils/System.js:62:45 Jan 25 13:45:55 garage volumio[1181]: at ChildProcess.exithandler (node:child_process:429:5) Jan 25 13:45:55 garage volumio[1181]: at ChildProcess.emit (node:events:514:28) Jan 25 13:45:55 garage volumio[1181]: at maybeClose (node:internal/child_process:1105:16) Jan 25 13:45:55 garage volumio[1181]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Jan 25 13:45:55 garage volumio[1181]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 13:45:55 garage systemd[1]: Starting lircd.service - Flexible IR remote input/output application support... Jan 25 13:45:55 garage systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 25 13:45:55 garage sudo[1629]: pam_unix(sudo:session): session closed for user root Jan 25 13:45:55 garage sudo[1633]: pam_unix(sudo:session): session closed for user root Jan 25 13:45:55 garage systemd[1]: Reached target multi-user.target - Multi-User System. Jan 25 13:45:55 garage sudo[1632]: pam_unix(sudo:session): session closed for user root Jan 25 13:45:55 garage lircd-0.10.1[1986]: Info: lircd: Opening log, level: Info Jan 25 13:45:55 garage lircd-0.10.1[1986]: Notice: Version: lircd 0.10.1 Jan 25 13:45:55 garage systemd[1]: Reached target graphical.target - Graphical Interface. Jan 25 13:45:55 garage lircd-0.10.1[1986]: Notice: System info: Linux garage 6.12.47-v7+ #1904 SMP Mon Sep 15 13:05:52 BST 2025 armv7l GNU/Linux Jan 25 13:45:56 garage systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Jan 25 13:45:56 garage systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jan 25 13:45:56 garage systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Jan 25 13:45:56 garage lircd-0.10.1[1986]: Info: Initial device: /dev/lirc0 Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: 'lirc' written to protocols file /sys/class/rc/rc1/protocols Jan 25 13:45:56 garage lircd-0.10.1[1986]: Info: Initial device: /dev/lirc0 Jan 25 13:45:56 garage lircd-0.10.1[1986]: Info: [lirc] protocol is enabled Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Options: driver: default Jan 25 13:45:56 garage lircd[1986]: lircd-0.10.1[1986]: Info: lircd: Opening log, level: Info Jan 25 13:45:56 garage lircd[1986]: lircd-0.10.1[1986]: Notice: Using systemd fd Jan 25 13:45:56 garage lircd[1986]: lircd-0.10.1[1986]: Warning: Running as root Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Options: output: /var/run/lirc/lircd Jan 25 13:45:56 garage lircd[1986]: lircd-0.10.1[1986]: Info: Using remote: rc2. Jan 25 13:45:56 garage lircd[1986]: lircd-0.10.1[1986]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Options: nodaemon: 1 Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Options: logfile: syslog Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Options: immediate-init: 0 Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Options: permission: 666 Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Options: driver-options: Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Options: listen: 0 Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Options: connect: (null) Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Options: userelease: 0 Jan 25 13:45:56 garage systemd[1]: Started lircd.service - Flexible IR remote input/output application support. Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Options: effective_user: (null) Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Options: release_suffix: _EVUP Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Options: allow_simulate: 0 Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Options: repeat_max: 600 Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Options: configfile: /etc/lirc/lircd.conf Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Options: dynamic_codes: (null) Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Current driver: default Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Driver API version: 3 Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Driver version: 0.10.0 Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Jan 25 13:45:56 garage lircd-0.10.1[1986]: Info: lircd: Opening log, level: Info Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: Using systemd fd Jan 25 13:45:56 garage lircd-0.10.1[1986]: Warning: Running as root Jan 25 13:45:56 garage lircd-0.10.1[1986]: Info: Using remote: rc2. Jan 25 13:45:56 garage lircd-0.10.1[1986]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jan 25 13:45:56 garage sudo[1688]: pam_unix(sudo:session): session closed for user root Jan 25 13:45:56 garage go-librespot[1963]: time="2026-01-25T13:45:56+01:00" level=info msg="running go-librespot 0.4.0" Jan 25 13:45:56 garage go-librespot[1963]: time="2026-01-25T13:45:56+01:00" level=debug msg="app state loaded" Jan 25 13:45:56 garage go-librespot[1963]: time="2026-01-25T13:45:56+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 25 13:45:56 garage sudo[1665]: pam_unix(sudo:session): session closed for user root Jan 25 13:45:56 garage volumio5-onboarding[1968]: time=2026-01-25T13:45:56.525+01:00 level=INFO msg="running volumio5-device-gateway" version=9838fecf+CHANGES buildDate=2026-01-16T16:06:14Z Jan 25 13:45:57 garage go-librespot[1963]: time="2026-01-25T13:45:57+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 25 13:45:57 garage go-librespot[1963]: time="2026-01-25T13:45:57+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 25 13:45:57 garage go-librespot[1963]: time="2026-01-25T13:45:57+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 25 13:45:57 garage go-librespot[1963]: time="2026-01-25T13:45:57+01:00" level=info msg="zeroconf server listening on port 41663" Jan 25 13:45:57 garage go-librespot[1963]: time="2026-01-25T13:45:57+01:00" level=debug msg="obtained new client token: AADmRfxVD9PIp9l/Ym8nGTeTsJt/i3hLkdS2JTE69WC3A4HBYrRf2OyJeJxXT/3Hk90DKWT3SwSJn/wF6/QfVOjutfdZiYnz2KnlptDc6bjohNP2elMC7MYje97XOruoOgotvQSv9y3lZq+p80dbBKOg1SPegPsLFG+mK0qB4EJxXMn0GyiGW+IhkMK370Sx3q3surxEH++JoxR4dvQMF3UzlT91V4mhxcoqTEx6/G2jf9OvZyDh27hiyA0=" Jan 25 13:45:57 garage go-librespot[1963]: time="2026-01-25T13:45:57+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jan 25 13:46:03 garage sudo[2027]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-25 13:45' Jan 25 13:46:03 garage sudo[2027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 13:46:03 garage mpd[2018]: 2026-01-25T13:46:03 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 25 13:46:03 garage systemd[1]: Started mpd.service - Music Player Daemon. Jan 25 13:46:03 garage systemd[1]: Startup finished in 1min 48.847s (kernel) + 2min 35.431s (userspace) = 4min 24.279s. Jan 25 13:46:03 garage sudo[1537]: pam_unix(sudo:session): session closed for user root Jan 25 13:46:03 garage sudo[1525]: pam_unix(sudo:session): session closed for user root 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="2d08ec68ade0b018167f2db96c3e58a38ca68229" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Jan 20 18:01:18 UTC 2026" VOLUMIO_VERSION="4.089" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="9885f1e3a558c3ea9d61decc103defba"