-- Logs begin at Thu 2025-09-04 08:52:09 JST, end at Thu 2025-09-04 08:54:29 JST. -- Sep 04 08:53:00 volumio volumio[1313]: verbose: New Socket.io Connection to 192.168.223.251 from 192.168.223.191 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7 Sep 04 08:53:00 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 04 08:53:00 volumio volumio[1313]: info: CoreCommandRouter::volumioGetVisibleSources Sep 04 08:53:00 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 08:53:00 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Sep 04 08:53:00 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:00 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Sep 04 08:53:00 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:00 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 04 08:53:00 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 04 08:53:00 volumio volumio[1313]: info: Received Get System Info Sep 04 08:53:00 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 08:53:00 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 08:53:00 volumio volumio[1313]: info: Discovery: Getting this device information Sep 04 08:53:00 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Sep 04 08:53:00 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:00 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 08:53:00 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Sep 04 08:53:00 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:00 volumio volumio[1313]: info: Listing playlists Sep 04 08:53:01 volumio volumio[1574]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 04 08:53:02 volumio volumio[1574]: ....++++ Sep 04 08:53:03 volumio volumio[1313]: info: Sep 04 08:53:03 volumio volumio[1313]: ---------------------------- Client requests Volume 30 Sep 04 08:53:03 volumio volumio[1313]: info: VolumeController::SetAlsaVolume30 Sep 04 08:53:03 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:53:03 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:03 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:53:04 volumio volumio[1313]: info: Sep 04 08:53:04 volumio volumio[1313]: ---------------------------- Client requests Volume NaN Sep 04 08:53:04 volumio volumio[1313]: info: VolumeController::SetAlsaVolumeNaN Sep 04 08:53:04 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:53:04 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:04 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:53:04 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:53:04 volumio volumio[1313]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 04 08:53:04 volumio volumio[1313]: info: VolumeController::SetAlsaVolume0 Sep 04 08:53:04 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:53:04 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:04 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:53:04 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:53:06 volumio volumio[1313]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=nanopineo-armbian&variante=volumio&version=3.832&uuid=58bbcc613d773b3c8951b426f939e7ab" http://updates.volumio.org/downloader-v1/track-device Sep 04 08:53:06 volumio volumio[1313]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 04 08:53:06 volumio volumio[1313]: Dload Upload Total Spent Left Speed Sep 04 08:53:06 volumio volumio[1313]: [132B blob data] Sep 04 08:53:06 volumio volumio[1313]: retrying in 5 seconds, trial 0 Sep 04 08:53:06 volumio volumio[1313]: info: Volumio Calling Home Sep 04 08:53:07 volumio volumio[1313]: info: Volumio called home Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 04 08:53:08 volumio volumio[1313]: info: Adding plugin bluetooth to MyMusic Plugins Sep 04 08:53:08 volumio volumio[1313]: info: Adding plugin multiroom to MyMusic Plugins Sep 04 08:53:08 volumio volumio[1313]: info: Adding plugin metavolumio to MyMusic Plugins Sep 04 08:53:08 volumio volumio[1313]: info: Adding plugin cd_controller to MyMusic Plugins Sep 04 08:53:08 volumio volumio[1313]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 04 08:53:08 volumio volumio[1313]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 04 08:53:08 volumio volumio[1313]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 04 08:53:08 volumio volumio[1313]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 04 08:53:10 volumio volumio[1313]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 04 08:53:10 volumio volumio[1313]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 04 08:53:10 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 08:53:11 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 08:53:11 volumio volumio[1313]: info: Starting MyVolumio Remote Streaming Endpoints Sep 04 08:53:11 volumio volumio[1313]: info: MyVolumio login type: Token Sep 04 08:53:11 volumio volumio[1313]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 04 08:53:11 volumio volumio[1313]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 04 08:53:13 volumio volumio[1313]: info: Starting Streaming Service Transparent Proxy Sep 04 08:53:13 volumio volumio[1313]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 04 08:53:13 volumio volumio[1313]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 04 08:53:13 volumio volumio[1313]: info: Streaming services startup Sep 04 08:53:13 volumio volumio[1313]: info: Starting Streaming Daemon Sep 04 08:53:14 volumio sudo[1715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 04 08:53:14 volumio sudo[1715]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 08:53:14 volumio volumio[1313]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 04 08:53:14 volumio sudo[1715]: pam_unix(sudo:session): session closed for user root Sep 04 08:53:14 volumio volumio[1313]: error: Cannot start Volumio Streaming Daemon Sep 04 08:53:14 volumio volumio[1313]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 04 08:53:14 volumio volumio[1313]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 04 08:53:14 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Sep 04 08:53:14 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:14 volumio volumio[1313]: error: MyVolumio Custom Token format not valid, refreshing it Sep 04 08:53:15 volumio volumio[1313]: info: MyVolumio login type: Token Sep 04 08:53:16 volumio volumio[1313]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 04 08:53:16 volumio volumio[1313]: STREAMING PROXY: Starting server on port 3245 Sep 04 08:53:16 volumio volumio[1313]: Node JS runtime: 14 Sep 04 08:53:16 volumio volumio[1313]: info: MyVolumio token set successfully Sep 04 08:53:16 volumio volumio[1313]: info: MYVOLUMIO: Adding device Sep 04 08:53:16 volumio volumio[1313]: info: MYVOLUMIO: Evaluating Server Sep 04 08:53:17 volumio volumio[1313]: info: MyVolumio status changed Sep 04 08:53:17 volumio volumio[1313]: info: Streaming services startup Sep 04 08:53:17 volumio volumio[1313]: info: Starting Streaming Daemon Sep 04 08:53:17 volumio volumio[1313]: info: Removing browser output: myVolumio user plan is not superstar Sep 04 08:53:17 volumio volumio[1313]: info: Removing audio output: Sep 04 08:53:17 volumio volumio[1313]: info: Stoppping Tunnel 1 Sep 04 08:53:17 volumio sudo[1783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 04 08:53:17 volumio sudo[1783]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 08:53:17 volumio sudo[1783]: pam_unix(sudo:session): session closed for user root Sep 04 08:53:17 volumio sudo[1786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 04 08:53:17 volumio sudo[1786]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 08:53:17 volumio volumio[1313]: info: Setting Geolocation for MyVolumio to as1 Sep 04 08:53:17 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 08:53:17 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 08:53:17 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 08:53:17 volumio sudo[1786]: pam_unix(sudo:session): session closed for user root Sep 04 08:53:17 volumio volumio[1313]: error: Cannot start Volumio Streaming Daemon Sep 04 08:53:17 volumio volumio[1313]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 04 08:53:17 volumio volumio[1313]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 04 08:53:17 volumio volumio[1313]: info: Remote SSH Stopped Sep 04 08:53:18 volumio volumio[1313]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 04 08:53:18 volumio volumio[1313]: info: Updating MyVolumio device info Sep 04 08:53:18 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 08:53:18 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 08:53:18 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 08:53:19 volumio volumio[1313]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 04 08:53:19 volumio volumio[1313]: info: Sep 04 08:53:19 volumio volumio[1313]: ---------------------------- Client requests Volume 30 Sep 04 08:53:19 volumio volumio[1313]: info: VolumeController::SetAlsaVolume30 Sep 04 08:53:19 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:53:19 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:19 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:53:19 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:53:20 volumio volumio[1313]: info: Sep 04 08:53:20 volumio volumio[1313]: ---------------------------- Client requests Volume NaN Sep 04 08:53:20 volumio volumio[1313]: info: VolumeController::SetAlsaVolumeNaN Sep 04 08:53:20 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:53:20 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:20 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:53:20 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:53:21 volumio volumio[1313]: info: VolumeController::SetAlsaVolume0 Sep 04 08:53:21 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:53:21 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:21 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:53:21 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:53:22 volumio volumio[1313]: info: MYVOLUMIO: Adding device Sep 04 08:53:22 volumio volumio[1313]: info: MYVOLUMIO: Evaluating Server Sep 04 08:53:23 volumio volumio[1313]: info: Setting Geolocation for MyVolumio to as1 Sep 04 08:53:23 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 08:53:23 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 08:53:23 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 08:53:23 volumio volumio[1313]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 04 08:53:24 volumio volumio[1313]: info: Updating MyVolumio device info Sep 04 08:53:24 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 08:53:24 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 08:53:24 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 08:53:24 volumio volumio[1313]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 04 08:53:24 volumio ntpd[994]: Soliciting pool server 167.179.119.205 Sep 04 08:53:25 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 04 08:53:25 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 08:53:25 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 04 08:53:25 volumio volumio-remote-updater[745]: Test mode enabled Sep 04 08:53:25 volumio volumio-remote-updater[745]: No alpha test mode Sep 04 08:53:25 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 04 08:53:25 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Sep 04 08:53:25 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:26 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 04 08:53:26 volumio volumio[1313]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 04 08:53:26 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Sep 04 08:53:26 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:26 volumio volumio[1313]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 04 08:53:26 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 04 08:53:28 volumio ntpd[994]: Soliciting pool server 162.159.200.123 Sep 04 08:53:28 volumio ntpd[994]: Soliciting pool server 203.135.216.218 Sep 04 08:53:29 volumio ntpd[994]: Soliciting pool server 162.159.200.1 Sep 04 08:53:29 volumio ntpd[994]: Soliciting pool server 129.250.35.251 Sep 04 08:53:29 volumio volumio[1574]: .............................................................................................................................................................................................................................++++ Sep 04 08:53:30 volumio volumio[1574]: e is 65537 (0x010001) Sep 04 08:53:30 volumio volumio[1574]: writing RSA key Sep 04 08:53:30 volumio ntpd[994]: Soliciting pool server 129.250.35.250 Sep 04 08:53:30 volumio ntpd[994]: Soliciting pool server 46.232.60.193 Sep 04 08:53:30 volumio ntpd[994]: Soliciting pool server 133.130.121.141 Sep 04 08:53:31 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 08:53:31 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 08:53:31 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 04 08:53:31 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 04 08:53:31 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 04 08:53:31 volumio volumio[1313]: info: CoreCommandRouter::volumioGetBrowseSources Sep 04 08:53:31 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 04 08:53:31 volumio volumio[1313]: info: Sep 04 08:53:31 volumio volumio[1313]: ---------------------------- Client requests Volume 30 Sep 04 08:53:31 volumio volumio[1313]: info: VolumeController::SetAlsaVolume30 Sep 04 08:53:31 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:53:31 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:31 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:53:31 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:53:31 volumio ntpd[994]: Soliciting pool server 103.131.151.20 Sep 04 08:53:32 volumio volumio[1313]: info: Sep 04 08:53:32 volumio volumio[1313]: ---------------------------- Client requests Volume NaN Sep 04 08:53:32 volumio volumio[1313]: info: VolumeController::SetAlsaVolumeNaN Sep 04 08:53:32 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:53:32 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:32 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:53:32 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:53:32 volumio ntpd[994]: Soliciting pool server 2602:feda:6f:3e::a Sep 04 08:53:32 volumio volumio[1313]: info: VolumeController::SetAlsaVolume0 Sep 04 08:53:32 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:53:32 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:32 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:53:32 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:53:34 volumio volumio[1313]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 04 08:53:34 volumio volumio[1313]: info: Completed starting MyVolumio Plugin Sep 04 08:53:34 volumio volumio[1313]: [Metrics] CommandRouter: 56s 388.30ms Sep 04 08:53:34 volumio volumio[1313]: info: CoreCommandRouter::volumiosetStartupVolume Sep 04 08:53:34 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 08:53:34 volumio volumio[1313]: info: VolumeController:: Setting startup Volume 70 Sep 04 08:53:34 volumio volumio[1313]: info: VolumeController::SetAlsaVolume70 Sep 04 08:53:34 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 08:53:34 volumio volumio[1313]: info: CoreCommandRouter::Close All Modals sent Sep 04 08:53:34 volumio volumio[1313]: info: CoreCommandRouter::Close All Modals sent Sep 04 08:53:34 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:53:34 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:34 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:53:34 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:53:35 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 04 08:53:35 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 04 08:53:35 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 04 08:53:36 volumio ntpd[994]: receive: Unexpected origin timestamp 0xec63537f.889d53f4 does not match aorg 0000000000.00000000 from server@46.232.60.193 xmt 0xec635380.f80089f2 Sep 04 08:53:38 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Sep 04 08:53:38 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:42 volumio volumio[1313]: info: BOOT COMPLETED Sep 04 08:53:44 volumio volumio[1313]: info: Sep 04 08:53:44 volumio volumio[1313]: ---------------------------- Client requests Volume 30 Sep 04 08:53:44 volumio volumio[1313]: info: VolumeController::SetAlsaVolume30 Sep 04 08:53:44 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:53:44 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:44 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:53:44 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:53:45 volumio volumio[1313]: info: Sep 04 08:53:45 volumio volumio[1313]: ---------------------------- Client requests Volume NaN Sep 04 08:53:45 volumio volumio[1313]: info: VolumeController::SetAlsaVolumeNaN Sep 04 08:53:45 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:53:45 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:45 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:53:45 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:53:45 volumio volumio[1313]: info: VolumeController::SetAlsaVolume0 Sep 04 08:53:45 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:53:45 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:45 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:53:45 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:53:50 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Sep 04 08:53:50 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:55 volumio volumio[1313]: info: Sep 04 08:53:55 volumio volumio[1313]: ---------------------------- Client requests Volume 30 Sep 04 08:53:55 volumio volumio[1313]: info: VolumeController::SetAlsaVolume30 Sep 04 08:53:55 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:53:55 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:55 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:53:55 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:53:56 volumio volumio[1313]: info: Sep 04 08:53:56 volumio volumio[1313]: ---------------------------- Client requests Volume NaN Sep 04 08:53:56 volumio volumio[1313]: info: VolumeController::SetAlsaVolumeNaN Sep 04 08:53:56 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:53:56 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:56 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:53:56 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:53:57 volumio volumio[1313]: info: VolumeController::SetAlsaVolume0 Sep 04 08:53:57 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:53:57 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:53:57 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:53:57 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:54:01 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Sep 04 08:54:01 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:54:07 volumio volumio[1313]: info: Sep 04 08:54:07 volumio volumio[1313]: ---------------------------- Client requests Volume 30 Sep 04 08:54:07 volumio volumio[1313]: info: VolumeController::SetAlsaVolume30 Sep 04 08:54:07 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:54:07 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:54:07 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:54:07 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:54:08 volumio volumio[1313]: info: Sep 04 08:54:08 volumio volumio[1313]: ---------------------------- Client requests Volume NaN Sep 04 08:54:08 volumio volumio[1313]: info: VolumeController::SetAlsaVolumeNaN Sep 04 08:54:08 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:54:08 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:54:08 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:54:08 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:54:08 volumio volumio[1313]: info: VolumeController::SetAlsaVolume0 Sep 04 08:54:08 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:54:08 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:54:08 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:54:08 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:54:13 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Sep 04 08:54:13 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:54:18 volumio volumio[1313]: info: Sep 04 08:54:18 volumio volumio[1313]: ---------------------------- Client requests Volume 30 Sep 04 08:54:18 volumio volumio[1313]: info: VolumeController::SetAlsaVolume30 Sep 04 08:54:18 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:54:18 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:54:18 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:54:18 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:54:19 volumio dhcpcd[814]: eth0: carrier lost Sep 04 08:54:19 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Down Sep 04 08:54:19 volumio volumio[1313]: info: Sep 04 08:54:19 volumio volumio[1313]: ---------------------------- Client requests Volume NaN Sep 04 08:54:19 volumio volumio[1313]: info: VolumeController::SetAlsaVolumeNaN Sep 04 08:54:19 volumio volumio[1313]: info: CoreStateMachine::pushState Sep 04 08:54:19 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:54:19 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:54:19 volumio avahi-daemon[731]: Withdrawing address record for 192.168.223.251 on eth0. Sep 04 08:54:19 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Sep 04 08:54:19 volumio avahi-daemon[731]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.223.251. Sep 04 08:54:19 volumio avahi-daemon[731]: Interface eth0.IPv4 no longer relevant for mDNS. Sep 04 08:54:19 volumio dhcpcd[814]: eth0: deleting route to 192.168.223.0/24 Sep 04 08:54:19 volumio dhcpcd[814]: eth0: deleting default route via 192.168.223.1 Sep 04 08:54:19 volumio volumio[1313]: info: Discovery: A device disappeared from network Sep 04 08:54:19 volumio volumio[1313]: info: Discovery: Device volumio disappeared from network Sep 04 08:54:20 volumio ifplugd(eth0)[986]: Link beat lost. Sep 04 08:54:20 volumio ntpd[994]: Deleting interface #4 eth0, 192.168.223.251#123, interface stats: received=69, sent=69, dropped=3, active_time=80 secs Sep 04 08:54:20 volumio ntpd[994]: 103.131.151.20 local addr 192.168.223.251 -> Sep 04 08:54:20 volumio ntpd[994]: 46.232.60.193 local addr 192.168.223.251 -> Sep 04 08:54:20 volumio ntpd[994]: 129.250.35.250 local addr 192.168.223.251 -> Sep 04 08:54:20 volumio ntpd[994]: 133.130.121.141 local addr 192.168.223.251 -> Sep 04 08:54:20 volumio ntpd[994]: 129.250.35.251 local addr 192.168.223.251 -> Sep 04 08:54:20 volumio ntpd[994]: 162.159.200.1 local addr 192.168.223.251 -> Sep 04 08:54:20 volumio ntpd[994]: 203.135.216.218 local addr 192.168.223.251 -> Sep 04 08:54:20 volumio ntpd[994]: 162.159.200.123 local addr 192.168.223.251 -> Sep 04 08:54:22 volumio dhcpcd[814]: eth0: carrier acquired Sep 04 08:54:22 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off Sep 04 08:54:22 volumio dhcpcd[814]: eth0: IAID 9f:4f:71:d1 Sep 04 08:54:22 volumio dhcpcd[814]: eth0: probing address 192.168.223.251/24 Sep 04 08:54:23 volumio dhcpcd[814]: eth0: soliciting an IPv6 router Sep 04 08:54:23 volumio ifplugd(eth0)[986]: Link beat detected. Sep 04 08:54:24 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Sep 04 08:54:24 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:54:25 volumio volumio[1313]: info: Volumio Network Manager: Network status updated: 0 Sep 04 08:54:27 volumio dhcpcd[814]: eth0: using static address 192.168.223.251/24 Sep 04 08:54:27 volumio avahi-daemon[731]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.223.251. Sep 04 08:54:27 volumio dhcpcd[814]: eth0: adding route to 192.168.223.0/24 Sep 04 08:54:27 volumio avahi-daemon[731]: New relevant interface eth0.IPv4 for mDNS. Sep 04 08:54:27 volumio dhcpcd[814]: eth0: adding default route via 192.168.223.1 Sep 04 08:54:27 volumio avahi-daemon[731]: Registering new address record for 192.168.223.251 on eth0.IPv4. Sep 04 08:54:28 volumio volumio[1313]: info: Discovery: adding 55e405f5-a25e-4eee-b880-50dec7a9cf3e Sep 04 08:54:28 volumio volumio[1313]: info: Discovery: Found device Volumio Sep 04 08:54:28 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Sep 04 08:54:28 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Sep 04 08:54:28 volumio volumio[1313]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 08:54:28 volumio volumio[1313]: Error: The value NaN is not a number Sep 04 08:54:28 volumio volumio[1313]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Sep 04 08:54:28 volumio volumio[1313]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Sep 04 08:54:28 volumio volumio[1313]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Sep 04 08:54:28 volumio volumio[1313]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Sep 04 08:54:28 volumio volumio[1313]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Sep 04 08:54:28 volumio volumio[1313]: at Browser.emit (events.js:400:28) Sep 04 08:54:28 volumio volumio[1313]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Sep 04 08:54:28 volumio volumio[1313]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Sep 04 08:54:28 volumio volumio[1313]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Sep 04 08:54:28 volumio volumio[1313]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Sep 04 08:54:28 volumio volumio[1313]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Sep 04 08:54:28 volumio volumio[1313]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Sep 04 08:54:28 volumio volumio[1313]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Sep 04 08:54:28 volumio volumio[1313]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 08:54:28 volumio ntpd[994]: Listen normally on 5 eth0 192.168.223.251:123 Sep 04 08:54:28 volumio ntpd[994]: new interface(s) found: waking up resolver Sep 04 08:54:29 volumio sudo[2162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-04 08:53 Sep 04 08:54:29 volumio sudo[2162]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7044262daed40f016564d0bf019f582e0bb49d71" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 16 Aug 2025 11:55:35 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="nanopineo-armbian" VOLUMIO_DEVICENAME="NanoPi Neo" VOLUMIO_HASH="58507b464d7a6cb68ea58c3b8b4ba474"