-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Wed 2025-07-09 17:22:34 +07. -- Jul 09 17:21:32 volumio9 systemd-timedated[1258]: Changed local time to Wed Jul 9 17:21:32 2025 Jul 09 17:21:32 volumio9 sudo[1212]: pam_unix(sudo:session): session closed for user root Jul 09 17:21:32 volumio9 volumio-time-update[634]: volumio-time-update-util: System time updated successfully. Jul 09 17:21:32 volumio9 volumio[825]: info: Discovery: A device disappeared from network Jul 09 17:21:32 volumio9 volumio[825]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jul 09 17:21:32 volumio9 systemd[1]: Starting Daily apt download activities... Jul 09 17:21:32 volumio9 systemd[1]: Starting Daily man-db regeneration... Jul 09 17:21:32 volumio9 systemd[1]: Started Volumio Time Update Utility. Jul 09 17:21:32 volumio9 systemd[1]: Reached target Multi-User System. Jul 09 17:21:32 volumio9 systemd[1]: Reached target Graphical Interface. Jul 09 17:21:32 volumio9 kernel: CIFS: Attempting to mount //192.168.1.12/Hires Music Jul 09 17:21:32 volumio9 sudo[1214]: pam_unix(sudo:session): session closed for user root Jul 09 17:21:32 volumio9 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 09 17:21:32 volumio9 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 09 17:21:32 volumio9 systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 09 17:21:32 volumio9 volumio[825]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jul 09 17:21:32 volumio9 volumio[1264]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 09 17:21:32 volumio9 sudo[1216]: pam_unix(sudo:session): session closed for user root Jul 09 17:21:33 volumio9 volumio[825]: info: Initializing connection to go-librespot Websocket Jul 09 17:21:33 volumio9 go-librespot[1150]: time="2025-07-09T17:21:33+07:00" level=debug msg="new websocket client" Jul 09 17:21:33 volumio9 volumio[825]: info: Connection to go-librespot Websocket established Jul 09 17:21:33 volumio9 go-librespot[1150]: time="2025-07-09T17:21:33+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 09 17:21:33 volumio9 go-librespot[1150]: time="2025-07-09T17:21:33+07:00" level=trace msg="emitting websocket event: volume" Jul 09 17:21:33 volumio9 volumio[825]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Jul 09 17:21:33 volumio9 volumio[825]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Jul 09 17:21:33 volumio9 volumio[825]: info: Setting Volumio Volume from Spotify: 100 Jul 09 17:21:33 volumio9 volumio[825]: info: VolumeController::SetAlsaVolume100 Jul 09 17:21:33 volumio9 volumio[825]: info: CoreStateMachine::pushState Jul 09 17:21:33 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:21:33 volumio9 volumio[825]: info: CoreCommandRouter::volumioPushState Jul 09 17:21:33 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:21:33 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:21:33 volumio9 volumio[825]: info: touch_display: Setting screensaver timeout to 45 seconds. Jul 09 17:21:34 volumio9 volumio[1264]: ......................++++ Jul 09 17:21:35 volumio9 systemd[1]: apt-daily.service: Succeeded. Jul 09 17:21:35 volumio9 systemd[1]: Started Daily apt download activities. Jul 09 17:21:35 volumio9 systemd[1]: Starting Daily apt upgrade and clean activities... Jul 09 17:21:36 volumio9 volumio[825]: info: Getting Spotify volume Jul 09 17:21:36 volumio9 volumio[825]: info: Spotify volume: 100 Jul 09 17:21:36 volumio9 volumio[825]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 09 17:21:36 volumio9 volumio[825]: info: Adding plugin bluetooth to MyMusic Plugins Jul 09 17:21:36 volumio9 volumio[825]: info: Adding plugin multiroom to MyMusic Plugins Jul 09 17:21:36 volumio9 volumio[825]: info: Adding plugin metavolumio to MyMusic Plugins Jul 09 17:21:36 volumio9 volumio[825]: info: Adding plugin cd_controller to MyMusic Plugins Jul 09 17:21:36 volumio9 volumio[825]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 09 17:21:36 volumio9 volumio[825]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 09 17:21:36 volumio9 volumio[825]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 09 17:21:36 volumio9 volumio[825]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 09 17:21:37 volumio9 systemd[1]: man-db.service: Succeeded. Jul 09 17:21:37 volumio9 systemd[1]: Started Daily man-db regeneration. Jul 09 17:21:38 volumio9 systemd[1]: apt-daily-upgrade.service: Succeeded. Jul 09 17:21:38 volumio9 systemd[1]: Started Daily apt upgrade and clean activities. Jul 09 17:21:38 volumio9 systemd[1]: Startup finished in 17.322s (kernel) + 31.976s (userspace) = 49.299s. Jul 09 17:21:38 volumio9 volumio[825]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 09 17:21:38 volumio9 volumio[825]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 09 17:21:39 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 17:21:39 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 17:21:39 volumio9 volumio[825]: info: Starting MyVolumio Remote Streaming Endpoints Jul 09 17:21:39 volumio9 volumio[825]: info: MyVolumio login type: Token Jul 09 17:21:39 volumio9 volumio[825]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 09 17:21:39 volumio9 volumio[825]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 09 17:21:39 volumio9 volumio[1264]: ........................................................++++ Jul 09 17:21:39 volumio9 volumio[1264]: e is 65537 (0x010001) Jul 09 17:21:39 volumio9 volumio[1264]: writing RSA key Jul 09 17:21:39 volumio9 volumio[825]: info: Starting Streaming Service Transparent Proxy Jul 09 17:21:39 volumio9 volumio[825]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 09 17:21:39 volumio9 volumio[825]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 09 17:21:39 volumio9 volumio[825]: info: Streaming services startup Jul 09 17:21:39 volumio9 volumio[825]: info: Starting Streaming Daemon Jul 09 17:21:40 volumio9 sudo[1559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 09 17:21:40 volumio9 sudo[1559]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 17:21:40 volumio9 sudo[1559]: pam_unix(sudo:session): session closed for user root Jul 09 17:21:40 volumio9 volumio[825]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 09 17:21:40 volumio9 volumio[825]: error: Cannot start Volumio Streaming Daemon Jul 09 17:21:40 volumio9 volumio[825]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 09 17:21:40 volumio9 volumio[825]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 09 17:21:40 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:21:40 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:21:40 volumio9 volumio[825]: error: MyVolumio Custom Token format not valid, refreshing it Jul 09 17:21:40 volumio9 volumio[825]: STREAMING PROXY: Starting server on port 3245 Jul 09 17:21:40 volumio9 volumio[825]: Node JS runtime: 14 Jul 09 17:21:41 volumio9 volumio[825]: info: MyVolumio login type: Token Jul 09 17:21:42 volumio9 systemd[1]: systemd-fsckd.service: Succeeded. Jul 09 17:21:42 volumio9 volumio[825]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 09 17:21:45 volumio9 systemd[1]: systemd-hostnamed.service: Succeeded. Jul 09 17:21:46 volumio9 volumio[825]: info: MyVolumio token set successfully Jul 09 17:21:46 volumio9 volumio[825]: info: MYVOLUMIO: Adding device Jul 09 17:21:46 volumio9 volumio[825]: info: MYVOLUMIO: Evaluating Server Jul 09 17:21:46 volumio9 volumio[825]: info: MyVolumio status changed Jul 09 17:21:46 volumio9 volumio[825]: info: Streaming services startup Jul 09 17:21:46 volumio9 volumio[825]: info: Starting Streaming Daemon Jul 09 17:21:46 volumio9 volumio[825]: info: Removing browser output: myVolumio user plan is not superstar Jul 09 17:21:46 volumio9 volumio[825]: info: Removing audio output: Jul 09 17:21:46 volumio9 volumio[825]: info: Stoppping Tunnel 1 Jul 09 17:21:46 volumio9 sudo[1742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 09 17:21:46 volumio9 sudo[1742]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 17:21:46 volumio9 sudo[1744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 09 17:21:46 volumio9 sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 17:21:46 volumio9 sudo[1742]: pam_unix(sudo:session): session closed for user root Jul 09 17:21:46 volumio9 volumio[825]: error: Cannot start Volumio Streaming Daemon Jul 09 17:21:46 volumio9 volumio[825]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 09 17:21:46 volumio9 volumio[825]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 09 17:21:46 volumio9 sudo[1744]: pam_unix(sudo:session): session closed for user root Jul 09 17:21:46 volumio9 volumio[825]: info: Remote SSH Stopped Jul 09 17:21:47 volumio9 volumio[825]: info: Setting Geolocation for MyVolumio to as1 Jul 09 17:21:47 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 17:21:47 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 17:21:47 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 17:21:47 volumio9 volumio[825]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 09 17:21:48 volumio9 volumio[825]: info: Updating MyVolumio device info Jul 09 17:21:48 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 17:21:48 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 17:21:48 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 17:21:48 volumio9 volumio[825]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 09 17:21:51 volumio9 volumio[825]: info: MYVOLUMIO: Adding device Jul 09 17:21:51 volumio9 volumio[825]: info: MYVOLUMIO: Evaluating Server Jul 09 17:21:52 volumio9 volumio[825]: info: Setting Geolocation for MyVolumio to as1 Jul 09 17:21:52 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 17:21:52 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 17:21:52 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 17:21:53 volumio9 volumio[825]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 09 17:21:53 volumio9 volumio[825]: info: Updating MyVolumio device info Jul 09 17:21:53 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 17:21:54 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 09 17:21:54 volumio9 volumio[825]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 09 17:21:54 volumio9 volumio[825]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 09 17:21:54 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:21:54 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:21:54 volumio9 volumio[825]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jul 09 17:21:55 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 09 17:21:55 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 17:21:55 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 09 17:21:55 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 09 17:21:55 volumio9 volumio-remote-updater[624]: No test mode Jul 09 17:21:55 volumio9 volumio-remote-updater[624]: No alpha test mode Jul 09 17:21:55 volumio9 volumio[825]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jul 09 17:21:56 volumio9 volumio[825]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 09 17:21:56 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 09 17:21:56 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 09 17:21:56 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetVisibleSources Jul 09 17:21:56 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 17:21:56 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:21:56 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:21:56 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 09 17:21:56 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 09 17:21:56 volumio9 volumio[825]: info: Received Get System Info Jul 09 17:21:56 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 17:21:56 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 17:21:56 volumio9 volumio[825]: info: Discovery: Getting this device information Jul 09 17:21:56 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:21:56 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:21:56 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 17:21:56 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:21:56 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:21:56 volumio9 volumio[825]: info: Listing playlists Jul 09 17:21:56 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 09 17:21:57 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 09 17:21:57 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 09 17:21:57 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:21:57 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:21:57 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 09 17:21:58 volumio9 volumio[825]: info: Received Get System Info Jul 09 17:21:58 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 17:21:58 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 17:21:58 volumio9 volumio[825]: info: Discovery: Getting this device information Jul 09 17:21:58 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:21:58 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:21:58 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 17:21:58 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 09 17:21:59 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 17:21:59 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 17:21:59 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 09 17:21:59 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 09 17:21:59 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 09 17:21:59 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetBrowseSources Jul 09 17:21:59 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 09 17:21:59 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 09 17:22:00 volumio9 volumio[825]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 09 17:22:00 volumio9 volumio[825]: info: Completed starting MyVolumio Plugin Jul 09 17:22:00 volumio9 volumio[825]: [Metrics] CommandRouter: 41s 131.50ms Jul 09 17:22:00 volumio9 volumio[825]: info: CoreCommandRouter::volumiosetStartupVolume Jul 09 17:22:00 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 17:22:00 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 17:22:00 volumio9 volumio[825]: info: CoreCommandRouter::Close All Modals sent Jul 09 17:22:00 volumio9 volumio[825]: info: CoreCommandRouter::Close All Modals sent Jul 09 17:22:00 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 09 17:22:00 volumio9 volumio[825]: info: Received Get System Info Jul 09 17:22:00 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 17:22:00 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 17:22:00 volumio9 volumio[825]: info: Discovery: Getting this device information Jul 09 17:22:00 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:22:00 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:22:00 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 17:22:01 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 09 17:22:01 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 09 17:22:01 volumio9 go-librespot[1150]: time="2025-07-09T17:22:01+07:00" level=trace msg="sent dealer ping" Jul 09 17:22:01 volumio9 go-librespot[1150]: time="2025-07-09T17:22:01+07:00" level=trace msg="received dealer pong" Jul 09 17:22:01 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 09 17:22:01 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 09 17:22:01 volumio9 volumio[825]: info: Received Get System Info Jul 09 17:22:01 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 17:22:01 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 17:22:01 volumio9 volumio[825]: info: Discovery: Getting this device information Jul 09 17:22:01 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:22:01 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:22:01 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 17:22:02 volumio9 systemd[1]: systemd-timedated.service: Succeeded. Jul 09 17:22:04 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 17:22:04 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 17:22:04 volumio9 volumio[825]: info: Discovery: Getting this device information Jul 09 17:22:04 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:22:04 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:22:04 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 17:22:04 volumio9 volumio[825]: verbose: New Socket.io Connection to 192.168.1.82:3000 from 192.168.1.72 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jul 09 17:22:04 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:22:04 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:22:04 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 09 17:22:04 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 09 17:22:05 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 09 17:22:06 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:22:06 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:22:07 volumio9 volumio[825]: info: BOOT COMPLETED Jul 09 17:22:07 volumio9 volumio[825]: verbose: New Socket.io Connection to 192.168.1.82:3000 from 192.168.1.72 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jul 09 17:22:07 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:22:07 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:22:07 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 09 17:22:07 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 09 17:22:07 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:22:07 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:22:14 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 17:22:14 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 17:22:14 volumio9 volumio[825]: info: Discovery: Getting this device information Jul 09 17:22:14 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:22:14 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:22:14 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 17:22:17 volumio9 sudo[2107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 09 17:22:17 volumio9 sudo[2107]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 17:22:17 volumio9 sudo[2107]: pam_unix(sudo:session): session closed for user root Jul 09 17:22:17 volumio9 sudo[2109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 09 17:22:17 volumio9 sudo[2109]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 17:22:17 volumio9 sudo[2109]: pam_unix(sudo:session): session closed for user root Jul 09 17:22:17 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 09 17:22:17 volumio9 volumio[825]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 09 17:22:17 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 09 17:22:17 volumio9 volumio[825]: info: Received Get System Version Jul 09 17:22:17 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 09 17:22:17 volumio9 volumio[825]: info: Received Get System Info Jul 09 17:22:17 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 17:22:17 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 17:22:17 volumio9 volumio[825]: info: Discovery: Getting this device information Jul 09 17:22:17 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:22:17 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:22:17 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 17:22:17 volumio9 volumio[825]: verbose: New Socket.io Connection to 192.168.1.82 from 192.168.1.72 UA: Mozilla/5.0 (Linux; Android 13; 2201117TG Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.117 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jul 09 17:22:18 volumio9 sudo[2116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 09 17:22:18 volumio9 sudo[2116]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 17:22:18 volumio9 sudo[2116]: pam_unix(sudo:session): session closed for user root Jul 09 17:22:18 volumio9 sudo[2118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 09 17:22:18 volumio9 sudo[2118]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 17:22:18 volumio9 sudo[2118]: pam_unix(sudo:session): session closed for user root Jul 09 17:22:18 volumio9 volumio[825]: verbose: New Socket.io Connection to 192.168.1.82 from 192.168.1.72 UA: Mozilla/5.0 (Linux; Android 13; 2201117TG Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.117 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jul 09 17:22:19 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 09 17:22:19 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetVisibleSources Jul 09 17:22:19 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 17:22:19 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:22:19 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:22:19 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 09 17:22:19 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 09 17:22:19 volumio9 volumio[825]: info: Received Get System Info Jul 09 17:22:19 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 17:22:19 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 17:22:19 volumio9 volumio[825]: info: Discovery: Getting this device information Jul 09 17:22:19 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:22:19 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:22:19 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 17:22:19 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:22:19 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:22:19 volumio9 volumio[825]: info: Listing playlists Jul 09 17:22:19 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 09 17:22:19 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 09 17:22:19 volumio9 ntpd[785]: Soliciting pool server 202.28.92.208 Jul 09 17:22:19 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 09 17:22:20 volumio9 ntpd[785]: Soliciting pool server 203.159.70.33 Jul 09 17:22:20 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 09 17:22:20 volumio9 volumio[825]: info: Received Get System Info Jul 09 17:22:20 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 17:22:20 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 17:22:20 volumio9 volumio[825]: info: Discovery: Getting this device information Jul 09 17:22:20 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:22:20 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:22:20 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 17:22:20 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 09 17:22:21 volumio9 ntpd[785]: Soliciting pool server 202.28.116.236 Jul 09 17:22:21 volumio9 ntpd[785]: Soliciting pool server 202.28.117.7 Jul 09 17:22:22 volumio9 ntpd[785]: Soliciting pool server 202.80.227.130 Jul 09 17:22:22 volumio9 ntpd[785]: Soliciting pool server 147.50.17.138 Jul 09 17:22:22 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 09 17:22:22 volumio9 volumio[825]: info: Received Get System Info Jul 09 17:22:22 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 17:22:22 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 17:22:22 volumio9 volumio[825]: info: Discovery: Getting this device information Jul 09 17:22:22 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetState Jul 09 17:22:22 volumio9 volumio[825]: info: CorePlayQueue::getTrack 0 Jul 09 17:22:22 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 17:22:23 volumio9 ntpd[785]: Soliciting pool server 2001:c38:2000:180:d82a:1aff:fe38:f84b Jul 09 17:22:24 volumio9 ntpd[785]: Soliciting pool server 202.29.58.130 Jul 09 17:22:25 volumio9 ntpd[785]: Soliciting pool server 122.155.180.231 Jul 09 17:22:25 volumio9 volumio[825]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 17:22:25 volumio9 volumio[825]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 17:22:25 volumio9 volumio[825]: info: Retrieving Cloud Streaming UI Jul 09 17:22:25 volumio9 volumio[825]: info: Getting Tidal Cloud Configuration Jul 09 17:22:25 volumio9 volumio[825]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 17:22:25 volumio9 volumio[825]: info: Getting Qobuz Cloud Configuration Jul 09 17:22:25 volumio9 volumio[825]: info: Asking plugin for UI Config Jul 09 17:22:25 volumio9 volumio[825]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 17:22:25 volumio9 volumio[825]: info: Getting Spotify Cloud Configuration Jul 09 17:22:25 volumio9 volumio[825]: info: Asking plugin for UI Config Jul 09 17:22:25 volumio9 volumio[825]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 17:22:25 volumio9 volumio[825]: info: Saving Spotify Acccount Jul 09 17:22:25 volumio9 volumio[825]: info: Got Tidal Cloud Configuration Jul 09 17:22:25 volumio9 volumio[825]: info: Got it Jul 09 17:22:25 volumio9 volumio[825]: info: Got it Jul 09 17:22:25 volumio9 volumio[825]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 09 17:22:25 volumio9 volumio[825]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 17:22:25 volumio9 volumio[825]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 09 17:22:25 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 09 17:22:25 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 09 17:22:25 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 09 17:22:25 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 09 17:22:25 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 17:22:25 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 17:22:25 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 17:22:25 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 17:22:25 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetBrowseSources Jul 09 17:22:25 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetBrowseSources Jul 09 17:22:25 volumio9 volumio[825]: info: CoreCommandRouter::volumioGetBrowseSources Jul 09 17:22:26 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 17:22:26 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 09 17:22:29 volumio9 ntpd[785]: receive: Unexpected origin timestamp 0xec18c162.5b3022fe does not match aorg 0000000000.00000000 from server@202.28.116.236 xmt 0xec18c165.fc8af3f3 Jul 09 17:22:32 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 09 17:22:33 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 17:22:34 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jul 09 17:22:34 volumio9 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jul 09 17:22:34 volumio9 volumio[825]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 17:22:34 volumio9 volumio[825]: Error: getaddrinfo -3007 Jul 09 17:22:34 volumio9 volumio[825]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Jul 09 17:22:34 volumio9 volumio[825]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Jul 09 17:22:34 volumio9 volumio[825]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Jul 09 17:22:34 volumio9 volumio[825]: code: -3007, Jul 09 17:22:34 volumio9 volumio[825]: errno: -3007, Jul 09 17:22:34 volumio9 volumio[825]: syscall: 'getaddrinfo' Jul 09 17:22:34 volumio9 volumio[825]: } Jul 09 17:22:34 volumio9 volumio[825]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 17:22:34 volumio9 sudo[2152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-09 17:21 Jul 09 17:22:34 volumio9 sudo[2152]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"