Feb 22 14:45:18 volumio ntpd[1204]: CLOCK: time stepped by 59.712915 Feb 22 14:45:18 volumio ntpd[1204]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 22 14:45:19 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Feb 22 14:45:19 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 22 14:45:19 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 22 14:45:19 volumio upmpdcli[1952]: Could not open config: /tmp/upmpdcli.conf Feb 22 14:45:19 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:45:19 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 22 14:45:20 volumio volumio[1446]: info: Volumio Network Manager: Network status updated: 2 Feb 22 14:45:21 volumio sudo[1968]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 14:45:21 volumio sudo[1968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:45:21 volumio sudo[1970]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 14:45:21 volumio sudo[1968]: pam_unix(sudo:session): session closed for user root Feb 22 14:45:21 volumio sudo[1970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:45:21 volumio sudo[1970]: pam_unix(sudo:session): session closed for user root Feb 22 14:45:21 volumio sudo[1974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 22 14:45:21 volumio sudo[1974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:45:23 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:45:23 volumio volumio[1446]: info: Discovery: Started advertising with name: Volumio Feb 22 14:45:24 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 22 14:45:24 volumio volumio[1446]: info: Adding plugin bluetooth to MyMusic Plugins Feb 22 14:45:24 volumio volumio[1446]: info: Adding plugin multiroom to MyMusic Plugins Feb 22 14:45:24 volumio volumio[1446]: info: Adding plugin metavolumio to MyMusic Plugins Feb 22 14:45:24 volumio volumio[1446]: info: Adding plugin cd_controller to MyMusic Plugins Feb 22 14:45:24 volumio volumio[1446]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 22 14:45:24 volumio volumio[1446]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 22 14:45:24 volumio volumio[1446]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 22 14:45:24 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:45:24 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:45:24 volumio volumio[1446]: info: Starting MyVolumio Remote Streaming Endpoints Feb 22 14:45:24 volumio volumio[1446]: info: MyVolumio login type: Token Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 22 14:45:24 volumio volumio[1446]: info: Streaming services startup Feb 22 14:45:24 volumio volumio[1446]: info: Starting Streaming Daemon Feb 22 14:45:24 volumio sudo[1982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 22 14:45:24 volumio sudo[1982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:45:24 volumio volumio[1446]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 22 14:45:24 volumio sudo[1982]: pam_unix(sudo:session): session closed for user root Feb 22 14:45:24 volumio volumio[1446]: info: Discovery: adding 2dd14f48-379c-4bb5-b33b-811c83b337ba Feb 22 14:45:24 volumio volumio[1446]: info: Discovery: Found device Volumio Feb 22 14:45:24 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:24 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:24 volumio volumio[1446]: info: Discovery: this is already registered, 2dd14f48-379c-4bb5-b33b-811c83b337ba Feb 22 14:45:24 volumio volumio[1446]: info: Discovery: Found device Volumio Feb 22 14:45:24 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:24 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:24 volumio volumio[1446]: error: Cannot start Volumio Streaming Daemon Feb 22 14:45:24 volumio volumio[1446]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 22 14:45:24 volumio volumio[1446]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 22 14:45:25 volumio volumio[1446]: error: MyVolumio Custom Token format not valid, refreshing it Feb 22 14:45:26 volumio volumio[1446]: info: MyVolumio login type: Token Feb 22 14:45:26 volumio sudo[1989]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 14:45:26 volumio sudo[1989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:45:26 volumio sudo[1989]: pam_unix(sudo:session): session closed for user root Feb 22 14:45:26 volumio sudo[1991]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 14:45:26 volumio sudo[1991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:45:26 volumio sudo[1991]: pam_unix(sudo:session): session closed for user root Feb 22 14:45:26 volumio volumio[1446]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 22 14:45:26 volumio volumio[1446]: verbose: New Socket.io Connection to 192.168.1.5 from 192.168.1.11 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 6 Feb 22 14:45:26 volumio sudo[1995]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 14:45:26 volumio sudo[1995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:45:26 volumio sudo[1995]: pam_unix(sudo:session): session closed for user root Feb 22 14:45:26 volumio sudo[1997]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 14:45:26 volumio sudo[1997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:45:26 volumio sudo[1997]: pam_unix(sudo:session): session closed for user root Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:26 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:26 volumio volumio[1446]: verbose: New Socket.io Connection to 192.168.1.5 from 192.168.1.11 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 6 Feb 22 14:45:26 volumio volumio[1446]: info: Received Get System Info Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:45:26 volumio volumio[1446]: info: Discovery: Getting this device information Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:26 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:26 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:26 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:26 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 14:45:26 volumio volumio[1446]: info: Received Get System Info Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:45:26 volumio volumio[1446]: info: Discovery: Getting this device information Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:26 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:26 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:26 volumio volumio[1446]: info: Listing playlists Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:26 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:26 volumio volumio[1446]: info: Received Get System Info Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:45:26 volumio volumio[1446]: info: Discovery: Getting this device information Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:26 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:26 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:26 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:26 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 14:45:26 volumio volumio[1446]: info: Received Get System Info Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:45:26 volumio volumio[1446]: info: Discovery: Getting this device information Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:26 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:45:26 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:26 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:26 volumio volumio[1446]: info: Listing playlists Feb 22 14:45:27 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 22 14:45:27 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 22 14:45:27 volumio volumio[1446]: info: MyVolumio token set successfully Feb 22 14:45:27 volumio volumio[1446]: info: MYVOLUMIO: Adding device Feb 22 14:45:27 volumio volumio[1446]: info: MYVOLUMIO: Evaluating Server Feb 22 14:45:27 volumio volumio[1446]: info: MyVolumio status changed Feb 22 14:45:27 volumio volumio[1446]: info: Streaming services startup Feb 22 14:45:27 volumio volumio[1446]: info: Starting Streaming Daemon Feb 22 14:45:27 volumio volumio[1446]: info: Removing browser output: myVolumio user plan is not superstar Feb 22 14:45:27 volumio volumio[1446]: info: Removing audio output: Feb 22 14:45:27 volumio volumio[1446]: info: Stoppping Tunnel 1 Feb 22 14:45:27 volumio sudo[2022]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 22 14:45:27 volumio sudo[2024]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 22 14:45:27 volumio sudo[2022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:45:27 volumio sudo[2024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:45:27 volumio sudo[2022]: pam_unix(sudo:session): session closed for user root Feb 22 14:45:27 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 22 14:45:27 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 22 14:45:27 volumio volumio[1446]: error: Cannot start Volumio Streaming Daemon Feb 22 14:45:27 volumio volumio[1446]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 22 14:45:27 volumio volumio[1446]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 22 14:45:27 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 22 14:45:27 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 22 14:45:27 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 22 14:45:27 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 22 14:45:27 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 22 14:45:27 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 22 14:45:27 volumio sudo[2024]: pam_unix(sudo:session): session closed for user root Feb 22 14:45:27 volumio volumio[1446]: info: Remote SSH Stopped Feb 22 14:45:27 volumio volumio[1446]: info: Setting Geolocation for MyVolumio to as1 Feb 22 14:45:27 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:45:27 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:45:27 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:45:28 volumio volumio[1446]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 22 14:45:28 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:45:28 volumio volumio[1446]: info: Received Get System Info Feb 22 14:45:28 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:45:28 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:45:28 volumio volumio[1446]: info: Discovery: Getting this device information Feb 22 14:45:28 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:28 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:28 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:45:28 volumio volumio[1446]: info: Updating MyVolumio device info Feb 22 14:45:28 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:45:28 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:45:28 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:45:29 volumio volumio[1446]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 22 14:45:29 volumio sudo[2028]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 14:45:29 volumio sudo[2028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:45:29 volumio sudo[2028]: pam_unix(sudo:session): session closed for user root Feb 22 14:45:29 volumio sudo[2030]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 14:45:29 volumio sudo[2030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:45:29 volumio sudo[2030]: pam_unix(sudo:session): session closed for user root Feb 22 14:45:29 volumio volumio[1446]: verbose: New Socket.io Connection to 192.168.1.5 from 192.168.1.11 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 6 Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:29 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:29 volumio volumio[1446]: info: Received Get System Info Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:45:29 volumio volumio[1446]: info: Discovery: Getting this device information Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:29 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:29 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:29 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:29 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 14:45:29 volumio volumio[1446]: info: Received Get System Info Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:45:29 volumio volumio[1446]: info: Discovery: Getting this device information Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:29 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:29 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:29 volumio volumio[1446]: info: Listing playlists Feb 22 14:45:29 volumio volumio[1446]: info: Received Get System Info Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:45:29 volumio volumio[1446]: info: Discovery: Getting this device information Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:29 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:29 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:29 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 22 14:45:30 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:30 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:32 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:45:32 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 22 14:45:32 volumio volumio[1446]: info: MYVOLUMIO: Adding device Feb 22 14:45:32 volumio volumio[1446]: info: MYVOLUMIO: Evaluating Server Feb 22 14:45:32 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 14:45:32 volumio volumio[1446]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 22 14:45:32 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 22 14:45:32 volumio volumio[1446]: info: Received Get System Version Feb 22 14:45:32 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 14:45:32 volumio volumio[1446]: info: Received Get System Info Feb 22 14:45:32 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:45:32 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:45:32 volumio volumio[1446]: info: Discovery: Getting this device information Feb 22 14:45:32 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:32 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:32 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:45:32 volumio volumio[1446]: info: Setting Geolocation for MyVolumio to as1 Feb 22 14:45:32 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:45:32 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:45:32 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:45:33 volumio volumio[1446]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 22 14:45:33 volumio volumio[1446]: info: Updating MyVolumio device info Feb 22 14:45:33 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:45:33 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:45:33 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:45:34 volumio volumio[1446]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 22 14:45:34 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Feb 22 14:45:34 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 22 14:45:34 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 22 14:45:34 volumio sudo[1974]: pam_unix(sudo:session): session closed for user root Feb 22 14:45:34 volumio volumio[1446]: info: Upmpdcli Daemon Started Feb 22 14:45:35 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 22 14:45:36 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 22 14:45:36 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 22 14:45:37 volumio volumio[1446]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:45:37 volumio volumio[1446]: info: touch_display: About to detect HDMI ports... Feb 22 14:45:37 volumio volumio[1446]: info: touch_display: X server not ready, cannot detect HDMI ports yet. Feb 22 14:45:38 volumio volumio[1446]: info: Bluetoothremote--- Checking for trusted devices to reconnect... Feb 22 14:45:38 volumio volumio[1446]: info: Bluetoothremote--- Device list cleared and placeholder written. Feb 22 14:45:38 volumio bluetoothd[966]: Adv Monitor app :1.25 disconnected from D-Bus Feb 22 14:45:38 volumio volumio[1446]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:45:38 volumio volumio[1446]: info: Bluetoothremote--- Device found: Press scan to detect BT device - xx Feb 22 14:45:38 volumio upmpdcli[2102]: writing RSA key Feb 22 14:45:38 volumio bluetoothd[966]: Adv Monitor app :1.26 disconnected from D-Bus Feb 22 14:45:39 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 22 14:45:39 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 14:45:39 volumio volumio[1446]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 22 14:45:39 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:45:39 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:45:39 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 22 14:45:39 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 22 14:45:43 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 14:45:43 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:45:43 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 14:45:43 volumio volumio-remote-updater[980]: Test mode disabled Feb 22 14:45:43 volumio volumio-remote-updater[980]: Alpha mode disabled Feb 22 14:45:43 volumio volumio-remote-updater[980]: Alpha legacy test mode disabled Feb 22 14:45:43 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 22 14:45:44 volumio volumio[1446]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 22 14:45:44 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 22 14:45:44 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:45:44 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:45:44 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 22 14:45:44 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 22 14:45:44 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 22 14:45:44 volumio volumio[1446]: info: CoreCommandRouter::volumioGetBrowseSources Feb 22 14:45:44 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 14:45:44 volumio volumio[1446]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 22 14:45:44 volumio volumio[1446]: info: Completed starting MyVolumio Plugin Feb 22 14:45:44 volumio volumio[1446]: [Metrics] CommandRouter: 35s 332.90ms Feb 22 14:45:44 volumio volumio[1446]: info: CoreCommandRouter::volumiosetStartupVolume Feb 22 14:45:44 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:45:44 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:45:44 volumio volumio[1446]: info: CoreCommandRouter::Close All Modals sent Feb 22 14:45:44 volumio volumio[1446]: info: CoreCommandRouter::Close All Modals sent Feb 22 14:45:44 volumio volumio[1446]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Feb 22 14:45:44 volumio volumio[1446]: aplay: main:831: audio open error: Unknown error 524 Feb 22 14:45:45 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 22 14:45:45 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 14:45:45 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 22 14:45:47 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:45:47 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 22 14:45:51 volumio volumio[1446]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:45:51 volumio volumio[1446]: info: touch_display: About to detect HDMI ports... Feb 22 14:45:51 volumio volumio[1446]: info: touch_display: X server not ready, cannot detect HDMI ports yet. Feb 22 14:45:51 volumio volumio[1446]: info: BOOT COMPLETED Feb 22 14:45:56 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:45:56 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 22 14:46:02 volumio volumio[1446]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:46:05 volumio volumio[1446]: info: CALLMETHOD: system_hardware raspdac_mini_lcd restartLCD undefined Feb 22 14:46:05 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: raspdac_mini_lcd , restartLCD Feb 22 14:46:05 volumio volumio[1446]: info: [RaspDacMini LCD] User requested LCD service restart Feb 22 14:46:05 volumio sudo[2178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart rdmlcd.service Feb 22 14:46:05 volumio sudo[2178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:46:05 volumio sudo[2178]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:05 volumio volumio[1446]: error: [RaspDacMini LCD] systemctl restart rdmlcd.service failed: Error: Command failed: /usr/bin/sudo /bin/systemctl restart rdmlcd.service Feb 22 14:46:05 volumio volumio[1446]: Failed to restart rdmlcd.service: Unit rdmlcd.service not found. Feb 22 14:46:05 volumio volumio[1446]: error: [RaspDacMini LCD] Failed to restart service: Error: Command failed: /usr/bin/sudo /bin/systemctl restart rdmlcd.service Feb 22 14:46:05 volumio volumio[1446]: Failed to restart rdmlcd.service: Unit rdmlcd.service not found. Feb 22 14:46:07 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:46:07 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 22 14:46:11 volumio volumio[1446]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:46:11 volumio volumio[1446]: info: [MPD_OLED Plugin] Populating: oledType: 1 - 1. Adafruit SPI 128x64 Feb 22 14:46:11 volumio volumio[1446]: info: [MPD_OLED Plugin] Populating: numberOfBars: 26 - 26 Feb 22 14:46:11 volumio volumio[1446]: info: [MPD_OLED Plugin] Populating: gapBetweenBars: 1 - 1 (default) Feb 22 14:46:11 volumio volumio[1446]: info: [MPD_OLED Plugin] Populating: frameRate: 50 - 50 (default) Feb 22 14:46:11 volumio volumio[1446]: info: [MPD_OLED Plugin] Populating: scrollRate: 8 - 8 (default) Feb 22 14:46:11 volumio volumio[1446]: info: [MPD_OLED Plugin] Populating: scrollDelay: 5 - 5 (default) Feb 22 14:46:11 volumio volumio[1446]: info: [MPD_OLED Plugin] Populating: clockFormat: 0 - 24h leading 0 (default) Feb 22 14:46:11 volumio volumio[1446]: info: [MPD_OLED Plugin] Populating: pauseScreenType: p - Play (default) Feb 22 14:46:11 volumio volumio[1446]: info: [MPD_OLED Plugin] Populating: invertDisplayPeriod: 0 - Disabled (default) Feb 22 14:46:11 volumio volumio[1446]: info: [MPD_OLED Plugin] Populating: i2cAddress: - Default for display Feb 22 14:46:11 volumio volumio[1446]: info: [MPD_OLED Plugin] Populating: i2cBus: 1 - /dev/i2c-1 (default) Feb 22 14:46:11 volumio volumio[1446]: info: [MPD_OLED Plugin] Populating: spiResetGPIONumber: 25 - 25 (default) Feb 22 14:46:11 volumio volumio[1446]: info: [MPD_OLED Plugin] Populating: spiDCGPIONumber: 24 - 24 (default) Feb 22 14:46:11 volumio volumio[1446]: info: [MPD_OLED Plugin] Populating: spiCS: 0 - 0 (default) Feb 22 14:46:11 volumio volumio[1446]: info: [MPD_OLED Plugin] Populating: dateFormat: 0 - DD-MM-YYYY (default) Feb 22 14:46:15 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:46:15 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 22 14:46:19 volumio volumio[1446]: info: Starting Uninstall of plugin system_hardware - mpd_oled Feb 22 14:46:19 volumio volumio[1446]: info: Uninstalling plugin mpd_oled Feb 22 14:46:19 volumio sudo[2199]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd_oled_plugin.service Feb 22 14:46:19 volumio volumio5-onboarding[1760]: time=2026-02-22T14:46:19.926+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 14:46:19 volumio sudo[2199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:46:19 volumio volumio[1446]: info: Disabling plugin mpd_oled Feb 22 14:46:19 volumio volumio[1446]: info: Preparing to generate the ALSA configuration file Feb 22 14:46:19 volumio volumio[1446]: info: Asound.conf file written Feb 22 14:46:19 volumio systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Feb 22 14:46:19 volumio systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Feb 22 14:46:19 volumio systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Feb 22 14:46:19 volumio systemd[1]: mpd_oled_plugin.service: Consumed 1min 4.081s CPU time. Feb 22 14:46:19 volumio sudo[2206]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 22 14:46:19 volumio sudo[2206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:46:19 volumio sudo[2206]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:19 volumio sudo[2199]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:19 volumio volumio[1446]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 22 14:46:19 volumio volumio[1446]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 22 14:46:19 volumio volumio[1446]: info: Output device has changed, restarting MPD Feb 22 14:46:19 volumio volumio[1446]: info: Output device has changed, restarting Shairport Sync Feb 22 14:46:19 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:46:19 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:46:19 volumio sudo[2216]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 14:46:19 volumio volumio[1446]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 14:46:19 volumio sudo[2216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:46:19 volumio sudo[2212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 14:46:19 volumio volumio5-onboarding[1760]: time=2026-02-22T14:46:19.973+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 14:46:19 volumio sudo[2212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:46:19 volumio sudo[2212]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:19 volumio volumio[1446]: info: Checking if uninstall.sh is present Feb 22 14:46:19 volumio volumio[1446]: info: MPD Permissions set Feb 22 14:46:19 volumio volumio[1446]: info: [MPD_OLED Plugin] Stopped mpd_oled_plugin service Feb 22 14:46:19 volumio volumio[1446]: info: Executing uninstall.sh Feb 22 14:46:19 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:46:19 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:46:19 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:46:19 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:46:19 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:46:19 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:46:19 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:46:19 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:46:19 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 22 14:46:19 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:46:19 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:46:19 volumio volumio[1446]: info: Starting Shairport Sync Feb 22 14:46:20 volumio sudo[2230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 14:46:20 volumio sudo[2230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:46:20 volumio sudo[2223]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/system_hardware/mpd_oled/uninstall.sh Feb 22 14:46:20 volumio sudo[2223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:46:20 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 22 14:46:20 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 22 14:46:20 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 14:46:20 volumio systemd[1]: shairport-sync.service: Consumed 1.555s CPU time. Feb 22 14:46:20 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 14:46:20 volumio sudo[2230]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:20 volumio volumio[1446]: info: Shairport-Sync Started Feb 22 14:46:20 volumio systemd[1]: Reloading. Feb 22 14:46:20 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 22 14:46:20 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 14:46:20 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 22 14:46:20 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 14:46:20 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 14:46:20 volumio sudo[2281]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/rm /etc/systemd/system/mpd_oled_plugin.service Feb 22 14:46:20 volumio sudo[2281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 14:46:20 volumio sudo[2281]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:20 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 14:46:20 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 14:46:20 volumio systemd[1]: Reloading. Feb 22 14:46:20 volumio sudo[2285]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 14:46:20 volumio sudo[2285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 14:46:20 volumio sudo[2285]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:20 volumio sudo[2223]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:20 volumio volumio[1446]: info: Uninstall script completed Feb 22 14:46:20 volumio volumio[1446]: info: Removing plugin mpd_oled from configuration Feb 22 14:46:20 volumio volumio5-onboarding[1760]: time=2026-02-22T14:46:20.901+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 14:46:20 volumio volumio[1446]: info: Successfully removed mpd_oled configuration files Feb 22 14:46:20 volumio volumio5-onboarding[1760]: time=2026-02-22T14:46:20.947+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 14:46:20 volumio volumio[1446]: info: Plugin folders cleanup Feb 22 14:46:20 volumio volumio[1446]: info: Scanning into folder /volumio/app/plugins/ Feb 22 14:46:20 volumio volumio[1446]: info: Scanning category audio_interface Feb 22 14:46:20 volumio volumio[1446]: info: Scanning category miscellanea Feb 22 14:46:20 volumio volumio[1446]: info: Scanning category music_service Feb 22 14:46:20 volumio volumio[1446]: info: Scanning category plugins.json Feb 22 14:46:20 volumio volumio[1446]: info: Scanning category system_controller Feb 22 14:46:20 volumio volumio[1446]: info: Scanning category user_interface Feb 22 14:46:20 volumio volumio[1446]: info: Scanning into folder /data/plugins/ Feb 22 14:46:20 volumio volumio[1446]: info: Scanning category music_service Feb 22 14:46:20 volumio volumio[1446]: info: Scanning category system_hardware Feb 22 14:46:20 volumio volumio[1446]: info: Cleaning folder for mpd_oled Feb 22 14:46:20 volumio volumio[1446]: info: Scanning category user_interface Feb 22 14:46:20 volumio volumio[1446]: info: Plugin folders cleanup completed Feb 22 14:46:21 volumio volumio5-onboarding[1760]: time=2026-02-22T14:46:21.000+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 14:46:21 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:46:21 volumio volumio[1446]: info: [{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"2.4.0","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Bluetooth_Remote","name":"Bluetoothremote","category":"system_hardware","version":"0.0.13","icon":"fa-bluetooth","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"RaspDacMini LCD","name":"raspdac_mini_lcd","category":"system_hardware","version":"1.1.1","icon":"fa-television","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.6.0","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":false}] Feb 22 14:46:21 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 22 14:46:21 volumio mpd[2304]: 2026-02-22T14:46:21 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 14:46:21 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 22 14:46:21 volumio sudo[2216]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:21 volumio volumio[1446]: error: updateQueue error: null Feb 22 14:46:23 volumio volumio[1446]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:46:23 volumio volumio[1446]: info: touch_display: About to detect HDMI ports... Feb 22 14:46:23 volumio volumio[1446]: info: touch_display: X server not ready, cannot detect HDMI ports yet. Feb 22 14:46:28 volumio volumio[1446]: info: CoreCommandRouter::volumioGetState Feb 22 14:46:28 volumio volumio[1446]: info: CorePlayQueue::getTrack 0 Feb 22 14:46:32 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:46:32 volumio volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 22 14:46:35 volumio volumio[1446]: info: Starting Uninstall of plugin user_interface - touch_display Feb 22 14:46:35 volumio volumio[1446]: info: Uninstalling plugin touch_display Feb 22 14:46:35 volumio sudo[2332]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 22 14:46:35 volumio sudo[2332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:46:35 volumio sudo[2332]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:35 volumio sudo[2334]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Feb 22 14:46:35 volumio sudo[2334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:46:35 volumio volumio[1446]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Feb 22 14:46:35 volumio volumio[1446]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 14:46:35 volumio volumio[1446]: TypeError: Cannot read properties of undefined (reading 'split') Feb 22 14:46:35 volumio volumio[1446]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Feb 22 14:46:35 volumio volumio[1446]: at ChildProcess.exithandler (node:child_process:413:7) Feb 22 14:46:35 volumio volumio[1446]: at ChildProcess.emit (node:events:514:28) Feb 22 14:46:35 volumio volumio[1446]: at maybeClose (node:internal/child_process:1105:16) Feb 22 14:46:35 volumio volumio[1446]: at Socket. (node:internal/child_process:457:11) Feb 22 14:46:35 volumio volumio[1446]: at Socket.emit (node:events:514:28) Feb 22 14:46:35 volumio volumio[1446]: at Pipe. (node:net:337:12) Feb 22 14:46:35 volumio volumio[1446]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 14:46:35 volumio sudo[2338]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Feb 22 14:46:35 volumio sudo[2340]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Feb 22 14:46:35 volumio sudo[2340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:46:35 volumio sudo[2338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 14:46:35 volumio systemd[1]: Created slice system-getty.slice - Slice /system/getty. Feb 22 14:46:35 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Feb 22 14:46:35 volumio sudo[2334]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:35 volumio sudo[2340]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:35 volumio systemd[1]: Reloading. Feb 22 14:46:35 volumio sudo[2375]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-22 14:45' Feb 22 14:46:35 volumio sudo[2375]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"