Feb 11 19:10:02 volumio systemd[1]: Reloading. Feb 11 19:10:03 volumio sudo[1658]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:05 volumio mpd[1609]: 2026-02-11T19:10:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 11 19:10:06 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 11 19:10:06 volumio sudo[1596]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:06 volumio sudo[1581]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:06 volumio sudo[1660]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:10 volumio volumio[1230]: info: [now-playing] App is listening on port 4004. Feb 11 19:10:10 volumio volumio[1230]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Feb 11 19:10:11 volumio volumio-remote-updater[668]: [2026-02-11 19:10:11] [connect] Successful connection Feb 11 19:10:11 volumio volumio[1230]: info: touch_display: No backlight interface detected. Feb 11 19:10:11 volumio volumio[1230]: verbose: New Socket.io Connection to 192.168.5.190 from 192.168.5.46 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/29.0 Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 11 19:10:11 volumio volumio[1230]: info: touch_display: systemctl daemon-reload succeeded. Feb 11 19:10:12 volumio volumio[1230]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 11 19:10:12 volumio volumio[1230]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 11 19:10:12 volumio volumio[1230]: info: MPD Permissions set Feb 11 19:10:12 volumio volumio[1230]: info: MPD Permissions set Feb 11 19:10:12 volumio volumio[1230]: info: Upmpdcli Daemon Started Feb 11 19:10:12 volumio sudo[1734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 11 19:10:12 volumio sudo[1734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:10:12 volumio volumio[1230]: info: Volumio called home Feb 11 19:10:12 volumio sudo[1734]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:13 volumio sudo[1736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Feb 11 19:10:13 volumio sudo[1736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:10:13 volumio sudo[1736]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:13 volumio volumio[1230]: error: MPD error: The expression evaluated to a falsy value: Feb 11 19:10:13 volumio volumio[1230]: assert.ok(self.idling) Feb 11 19:10:13 volumio volumio[1230]: error: The expression evaluated to a falsy value: Feb 11 19:10:13 volumio volumio[1230]: assert.ok(self.idling) Feb 11 19:10:13 volumio volumio[1230]: info: MPD running with PID1609 Feb 11 19:10:13 volumio volumio[1230]: ,establishing connection Feb 11 19:10:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:14 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 19:10:14 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:14 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:14 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:14 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:14 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:14 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:14 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:14 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:14 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:14 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 19:10:14 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:14 volumio volumio[1230]: error: updateQueue error: null Feb 11 19:10:15 volumio volumio-remote-updater[668]: [2026-02-11 19:10:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770837011 101 Feb 11 19:10:15 volumio volumio[1230]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 7 Feb 11 19:10:15 volumio volumio[1230]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Feb 11 19:10:15 volumio volumio[1230]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Feb 11 19:10:15 volumio volumio[1230]: info: Completed starting Core Plugins Feb 11 19:10:15 volumio volumio[1230]: info: ------------------------------------------- Feb 11 19:10:15 volumio volumio[1230]: info: ----- MyVolumio plugins startup ---- Feb 11 19:10:15 volumio volumio[1230]: info: ------------------------------------------- Feb 11 19:10:15 volumio volumio[1230]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 11 19:10:15 volumio volumio[1230]: info: touch_display: IframeKeyboardBridge extension installed successfully Feb 11 19:10:16 volumio sudo[1754]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 19:10:16 volumio sudo[1754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:10:16 volumio sudo[1754]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:16 volumio sudo[1756]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 19:10:16 volumio sudo[1756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:10:16 volumio sudo[1756]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:16 volumio volumio[1230]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 11 19:10:16 volumio volumio[1230]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 11 19:10:16 volumio volumio[1230]: error: updateQueue error: null Feb 11 19:10:17 volumio sudo[1761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 11 19:10:17 volumio sudo[1761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:10:17 volumio sudo[1761]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:17 volumio volumio[1230]: info: Starting Shairport Sync Feb 11 19:10:17 volumio volumio[1230]: info: Starting Shairport Sync Feb 11 19:10:17 volumio volumio[1230]: info: Starting Shairport Sync Feb 11 19:10:17 volumio sudo[1764]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 19:10:17 volumio sudo[1764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:10:17 volumio sudo[1766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 19:10:17 volumio sudo[1766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:10:17 volumio sudo[1768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 19:10:17 volumio sudo[1768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:10:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 19:10:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 19:10:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 19:10:17 volumio systemd[1]: shairport-sync.service: Consumed 2.332s CPU time. Feb 11 19:10:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 19:10:17 volumio sudo[1764]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:17 volumio sudo[1766]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:17 volumio volumio[1230]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 11 19:10:18 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 19:10:18 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 19:10:18 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 19:10:18 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 19:10:18 volumio sudo[1768]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:18 volumio volumio[1230]: verbose: New Socket.io Connection to 192.168.5.190 from 192.168.5.46 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/29.0 Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Feb 11 19:10:18 volumio volumio[1230]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 11 19:10:19 volumio volumio[1230]: info: Shairport-Sync Started Feb 11 19:10:19 volumio volumio[1230]: Error adding Membership: Error: addMembership EINVAL Feb 11 19:10:19 volumio volumio[1230]: info: Shairport-Sync Started Feb 11 19:10:19 volumio volumio[1230]: verbose: New Socket.io Connection to 192.168.5.190 from 192.168.5.46 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/29.0 Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Feb 11 19:10:19 volumio volumio[1230]: info: Shairport-Sync Started Feb 11 19:10:19 volumio volumio[1230]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Feb 11 19:10:20 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:10:20 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:21 volumio volumio[1230]: verbose: New Socket.io Connection to 192.168.5.190 from 192.168.5.46 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/29.0 Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Feb 11 19:10:21 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:10:22 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 11 19:10:22 volumio volumio[1230]: info: Received Get System Info Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 19:10:22 volumio volumio[1230]: info: Discovery: Getting this device information Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:10:22 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:10:22 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:22 volumio volumio[1230]: info: Listing playlists Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 19:10:22 volumio volumio[1230]: info: Received Get System Info Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 19:10:22 volumio volumio[1230]: info: Discovery: Getting this device information Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:10:22 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:10:22 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 11 19:10:22 volumio volumio[1230]: info: Received Get System Info Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 19:10:22 volumio volumio[1230]: info: Discovery: Getting this device information Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:10:22 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 19:10:22 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:10:22 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:22 volumio volumio[1230]: info: Listing playlists Feb 11 19:10:23 volumio volumio[1230]: info: pi_screen_setup: getUIConfig - wizardStep=0, wizardComplete=true, migrationState=none (cache=none, stepCache=0) Feb 11 19:10:23 volumio volumio[1230]: info: pi_screen_setup: getUIConfig - checkMigration=false Feb 11 19:10:23 volumio volumio[1230]: info: pi_screen_setup: OTA drift section hidden=true (driftDetected=false, wizardComplete=true) Feb 11 19:10:23 volumio volumio[1230]: info: pi_screen_setup: Section 1 - default hidden, checkMigration=false, migrationState=none Feb 11 19:10:23 volumio volumio[1230]: info: pi_screen_setup: populateWizardSections - wizardStep=0, wizardComplete=true Feb 11 19:10:23 volumio volumio[1230]: info: pi_screen_setup: populateWizardSections - primaryOutput=hdmi0 (cache=null) Feb 11 19:10:23 volumio volumio[1230]: info: pi_screen_setup: Step 0 section hidden=true (wizardStep=0, migrationState=none) Feb 11 19:10:23 volumio volumio[1230]: info: pi_screen_setup: Step 1 section hidden=true (wizardStep=0) Feb 11 19:10:23 volumio volumio[1230]: info: pi_screen_setup: Step 2 HDMI section hidden=true (showHdmi=false, primaryOutput=hdmi0) Feb 11 19:10:23 volumio volumio[1230]: info: pi_screen_setup: generateConfigSummary - primaryOutput=hdmi0 (cache=null) Feb 11 19:10:23 volumio volumio[1230]: info: pi_screen_setup: generateConfigSummary - displayPreset=auto Feb 11 19:10:23 volumio volumio[1230]: info: pi_screen_setup: generateConfigSummary - rotation=0 Feb 11 19:10:23 volumio volumio[1230]: info: pi_screen_setup: generateConfigSummary - wizardDataCache={} Feb 11 19:10:23 volumio volumio[1230]: info: pi_screen_setup: generateConfigSummary - cmaOption=default Feb 11 19:10:23 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 11 19:10:24 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 11 19:10:24 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 19:10:24 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 19:10:24 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 19:10:24 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 11 19:10:24 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 11 19:10:24 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 11 19:10:24 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 11 19:10:24 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 11 19:10:24 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 11 19:10:24 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 11 19:10:24 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 11 19:10:25 volumio volumio[1230]: info: Adding plugin bluetooth to MyMusic Plugins Feb 11 19:10:25 volumio volumio[1230]: info: Adding plugin multiroom to MyMusic Plugins Feb 11 19:10:25 volumio volumio[1230]: info: Adding plugin metavolumio to MyMusic Plugins Feb 11 19:10:25 volumio volumio[1230]: info: Adding plugin cd_controller to MyMusic Plugins Feb 11 19:10:25 volumio volumio[1230]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 11 19:10:25 volumio volumio[1230]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 11 19:10:25 volumio volumio[1230]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 11 19:10:25 volumio volumio[1230]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 11 19:10:36 volumio volumio[1230]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 11 19:10:36 volumio volumio[1230]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 11 19:10:36 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 19:10:36 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 19:10:36 volumio volumio[1230]: info: Starting MyVolumio Remote Streaming Endpoints Feb 11 19:10:37 volumio volumio[1230]: info: MyVolumio login type: Token Feb 11 19:10:37 volumio volumio[1230]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 11 19:10:37 volumio volumio[1230]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 11 19:10:46 volumio volumio[1230]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 11 19:10:46 volumio volumio[1230]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 11 19:10:46 volumio volumio[1230]: info: Streaming services startup Feb 11 19:10:46 volumio volumio[1230]: info: Starting Streaming Daemon Feb 11 19:10:47 volumio volumio[1230]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 11 19:10:47 volumio sudo[1847]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 11 19:10:47 volumio sudo[1847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:10:47 volumio sudo[1847]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:47 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 11 19:10:47 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:10:47 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:48 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 19:10:48 volumio volumio[1230]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 11 19:10:48 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 11 19:10:48 volumio volumio[1230]: info: Received Get System Version Feb 11 19:10:48 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 19:10:48 volumio volumio[1230]: info: Received Get System Info Feb 11 19:10:48 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 19:10:48 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 19:10:48 volumio volumio[1230]: info: Discovery: Getting this device information Feb 11 19:10:48 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:10:48 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:48 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 19:10:48 volumio volumio[1230]: info: CoreCommandRouter::volumioPlay Feb 11 19:10:48 volumio volumio[1230]: info: CoreStateMachine::play index undefined Feb 11 19:10:48 volumio volumio[1230]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 19:10:48 volumio volumio[1230]: info: CoreCommandRouter::volumioPlay Feb 11 19:10:48 volumio volumio[1230]: info: CoreStateMachine::play index undefined Feb 11 19:10:48 volumio volumio[1230]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 19:10:48 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:48 volumio volumio[1230]: info: CoreStateMachine::startPlaybackTimer Feb 11 19:10:48 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:48 volumio volumio[1230]: info: [1770837048295] ControllerWebradio::clearAddPlayTrack Feb 11 19:10:48 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 19:10:48 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:48 volumio volumio[1230]: info: CoreStateMachine::startPlaybackTimer Feb 11 19:10:48 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:48 volumio volumio[1230]: info: [1770837048334] ControllerWebradio::clearAddPlayTrack Feb 11 19:10:48 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 19:10:48 volumio volumio[1230]: error: Cannot start Volumio Streaming Daemon Feb 11 19:10:48 volumio volumio[1230]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 11 19:10:48 volumio volumio[1230]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 11 19:10:48 volumio volumio[1230]: info: sendMpdCommand stop took 641 milliseconds Feb 11 19:10:48 volumio volumio[1230]: info: sendMpdCommand stop took 632 milliseconds Feb 11 19:10:48 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand clear Feb 11 19:10:48 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand clear Feb 11 19:10:49 volumio volumio[1230]: info: Feb 11 19:10:49 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Feb 11 19:10:49 volumio volumio[1230]: info: Ignoring MPD Status Update Feb 11 19:10:49 volumio volumio[1230]: info: Feb 11 19:10:49 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Feb 11 19:10:49 volumio volumio[1230]: info: Ignoring MPD Status Update Feb 11 19:10:49 volumio volumio[1230]: info: sendMpdCommand clear took 200 milliseconds Feb 11 19:10:49 volumio volumio[1230]: info: sendMpdCommand clear took 185 milliseconds Feb 11 19:10:49 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand load "https://listen-inspirefm.sharp-stream.com/inspirefm.mp3" Feb 11 19:10:49 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand load "https://listen-inspirefm.sharp-stream.com/inspirefm.mp3" Feb 11 19:10:49 volumio volumio[1230]: info: Feb 11 19:10:49 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Feb 11 19:10:49 volumio volumio[1230]: info: Ignoring MPD Status Update Feb 11 19:10:49 volumio volumio[1230]: info: Feb 11 19:10:49 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Feb 11 19:10:49 volumio volumio[1230]: info: Ignoring MPD Status Update Feb 11 19:10:49 volumio volumio[1230]: error: updateQueue error: null Feb 11 19:10:49 volumio volumio[1230]: error: updateQueue error: null Feb 11 19:10:49 volumio volumio[1230]: info: ------------------------------ 586ms Feb 11 19:10:49 volumio volumio[1230]: info: ------------------------------ 584ms Feb 11 19:10:50 volumio volumio[1230]: verbose: New Socket.io Connection to 192.168.5.190:3000 from 192.168.5.227 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 13 Feb 11 19:10:52 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 19:10:52 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 19:10:52 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 19:10:52 volumio volumio[1230]: info: Discovery: Getting this device information Feb 11 19:10:52 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:10:52 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:52 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 19:10:52 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand add "https://listen-inspirefm.sharp-stream.com/inspirefm.mp3" Feb 11 19:10:52 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 19:10:52 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 19:10:52 volumio volumio[1230]: info: Discovery: Getting this device information Feb 11 19:10:52 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:10:52 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:52 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 19:10:53 volumio volumio[1230]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Feb 11 19:10:53 volumio volumio[1230]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 11 19:10:53 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 19:10:53 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 19:10:53 volumio volumio[1230]: info: Discovery: Getting this device information Feb 11 19:10:53 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:10:53 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:53 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 19:10:53 volumio volumio[1230]: error: updateQueue error: null Feb 11 19:10:53 volumio volumio[1230]: error: updateQueue error: null Feb 11 19:10:53 volumio volumio[1230]: info: Feb 11 19:10:53 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Feb 11 19:10:53 volumio volumio[1230]: info: Ignoring MPD Status Update Feb 11 19:10:53 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand add "https://listen-inspirefm.sharp-stream.com/inspirefm.mp3" Feb 11 19:10:53 volumio volumio[1230]: info: ------------------------------ 4775ms Feb 11 19:10:53 volumio volumio[1230]: info: ------------------------------ 4773ms Feb 11 19:10:53 volumio volumio[1230]: info: sendMpdCommand add "https://listen-inspirefm.sharp-stream.com/inspirefm.mp3" took 1515 milliseconds Feb 11 19:10:54 volumio volumio[1230]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 11 19:10:54 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand play Feb 11 19:10:54 volumio volumio[1230]: info: Feb 11 19:10:54 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Feb 11 19:10:54 volumio volumio[1230]: info: Ignoring MPD Status Update Feb 11 19:10:54 volumio volumio[1230]: info: Feb 11 19:10:54 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Feb 11 19:10:54 volumio volumio[1230]: info: Ignoring MPD Status Update Feb 11 19:10:54 volumio volumio[1230]: verbose: New Socket.io Connection to 192.168.5.190:3000 from 192.168.5.227 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 15 Feb 11 19:10:54 volumio volumio[1230]: info: Feb 11 19:10:54 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Feb 11 19:10:54 volumio volumio[1230]: info: Ignoring MPD Status Update Feb 11 19:10:54 volumio volumio[1230]: info: sendMpdCommand add "https://listen-inspirefm.sharp-stream.com/inspirefm.mp3" took 677 milliseconds Feb 11 19:10:54 volumio volumio[1230]: info: ------------------------------ 691ms Feb 11 19:10:54 volumio volumio[1230]: info: sendMpdCommand play took 661 milliseconds Feb 11 19:10:54 volumio volumio[1230]: info: ------------------------------ 645ms Feb 11 19:10:54 volumio volumio[1230]: info: ------------------------------ 624ms Feb 11 19:10:54 volumio volumio[1230]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 11 19:10:54 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand play Feb 11 19:10:54 volumio volumio[1230]: info: Feb 11 19:10:54 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Feb 11 19:10:54 volumio volumio[1230]: info: Ignoring MPD Status Update Feb 11 19:10:54 volumio volumio[1230]: info: Feb 11 19:10:54 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Feb 11 19:10:54 volumio volumio[1230]: info: Ignoring MPD Status Update Feb 11 19:10:54 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 19:10:54 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 19:10:54 volumio volumio[1230]: verbose: New Socket.io Connection to 192.168.5.190:3000 from 192.168.5.227 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 16 Feb 11 19:10:55 volumio volumio[1230]: info: ------------------------------ 491ms Feb 11 19:10:55 volumio volumio[1230]: info: sendMpdCommand play took 421 milliseconds Feb 11 19:10:55 volumio volumio[1230]: info: ------------------------------ 372ms Feb 11 19:10:55 volumio volumio[1230]: info: ------------------------------ 370ms Feb 11 19:10:55 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 19:10:55 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 19:10:55 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 19:10:55 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 19:10:55 volumio volumio[1230]: info: Discovery: Getting this device information Feb 11 19:10:55 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:10:55 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 19:10:56 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 11 19:10:56 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 11 19:10:56 volumio volumio[1230]: info: Feb 11 19:10:56 volumio volumio[1230]: ---------------------------- MPD announces state update: player Feb 11 19:10:56 volumio volumio[1230]: info: ControllerMpd::getState Feb 11 19:10:56 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status Feb 11 19:10:56 volumio volumio[1230]: info: Feb 11 19:10:56 volumio volumio[1230]: ---------------------------- MPD announces state update: player Feb 11 19:10:56 volumio volumio[1230]: info: ControllerMpd::getState Feb 11 19:10:56 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status Feb 11 19:10:56 volumio volumio[1230]: info: Feb 11 19:10:56 volumio volumio[1230]: ---------------------------- MPD announces state update: player Feb 11 19:10:56 volumio volumio[1230]: info: Feb 11 19:10:56 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Feb 11 19:10:56 volumio volumio[1230]: info: Ignoring MPD Status Update Feb 11 19:10:56 volumio volumio[1230]: info: Feb 11 19:10:56 volumio volumio[1230]: ---------------------------- MPD announces state update: player Feb 11 19:10:56 volumio volumio[1230]: info: ControllerMpd::getState Feb 11 19:10:56 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status Feb 11 19:10:56 volumio volumio[1230]: info: sendMpdCommand status took 140 milliseconds Feb 11 19:10:56 volumio volumio[1230]: info: ControllerMpd::getState Feb 11 19:10:56 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status Feb 11 19:10:56 volumio volumio[1230]: info: sendMpdCommand status took 131 milliseconds Feb 11 19:10:56 volumio volumio[1230]: verbose: ControllerMpd::parseState Feb 11 19:10:56 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 19:10:56 volumio volumio[1230]: verbose: ControllerMpd::parseState Feb 11 19:10:56 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 19:10:56 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 19:10:56 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 11 19:10:57 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:57 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:10:57 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 11 19:10:57 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 11 19:10:57 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 11 19:10:57 volumio volumio[1230]: info: CoreCommandRouter::volumioGetBrowseSources Feb 11 19:10:57 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 19:10:57 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 11 19:10:57 volumio volumio[1230]: info: Feb 11 19:10:57 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Feb 11 19:10:57 volumio volumio[1230]: info: Ignoring MPD Status Update Feb 11 19:10:57 volumio volumio[1230]: info: Feb 11 19:10:57 volumio volumio[1230]: ---------------------------- MPD announces state update: player Feb 11 19:10:57 volumio volumio[1230]: info: ControllerMpd::getState Feb 11 19:10:57 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status Feb 11 19:10:57 volumio volumio[1230]: info: Feb 11 19:10:57 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Feb 11 19:10:57 volumio volumio[1230]: info: Ignoring MPD Status Update Feb 11 19:10:57 volumio volumio[1230]: info: Feb 11 19:10:57 volumio volumio[1230]: ---------------------------- MPD announces state update: player Feb 11 19:10:57 volumio volumio[1230]: info: ControllerMpd::getState Feb 11 19:10:57 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status Feb 11 19:10:57 volumio volumio[1230]: info: sendMpdCommand status took 829 milliseconds Feb 11 19:10:57 volumio volumio[1230]: info: ------------------------------ 904ms Feb 11 19:10:57 volumio volumio[1230]: info: sendMpdCommand status took 808 milliseconds Feb 11 19:10:57 volumio volumio[1230]: info: sendMpdCommand playlistinfo took 773 milliseconds Feb 11 19:10:57 volumio volumio[1230]: info: sendMpdCommand playlistinfo took 745 milliseconds Feb 11 19:10:57 volumio volumio[1230]: info: ------------------------------ 48ms Feb 11 19:10:57 volumio volumio[1230]: info: sendMpdCommand status took 57 milliseconds Feb 11 19:10:57 volumio volumio[1230]: verbose: ControllerMpd::parseState Feb 11 19:10:57 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 19:10:57 volumio volumio[1230]: verbose: ControllerMpd::parseState Feb 11 19:10:57 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 19:10:57 volumio volumio[1230]: verbose: ControllerMpd::parseTrackInfo Feb 11 19:10:57 volumio volumio[1230]: verbose: ControllerMpd::parseTrackInfo Feb 11 19:10:57 volumio volumio[1230]: verbose: ControllerMpd::parseState Feb 11 19:10:57 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 19:10:57 volumio volumio[1230]: info: ControllerMpd::pushState Feb 11 19:10:57 volumio volumio[1230]: info: CoreCommandRouter::servicePushState Feb 11 19:10:57 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:57 volumio volumio[1230]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1696,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"inspirefm.mp3","artist":"no name","album":null,"uri":"https://listen-inspirefm.sharp-stream.com/inspirefm.mp3","trackType":"mp3"} Feb 11 19:10:57 volumio volumio[1230]: verbose: CURRENT POSITION 0 Feb 11 19:10:57 volumio volumio[1230]: info: CoreStateMachine::syncState stateService play Feb 11 19:10:57 volumio volumio[1230]: info: CoreStateMachine::syncState currentStatus stop Feb 11 19:10:57 volumio volumio[1230]: info: ControllerMpd::pushState Feb 11 19:10:57 volumio volumio[1230]: info: CoreCommandRouter::servicePushState Feb 11 19:10:57 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:57 volumio volumio[1230]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1696,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"inspirefm.mp3","artist":"no name","album":null,"uri":"https://listen-inspirefm.sharp-stream.com/inspirefm.mp3","trackType":"mp3"} Feb 11 19:10:57 volumio volumio[1230]: verbose: CURRENT POSITION 0 Feb 11 19:10:57 volumio volumio[1230]: info: CoreStateMachine::syncState stateService play Feb 11 19:10:57 volumio volumio[1230]: info: CoreStateMachine::syncState currentStatus play Feb 11 19:10:57 volumio volumio[1230]: info: Received an update from plugin. extracting info from payload Feb 11 19:10:57 volumio volumio[1230]: info: CoreStateMachine::pushState Feb 11 19:10:57 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:57 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 19:10:57 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Feb 11 19:10:57 volumio volumio[1230]: info: CoreStateMachine::pushState Feb 11 19:10:57 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Feb 11 19:10:57 volumio volumio[1230]: info: ------------------------------ 1123ms Feb 11 19:10:57 volumio volumio[1230]: info: ------------------------------ 1114ms Feb 11 19:10:57 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Feb 11 19:10:57 volumio sudo[1891]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 11 19:10:57 volumio sudo[1891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:10:57 volumio sudo[1891]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:58 volumio sudo[1876]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Feb 11 19:10:58 volumio sudo[1876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:10:58 volumio volumio[1230]: info: ------------------------------ 705ms Feb 11 19:10:58 volumio sudo[1899]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 19:10:58 volumio sudo[1899]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:10:58 volumio sudo[1881]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 11 19:10:58 volumio sudo[1881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:10:58 volumio volumio[1230]: info: sendMpdCommand status took 745 milliseconds Feb 11 19:10:58 volumio sudo[1883]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 11 19:10:58 volumio sudo[1876]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:58 volumio sudo[1883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:10:58 volumio sudo[1899]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:58 volumio volumio[1230]: info: sendMpdCommand playlistinfo took 722 milliseconds Feb 11 19:10:58 volumio volumio[1230]: info: sendMpdCommand playlistinfo took 735 milliseconds Feb 11 19:10:58 volumio volumio[1230]: info: sendMpdCommand playlistinfo took 720 milliseconds Feb 11 19:10:58 volumio volumio[1230]: verbose: ControllerMpd::parseState Feb 11 19:10:58 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 19:10:58 volumio volumio[1230]: verbose: ControllerMpd::parseTrackInfo Feb 11 19:10:58 volumio volumio[1230]: verbose: ControllerMpd::parseTrackInfo Feb 11 19:10:58 volumio volumio[1230]: verbose: ControllerMpd::parseTrackInfo Feb 11 19:10:58 volumio volumio[1230]: info: ControllerMpd::pushState Feb 11 19:10:58 volumio sudo[1900]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 19:10:58 volumio sudo[1900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:10:58 volumio sudo[1881]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:58 volumio volumio[1230]: info: CoreCommandRouter::servicePushState Feb 11 19:10:58 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:58 volumio volumio[1230]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1788,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"inspirefm.mp3","artist":"no name","album":null,"uri":"https://listen-inspirefm.sharp-stream.com/inspirefm.mp3","trackType":"mp3"} Feb 11 19:10:58 volumio sudo[1883]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:58 volumio volumio[1230]: verbose: CURRENT POSITION 0 Feb 11 19:10:58 volumio volumio[1230]: info: CoreStateMachine::syncState stateService play Feb 11 19:10:58 volumio volumio[1230]: info: CoreStateMachine::syncState currentStatus play Feb 11 19:10:58 volumio volumio[1230]: info: Received an update from plugin. extracting info from payload Feb 11 19:10:58 volumio volumio[1230]: info: CoreStateMachine::pushState Feb 11 19:10:58 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:58 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 19:10:58 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Feb 11 19:10:58 volumio sudo[1900]: pam_unix(sudo:session): session closed for user root Feb 11 19:10:58 volumio volumio[1230]: info: CoreStateMachine::pushState Feb 11 19:10:58 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Feb 11 19:10:58 volumio volumio[1230]: info: ControllerMpd::pushState Feb 11 19:10:58 volumio volumio[1230]: info: CoreCommandRouter::servicePushState Feb 11 19:10:58 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:58 volumio volumio[1230]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1925,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"inspirefm.mp3","artist":"no name","album":null,"uri":"https://listen-inspirefm.sharp-stream.com/inspirefm.mp3","trackType":"mp3"} Feb 11 19:10:58 volumio volumio[1230]: verbose: CURRENT POSITION 0 Feb 11 19:10:58 volumio volumio[1230]: info: CoreStateMachine::syncState stateService play Feb 11 19:10:58 volumio volumio[1230]: info: CoreStateMachine::syncState currentStatus play Feb 11 19:10:58 volumio volumio[1230]: info: Received an update from plugin. extracting info from payload Feb 11 19:10:58 volumio volumio[1230]: info: CoreStateMachine::pushState Feb 11 19:10:58 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:58 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Feb 11 19:10:58 volumio volumio[1230]: info: CoreStateMachine::pushState Feb 11 19:10:58 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Feb 11 19:10:58 volumio volumio[1230]: info: ControllerMpd::pushState Feb 11 19:10:58 volumio volumio[1230]: info: CoreCommandRouter::servicePushState Feb 11 19:10:58 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:58 volumio volumio[1230]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2659,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"inspirefm.mp3","artist":"no name","album":null,"uri":"https://listen-inspirefm.sharp-stream.com/inspirefm.mp3","trackType":"mp3"} Feb 11 19:10:58 volumio volumio[1230]: verbose: CURRENT POSITION 0 Feb 11 19:10:58 volumio volumio[1230]: info: CoreStateMachine::syncState stateService play Feb 11 19:10:58 volumio volumio[1230]: info: CoreStateMachine::syncState currentStatus play Feb 11 19:10:58 volumio volumio[1230]: info: Received an update from plugin. extracting info from payload Feb 11 19:10:58 volumio volumio[1230]: info: CoreStateMachine::pushState Feb 11 19:10:58 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:58 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Feb 11 19:10:58 volumio volumio[1230]: info: CoreStateMachine::pushState Feb 11 19:10:58 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Feb 11 19:10:58 volumio volumio[1230]: info: ------------------------------ 2428ms Feb 11 19:10:59 volumio volumio[1230]: info: ------------------------------ 2473ms Feb 11 19:10:59 volumio volumio[1230]: info: ------------------------------ 1618ms Feb 11 19:10:59 volumio volumio[1230]: info: sendMpdCommand playlistinfo took 1287 milliseconds Feb 11 19:10:59 volumio volumio[1230]: verbose: ControllerMpd::parseTrackInfo Feb 11 19:10:59 volumio volumio[1230]: info: ControllerMpd::pushState Feb 11 19:10:59 volumio volumio[1230]: info: CoreCommandRouter::servicePushState Feb 11 19:10:59 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:59 volumio volumio[1230]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2659,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"inspirefm.mp3","artist":"no name","album":null,"uri":"https://listen-inspirefm.sharp-stream.com/inspirefm.mp3","trackType":"mp3"} Feb 11 19:10:59 volumio volumio[1230]: verbose: CURRENT POSITION 0 Feb 11 19:10:59 volumio volumio[1230]: info: CoreStateMachine::syncState stateService play Feb 11 19:10:59 volumio volumio[1230]: info: CoreStateMachine::syncState currentStatus play Feb 11 19:10:59 volumio volumio[1230]: info: Received an update from plugin. extracting info from payload Feb 11 19:10:59 volumio volumio[1230]: info: CoreStateMachine::pushState Feb 11 19:10:59 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Feb 11 19:10:59 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 19:10:59 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Feb 11 19:10:59 volumio volumio[1230]: info: CoreStateMachine::pushState Feb 11 19:10:59 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Feb 11 19:10:59 volumio volumio[1230]: info: ------------------------------ 2383ms Feb 11 19:11:00 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:11:00 volumio volumio[1230]: info: MyVolumio token set successfully Feb 11 19:11:00 volumio volumio[1230]: info: MYVOLUMIO: Adding device Feb 11 19:11:01 volumio volumio[1230]: info: MYVOLUMIO: Evaluating Server Feb 11 19:11:01 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 19:11:01 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 19:11:03 volumio volumio[1230]: info: MyVolumio status changed Feb 11 19:11:03 volumio volumio[1230]: info: Streaming services startup Feb 11 19:11:03 volumio volumio[1230]: info: Starting Streaming Daemon Feb 11 19:11:03 volumio volumio[1230]: info: Removing browser output: myVolumio user plan is not superstar Feb 11 19:11:03 volumio volumio[1230]: info: Removing audio output: Feb 11 19:11:03 volumio volumio[1230]: info: Stoppping Tunnel 1 Feb 11 19:11:03 volumio sudo[1942]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 11 19:11:03 volumio sudo[1942]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:03 volumio sudo[1940]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 11 19:11:03 volumio sudo[1940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:03 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 19:11:03 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 19:11:03 volumio sudo[1940]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 19:11:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 19:11:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 19:11:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 19:11:04 volumio sudo[1942]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:04 volumio volumio[1230]: info: Setting Geolocation for MyVolumio to eu3 Feb 11 19:11:04 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 19:11:04 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 19:11:04 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 19:11:04 volumio volumio[1230]: error: Cannot start Volumio Streaming Daemon Feb 11 19:11:04 volumio volumio[1230]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 11 19:11:04 volumio volumio[1230]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 11 19:11:04 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 19:11:04 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 19:11:04 volumio volumio[1230]: info: Discovery: Getting this device information Feb 11 19:11:04 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:11:04 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 19:11:04 volumio volumio[1230]: info: Remote SSH Stopped Feb 11 19:11:04 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 19:11:04 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 19:11:04 volumio volumio[1230]: info: Discovery: Getting this device information Feb 11 19:11:04 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:11:04 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 19:11:04 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:11:04 volumio volumio[1230]: warn: [now-playing] MyBackgroundMonitor stopped Feb 11 19:11:04 volumio volumio[1230]: info: Disabling plugin now_playing Feb 11 19:11:04 volumio volumio[1230]: info: Done. Feb 11 19:11:05 volumio volumio[1230]: info: Successfully Added MyVolumio device Feb 11 19:11:05 volumio volumio[1230]: info: Updating MyVolumio device info Feb 11 19:11:05 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 19:11:05 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 19:11:05 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 19:11:05 volumio volumio[1230]: verbose: New Socket.io Connection to 192.168.5.190:3000 from 192.168.5.227 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 15 Feb 11 19:11:05 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 19:11:05 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 19:11:05 volumio volumio[1230]: info: Successfully Updated MyVolumio device Feb 11 19:11:07 volumio volumio[1230]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 11 19:11:07 volumio volumio[1230]: info: Completed starting MyVolumio Plugin Feb 11 19:11:07 volumio volumio[1230]: [Metrics] CommandRouter: 119s 410.47ms Feb 11 19:11:07 volumio volumio[1230]: info: CoreCommandRouter::volumiosetStartupVolume Feb 11 19:11:07 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 19:11:07 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 19:11:07 volumio volumio[1230]: info: CoreCommandRouter::Close All Modals sent Feb 11 19:11:07 volumio volumio[1230]: info: CoreCommandRouter::Close All Modals sent Feb 11 19:11:07 volumio volumio[1230]: info: pi_screen_setup: Stopping plugin Feb 11 19:11:07 volumio volumio[1230]: info: pi_screen_setup: Management server stopped Feb 11 19:11:07 volumio volumio[1230]: info: Disabling plugin pi_screen_setup Feb 11 19:11:07 volumio volumio[1230]: info: Done. Feb 11 19:11:08 volumio volumio[1230]: info: Enabling plugin touch_display Feb 11 19:11:08 volumio volumio[1230]: info: Loading plugin "touch_display"... Feb 11 19:11:08 volumio volumio[1230]: info: Applying required configuration parameters for plugin touch_display Feb 11 19:11:08 volumio volumio[1230]: info: PLUGIN START: touch_display Feb 11 19:11:08 volumio volumio[1230]: info: Loading i18n strings for locale en Feb 11 19:11:08 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 19:11:09 volumio sudo[1954]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 11 19:11:09 volumio sudo[1954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:09 volumio sudo[1952]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Feb 11 19:11:09 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 11 19:11:09 volumio sudo[1952]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:09 volumio sudo[1959]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 11 19:11:09 volumio sudo[1959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:09 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 11 19:11:09 volumio sudo[1954]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:09 volumio systemd[1]: Reloading. Feb 11 19:11:09 volumio sudo[1952]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:09 volumio sudo[1957]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 11 19:11:09 volumio sudo[1957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:09 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 11 19:11:09 volumio volumio[1230]: info: touch_display: No backlight interface detected. Feb 11 19:11:10 volumio volumio[1230]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 11 19:11:10 volumio sudo[1985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Feb 11 19:11:10 volumio sudo[1985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:10 volumio sudo[1985]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:10 volumio volumio[1230]: info: touch_display: IframeKeyboardBridge extension installed successfully Feb 11 19:11:10 volumio volumio[1230]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 11 19:11:11 volumio sudo[2008]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 11 19:11:11 volumio sudo[2008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:11 volumio sudo[2008]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:11 volumio volumio[1230]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 11 19:11:12 volumio volumio[1230]: info: Enabling plugin touch_display Feb 11 19:11:12 volumio volumio[1230]: info: Loading plugin "touch_display"... Feb 11 19:11:12 volumio volumio[1230]: info: Applying required configuration parameters for plugin touch_display Feb 11 19:11:12 volumio volumio[1230]: info: PLUGIN START: touch_display Feb 11 19:11:12 volumio volumio[1230]: info: Loading i18n strings for locale en Feb 11 19:11:12 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 19:11:13 volumio sudo[2016]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 11 19:11:13 volumio sudo[2016]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:13 volumio systemd[1]: Reloading. Feb 11 19:11:13 volumio sudo[1959]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:13 volumio sudo[2014]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Feb 11 19:11:13 volumio sudo[2014]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 19:11:14 volumio volumio[1230]: info: Received Get System Info Feb 11 19:11:14 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 19:11:14 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 19:11:14 volumio sudo[2014]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:14 volumio volumio[1230]: info: Discovery: Getting this device information Feb 11 19:11:14 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Feb 11 19:11:14 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 19:11:14 volumio sudo[2018]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 11 19:11:14 volumio sudo[2018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:14 volumio volumio[1230]: info: touch_display: No backlight interface detected. Feb 11 19:11:14 volumio sudo[2021]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 11 19:11:14 volumio sudo[2021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:14 volumio volumio[1230]: info: touch_display: systemctl daemon-reload succeeded. Feb 11 19:11:14 volumio volumio[1230]: info: BOOT COMPLETED Feb 11 19:11:14 volumio sudo[2043]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 11 19:11:14 volumio sudo[2043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:14 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 19:11:14 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 19:11:15 volumio volumio[1230]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 11 19:11:15 volumio sudo[2049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Feb 11 19:11:15 volumio sudo[2049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:15 volumio sudo[2051]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 11 19:11:15 volumio sudo[2051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:16 volumio sudo[2051]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:16 volumio sudo[2049]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:16 volumio volumio[1230]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 11 19:11:16 volumio volumio[1230]: info: touch_display: IframeKeyboardBridge extension installed successfully Feb 11 19:11:17 volumio systemd[1]: Reloading. Feb 11 19:11:17 volumio sudo[1957]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:17 volumio volumio[1230]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 11 19:11:18 volumio volumio[1230]: info: Enabling plugin now_playing Feb 11 19:11:18 volumio volumio[1230]: info: Loading plugin "now_playing"... Feb 11 19:11:18 volumio volumio[1230]: info: PLUGIN START: now_playing Feb 11 19:11:18 volumio volumio[1230]: info: [now-playing] ConfigUpdater: config is up to date. Feb 11 19:11:18 volumio volumio[1230]: info: [now-playing] App is listening on port 4004. Feb 11 19:11:18 volumio volumio[1230]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Feb 11 19:11:18 volumio volumio[1230]: info: Done. Feb 11 19:11:20 volumio sudo[2077]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 19:11:20 volumio sudo[2077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:20 volumio sudo[2078]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 19:11:20 volumio sudo[2078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:20 volumio sudo[2077]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:20 volumio sudo[2078]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:20 volumio volumio[1230]: verbose: New Socket.io Connection to 192.168.5.190 from 192.168.5.227 UA: Mozilla/5.0 (Linux; Android 16; SM-S908B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Feb 11 19:11:21 volumio systemd[1]: Reloading. Feb 11 19:11:21 volumio sudo[2043]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:21 volumio sudo[2021]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:22 volumio volumio[1230]: info: touch_display: Un-/commenting gpu_mem settings in /boot/config.txt. Feb 11 19:11:23 volumio sudo[2116]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 11 19:11:23 volumio sudo[2116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:23 volumio volumio[1230]: info: touch_display: Using /boot/config.txt instead of /boot/userconfig.txt. Feb 11 19:11:23 volumio sudo[2116]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:23 volumio volumio[1230]: info: touch_display: systemctl daemon-reload succeeded. Feb 11 19:11:23 volumio sudo[2118]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Feb 11 19:11:23 volumio sudo[2118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:23 volumio volumio[1230]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Feb 11 19:11:23 volumio volumio[1230]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Feb 11 19:11:24 volumio sudo[2120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Feb 11 19:11:24 volumio sudo[2120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:24 volumio sudo[2122]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Feb 11 19:11:24 volumio sudo[2122]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:24 volumio sudo[2126]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 11 19:11:24 volumio sudo[2126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 19:11:24 volumio volumio[1230]: info: Error: Error Feb 11 19:11:24 volumio volumio[1230]: Upnp client error: Error: This socket has been ended by the other party Feb 11 19:11:24 volumio volumio[1230]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Feb 11 19:11:24 volumio volumio[1230]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 19:11:25 volumio volumio[1230]: TypeError: Cannot read properties of undefined (reading 'split') Feb 11 19:11:25 volumio volumio[1230]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Feb 11 19:11:25 volumio volumio[1230]: at ChildProcess.exithandler (node:child_process:413:7) Feb 11 19:11:25 volumio volumio[1230]: at ChildProcess.emit (node:events:514:28) Feb 11 19:11:25 volumio volumio[1230]: at maybeClose (node:internal/child_process:1105:16) Feb 11 19:11:25 volumio volumio[1230]: at Socket. (node:internal/child_process:457:11) Feb 11 19:11:25 volumio volumio[1230]: at Socket.emit (node:events:514:28) Feb 11 19:11:25 volumio volumio[1230]: at Pipe. (node:net:337:12) Feb 11 19:11:25 volumio volumio[1230]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 19:11:25 volumio sudo[2018]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:25 volumio sudo[2122]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:25 volumio sudo[2016]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:25 volumio sudo[2126]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:25 volumio systemd[1]: Reloading. Feb 11 19:11:29 volumio systemd[1]: Created slice system-getty.slice - Slice /system/getty. Feb 11 19:11:30 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Feb 11 19:11:30 volumio sudo[2120]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:30 volumio sudo[2118]: pam_unix(sudo:session): session closed for user root Feb 11 19:11:40 volumio kernel: vc4-drm soc:gpu: [drm] *ERROR* [CRTC:95:pixelvalve-2] flip_done timed out Feb 11 19:11:45 volumio sudo[2191]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-11 19:10' Feb 11 19:11:45 volumio sudo[2191]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"