Dec 22 11:48:02 volumio upmpdcli[1751]: writing RSA key Dec 22 11:48:02 volumio volumio[1187]: info: Initializing connection to go-librespot Websocket Dec 22 11:48:02 volumio volumio[1187]: error: MyVolumio Plugin failed to start in a timely fashion Dec 22 11:48:02 volumio volumio[1187]: [Metrics] CommandRouter: 54s 287.29ms Dec 22 11:48:02 volumio volumio[1187]: info: CoreCommandRouter::volumiosetStartupVolume Dec 22 11:48:02 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:48:02 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:48:02 volumio volumio[1187]: info: CoreCommandRouter::Close All Modals sent Dec 22 11:48:02 volumio volumio[1187]: info: CoreCommandRouter::Close All Modals sent Dec 22 11:48:02 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 11:48:02 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:48:02 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 22 11:48:02 volumio volumio[1187]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Dec 22 11:48:02 volumio volumio[1187]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for sndrpihifiberry Dec 22 11:48:02 volumio volumio[1187]: aplay: main:831: audio open error: No such device Dec 22 11:48:03 volumio go-librespot[1607]: time="2025-12-22T11:48:03+09:00" level=debug msg="new websocket client" Dec 22 11:48:03 volumio volumio[1187]: info: Connection to go-librespot Websocket established Dec 22 11:48:03 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 22 11:48:03 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 22 11:48:03 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 22 11:48:04 volumio volumio[1187]: info: Adding plugin bluetooth to MyMusic Plugins Dec 22 11:48:04 volumio volumio[1187]: info: Adding plugin multiroom to MyMusic Plugins Dec 22 11:48:04 volumio volumio[1187]: info: Adding plugin metavolumio to MyMusic Plugins Dec 22 11:48:04 volumio volumio[1187]: info: Adding plugin cd_controller to MyMusic Plugins Dec 22 11:48:04 volumio volumio[1187]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 22 11:48:04 volumio volumio[1187]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 22 11:48:04 volumio volumio[1187]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 22 11:48:04 volumio volumio[1187]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 22 11:48:05 volumio go-librespot[1607]: time="2025-12-22T11:48:05+09:00" level=trace msg="sent dealer ping" Dec 22 11:48:05 volumio go-librespot[1607]: time="2025-12-22T11:48:05+09:00" level=trace msg="received dealer pong" Dec 22 11:48:09 volumio volumio[1187]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 22 11:48:09 volumio volumio[1187]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 22 11:48:09 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:48:09 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:48:09 volumio volumio[1187]: info: Starting MyVolumio Remote Streaming Endpoints Dec 22 11:48:09 volumio volumio[1187]: info: MyVolumio login type: Token Dec 22 11:48:09 volumio volumio[1187]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 22 11:48:09 volumio volumio[1187]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 22 11:48:09 volumio kernel: hwmon hwmon1: Voltage normalised Dec 22 11:48:11 volumio volumio[1187]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 22 11:48:11 volumio volumio[1187]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 22 11:48:11 volumio volumio[1187]: info: Streaming services startup Dec 22 11:48:11 volumio volumio[1187]: info: Starting Streaming Daemon Dec 22 11:48:11 volumio sudo[1789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 22 11:48:11 volumio sudo[1789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:11 volumio sudo[1789]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:11 volumio volumio[1187]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 22 11:48:12 volumio volumio[1187]: info: Getting Spotify volume Dec 22 11:48:12 volumio volumio[1187]: info: BOOT COMPLETED Dec 22 11:48:12 volumio volumio[1187]: error: Cannot start Volumio Streaming Daemon Dec 22 11:48:12 volumio volumio[1187]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 22 11:48:12 volumio volumio[1187]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 22 11:48:12 volumio volumio[1187]: info: Spotify volume: 100 Dec 22 11:48:12 volumio volumio[1187]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 22 11:48:13 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Dec 22 11:48:13 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Dec 22 11:48:13 volumio volumio[1187]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 22 11:48:14 volumio volumio[1187]: info: MyVolumio token set successfully Dec 22 11:48:14 volumio volumio[1187]: info: MYVOLUMIO: Adding device Dec 22 11:48:14 volumio volumio[1187]: info: MYVOLUMIO: Evaluating Server Dec 22 11:48:15 volumio volumio[1187]: info: MyVolumio status changed Dec 22 11:48:15 volumio volumio[1187]: info: Streaming services startup Dec 22 11:48:15 volumio volumio[1187]: info: Starting Streaming Daemon Dec 22 11:48:15 volumio volumio[1187]: info: Removing browser output: myVolumio user plan is not superstar Dec 22 11:48:15 volumio volumio[1187]: info: Removing audio output: Dec 22 11:48:15 volumio volumio[1187]: info: Stoppping Tunnel 1 Dec 22 11:48:15 volumio sudo[1830]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 22 11:48:15 volumio sudo[1830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:15 volumio sudo[1832]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 22 11:48:15 volumio sudo[1832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:15 volumio sudo[1830]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:15 volumio volumio[1187]: error: Cannot start Volumio Streaming Daemon Dec 22 11:48:15 volumio volumio[1187]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 22 11:48:15 volumio volumio[1187]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 22 11:48:15 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 22 11:48:15 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 22 11:48:15 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 22 11:48:15 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 22 11:48:15 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 22 11:48:15 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 22 11:48:15 volumio sudo[1832]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:15 volumio volumio[1187]: info: Setting Geolocation for MyVolumio to as1 Dec 22 11:48:15 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:48:15 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:48:15 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:48:15 volumio volumio[1187]: info: Remote SSH Stopped Dec 22 11:48:15 volumio volumio[1187]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 22 11:48:16 volumio sudo[1836]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 11:48:16 volumio sudo[1836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:16 volumio sudo[1838]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 11:48:16 volumio sudo[1836]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:16 volumio sudo[1838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:16 volumio sudo[1838]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:16 volumio volumio[1187]: verbose: New Socket.io Connection to 192.168.0.50 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 4 Dec 22 11:48:16 volumio volumio[1187]: info: Updating MyVolumio device info Dec 22 11:48:16 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:48:16 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:48:16 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:48:16 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 22 11:48:16 volumio volumio[1187]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 11:48:16 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 11:48:16 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Dec 22 11:48:16 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Dec 22 11:48:16 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 22 11:48:16 volumio volumio[1187]: info: CoreCommandRouter::volumioGetQueue Dec 22 11:48:16 volumio volumio[1187]: info: CoreStateMachine::getQueue Dec 22 11:48:16 volumio volumio[1187]: info: CorePlayQueue::getQueue Dec 22 11:48:16 volumio volumio[1187]: info: Listing playlists Dec 22 11:48:16 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 22 11:48:16 volumio volumio[1187]: info: Received Get System Info Dec 22 11:48:16 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 11:48:16 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 11:48:16 volumio volumio[1187]: info: Discovery: Getting this device information Dec 22 11:48:16 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Dec 22 11:48:16 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Dec 22 11:48:16 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 11:48:16 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Dec 22 11:48:16 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Dec 22 11:48:16 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 22 11:48:16 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 11:48:16 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 22 11:48:16 volumio volumio[1187]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 22 11:48:18 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 11:48:18 volumio volumio[1187]: info: Received Get System Info Dec 22 11:48:18 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 11:48:18 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 11:48:18 volumio volumio[1187]: info: Discovery: Getting this device information Dec 22 11:48:18 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Dec 22 11:48:18 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Dec 22 11:48:18 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 22 11:48:18 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 11:48:18 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 22 11:48:18 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 22 11:48:19 volumio volumio[1187]: info: MYVOLUMIO: Adding device Dec 22 11:48:19 volumio volumio[1187]: info: MYVOLUMIO: Evaluating Server Dec 22 11:48:21 volumio volumio[1187]: info: Setting Geolocation for MyVolumio to as1 Dec 22 11:48:21 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:48:21 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:48:21 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:48:21 volumio volumio[1187]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 22 11:48:21 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 11:48:21 volumio volumio[1187]: info: Received Get System Info Dec 22 11:48:21 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 11:48:21 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 11:48:21 volumio volumio[1187]: info: Discovery: Getting this device information Dec 22 11:48:21 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Dec 22 11:48:21 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Dec 22 11:48:21 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 11:48:22 volumio volumio[1187]: info: Updating MyVolumio device info Dec 22 11:48:22 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:48:22 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:48:22 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:48:22 volumio kernel: hwmon hwmon1: Voltage normalised Dec 22 11:48:23 volumio volumio[1187]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 22 11:48:23 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 22 11:48:23 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Dec 22 11:48:23 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Dec 22 11:48:24 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 11:48:24 volumio volumio[1187]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 11:48:24 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Dec 22 11:48:24 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Dec 22 11:48:25 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 22 11:48:26 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 22 11:48:29 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:48:29 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:48:29 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 22 11:48:29 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 22 11:48:29 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 22 11:48:29 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 22 11:48:29 volumio volumio[1187]: info: CoreCommandRouter::volumioGetBrowseSources Dec 22 11:48:29 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 11:48:31 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Dec 22 11:48:31 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Dec 22 11:48:34 volumio volumio[1187]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 11:48:34 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 22 11:48:34 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 22 11:48:34 volumio sudo[1903]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 22 11:48:34 volumio sudo[1903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:34 volumio sudo[1900]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 22 11:48:34 volumio sudo[1900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:34 volumio sudo[1903]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:34 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 22 11:48:34 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 22 11:48:34 volumio sudo[1900]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:34 volumio sudo[1916]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 22 11:48:34 volumio sudo[1916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:34 volumio sudo[1922]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 11:48:34 volumio sudo[1912]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 22 11:48:34 volumio sudo[1922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:34 volumio sudo[1912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:34 volumio sudo[1916]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:34 volumio sudo[1924]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 11:48:34 volumio sudo[1924]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:34 volumio sudo[1922]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:34 volumio sudo[1912]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:34 volumio sudo[1927]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 22 11:48:34 volumio sudo[1927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:34 volumio sudo[1924]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:34 volumio sudo[1927]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:34 volumio volumio[1187]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 22 11:48:34 volumio volumio[1187]: wlan0 Interface doesn't support scanning : Network is down Dec 22 11:48:34 volumio volumio[1187]: info: Cannot use regular scanning, forcing with ap-force Dec 22 11:48:35 volumio sudo[1936]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 22 11:48:35 volumio sudo[1936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:35 volumio sudo[1936]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:35 volumio volumio[1187]: command failed: Network is down (-100) Dec 22 11:48:35 volumio volumio[1187]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 22 11:48:35 volumio volumio[1187]: command failed: Network is down (-100) Dec 22 11:48:35 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 11:48:35 volumio volumio[1187]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 22 11:48:35 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 22 11:48:35 volumio volumio[1187]: info: Received Get System Version Dec 22 11:48:35 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 11:48:35 volumio volumio[1187]: info: Received Get System Info Dec 22 11:48:35 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 11:48:35 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 11:48:35 volumio volumio[1187]: info: Discovery: Getting this device information Dec 22 11:48:35 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Dec 22 11:48:35 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Dec 22 11:48:35 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 11:48:35 volumio go-librespot[1607]: time="2025-12-22T11:48:35+09:00" level=trace msg="sent dealer ping" Dec 22 11:48:35 volumio go-librespot[1607]: time="2025-12-22T11:48:35+09:00" level=trace msg="received dealer pong" Dec 22 11:48:50 volumio sudo[1972]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 11:48:50 volumio sudo[1972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:50 volumio sudo[1974]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 11:48:50 volumio sudo[1972]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:50 volumio sudo[1974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:50 volumio sudo[1974]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:51 volumio volumio[1187]: verbose: New Socket.io Connection to 192.168.0.50 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 6 Dec 22 11:48:51 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 22 11:48:51 volumio volumio[1187]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 11:48:51 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 11:48:51 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Dec 22 11:48:51 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Dec 22 11:48:51 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 22 11:48:51 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 22 11:48:51 volumio volumio[1187]: info: Received Get System Info Dec 22 11:48:51 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 11:48:51 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 11:48:51 volumio volumio[1187]: info: Discovery: Getting this device information Dec 22 11:48:51 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Dec 22 11:48:51 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Dec 22 11:48:51 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 11:48:51 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Dec 22 11:48:51 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Dec 22 11:48:51 volumio volumio[1187]: info: Listing playlists Dec 22 11:48:51 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 22 11:48:51 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 11:48:51 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 22 11:48:51 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 22 11:48:52 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 11:48:57 volumio kernel: hwmon hwmon1: Voltage normalised Dec 22 11:48:57 volumio volumio[1187]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Dec 22 11:48:57 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Dec 22 11:48:57 volumio sudo[1997]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Dec 22 11:48:57 volumio sudo[1997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:58 volumio sudo[1997]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:58 volumio sudo[1999]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Dec 22 11:48:58 volumio sudo[1999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:58 volumio sudo[1999]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:58 volumio volumio[1187]: info: Permissions for /etc/network/interfaces set Dec 22 11:48:58 volumio volumio[1187]: info: Restarting Wireless Network Dec 22 11:48:58 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 22 11:48:58 volumio volumio[1187]: info: Discovery: Restarting Advertising due to device name change Dec 22 11:48:58 volumio sudo[2002]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 22 11:48:58 volumio sudo[2002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:58 volumio systemd[1]: Stopping wireless.service - Wireless Services... Dec 22 11:48:58 volumio systemd[1]: wireless.service: Deactivated successfully. Dec 22 11:48:58 volumio systemd[1]: Stopped wireless.service - Wireless Services. Dec 22 11:48:58 volumio systemd[1]: wireless.service: Consumed 3min 39.141s CPU time. Dec 22 11:48:58 volumio systemd[1]: Starting wireless.service - Wireless Services... Dec 22 11:48:59 volumio volumio[1187]: info: Discovery: A device disappeared from network Dec 22 11:48:59 volumio volumio[1187]: info: Discovery: Device volumio disappeared from network Dec 22 11:48:59 volumio volumio[1187]: info: Discovery: A device disappeared from network Dec 22 11:48:59 volumio wireless.js[2006]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 22 11:48:59 volumio wireless.js[2006]: WIRELESS.JS: Wired network status changed to: ---connected--- Dec 22 11:48:59 volumio wireless.js[2006]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 22 11:48:59 volumio wireless.js[2006]: WIRELESS.JS: Cleaning previous... Dec 22 11:48:59 volumio sudo[2024]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 22 11:48:59 volumio sudo[2024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 11:48:59 volumio sudo[2024]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:59 volumio sudo[2026]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 22 11:48:59 volumio sudo[2026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 11:48:59 volumio sudo[2026]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:59 volumio wireless.js[2006]: WIRELESS.JS: Stopped aP Dec 22 11:48:59 volumio sudo[2034]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 22 11:48:59 volumio sudo[2034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:48:59 volumio sudo[2034]: pam_unix(sudo:session): session closed for user root Dec 22 11:48:59 volumio wireless.js[2006]: SIOCSIFFLAGS: Operation not possible due to RF-kill Dec 22 11:48:59 volumio wireless.js[2006]: WIRELESS.JS: Failed to determine most appropriate reg domain: Error: Command failed: /usr/bin/sudo /sbin/ifconfig wlan0 up && /usr/bin/sudo /sbin/iw reg get | grep country | cut -f1 -d':' Dec 22 11:48:59 volumio wireless.js[2006]: SIOCSIFFLAGS: Operation not possible due to RF-kill Dec 22 11:48:59 volumio wireless.js[2006]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow Dec 22 11:48:59 volumio wireless.js[2006]: WIRELESS.JS: Notified systemd about wireless ready Dec 22 11:48:59 volumio systemd[1]: Started wireless.service - Wireless Services. Dec 22 11:48:59 volumio sudo[2002]: pam_unix(sudo:session): session closed for user root Dec 22 11:49:03 volumio volumio[1187]: info: Discovery: Started advertising with name: Volumio Dec 22 11:49:04 volumio volumio[1187]: compat.c: read() failed: Resource temporarily unavailable Dec 22 11:49:04 volumio volumio[1187]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 11:49:04 volumio volumio[1187]: Error: dns service error: unknown Dec 22 11:49:04 volumio volumio[1187]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 22 11:49:04 volumio volumio[1187]: errorCode: -65537 Dec 22 11:49:04 volumio volumio[1187]: } Dec 22 11:49:04 volumio volumio[1187]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 11:49:05 volumio go-librespot[1607]: time="2025-12-22T11:49:05+09:00" level=trace msg="sent dealer ping" Dec 22 11:49:05 volumio go-librespot[1607]: time="2025-12-22T11:49:05+09:00" level=trace msg="received dealer pong" Dec 22 11:49:07 volumio sudo[2069]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-22 11:48' Dec 22 11:49:07 volumio sudo[2069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"