-- Logs begin at Thu 2024-10-24 19:10:59 KST, end at Fri 2024-10-25 18:38:58 KST. -- Oct 25 18:37:29 volumio ntpd[2777]: receive: Unexpected origin timestamp 0xeac49bc8.9ae15ba8 does not match aorg 0000000000.00000000 from server@175.193.3.234 xmt 0xeac5e559.7e97450a Oct 25 18:37:29 volumio ntpd[2777]: receive: Unexpected origin timestamp 0xeac49bc8.9adff8a9 does not match aorg 0000000000.00000000 from server@3.39.176.65 xmt 0xeac5e559.7f00208c Oct 25 18:37:29 volumio volumio[2500]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 25 18:37:29 volumio volumio[2500]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 25 18:37:29 volumio ntpd[2777]: receive: Unexpected origin timestamp 0xeac49bc8.9ade5737 does not match aorg 0000000000.00000000 from server@121.174.142.82 xmt 0xeac5e559.7f6200f9 Oct 25 18:37:29 volumio systemd[1]: Starting Daily apt download activities... Oct 25 18:37:29 volumio volumio[2500]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 25 18:37:30 volumio systemd[1]: apt-daily.service: Succeeded. Oct 25 18:37:30 volumio systemd[1]: Started Daily apt download activities. Oct 25 18:37:30 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Oct 25 18:37:30 volumio volumio[2500]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 25 18:37:30 volumio volumio[2500]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 25 18:37:30 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 18:37:30 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 18:37:30 volumio volumio[2500]: info: Starting MyVolumio Remote Streaming Endpoints Oct 25 18:37:30 volumio volumio[2500]: info: MyVolumio login type: Token Oct 25 18:37:30 volumio volumio[2500]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 25 18:37:30 volumio volumio[2500]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 25 18:37:30 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Oct 25 18:37:30 volumio systemd[1]: Started Daily apt upgrade and clean activities. Oct 25 18:37:31 volumio volumio[2500]: info: Starting Streaming Service Transparent Proxy Oct 25 18:37:31 volumio volumio[2500]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 25 18:37:31 volumio volumio[2500]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 25 18:37:31 volumio volumio[2500]: info: Streaming services startup Oct 25 18:37:31 volumio volumio[2500]: info: Starting Streaming Daemon Oct 25 18:37:31 volumio sudo[2964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 25 18:37:31 volumio sudo[2964]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:37:31 volumio sudo[2964]: pam_unix(sudo:session): session closed for user root Oct 25 18:37:31 volumio volumio[2500]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 25 18:37:31 volumio volumio[2500]: error: Cannot start Volumio Streaming Daemon Oct 25 18:37:31 volumio volumio[2500]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 25 18:37:31 volumio volumio[2500]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 25 18:37:31 volumio volumio[2500]: info: Volumio called home Oct 25 18:37:32 volumio volumio[2500]: error: MyVolumio Custom Token format not valid, refreshing it Oct 25 18:37:32 volumio volumio[2500]: STREAMING PROXY: Starting server on port 3245 Oct 25 18:37:32 volumio volumio[2500]: Node JS runtime: 14 Oct 25 18:37:33 volumio volumio[2500]: info: MyVolumio login type: Token Oct 25 18:37:33 volumio volumio[2500]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 25 18:37:34 volumio volumio[2500]: info: MyVolumio token set successfully Oct 25 18:37:34 volumio volumio[2500]: info: MYVOLUMIO: Adding device Oct 25 18:37:34 volumio volumio[2500]: info: MYVOLUMIO: Evaluating Server Oct 25 18:37:34 volumio volumio[2500]: info: MyVolumio status changed Oct 25 18:37:34 volumio volumio[2500]: info: Streaming services startup Oct 25 18:37:34 volumio volumio[2500]: info: Starting Streaming Daemon Oct 25 18:37:34 volumio volumio[2500]: info: Removing browser output: myVolumio user plan is not superstar Oct 25 18:37:34 volumio volumio[2500]: info: Removing audio output: Oct 25 18:37:34 volumio volumio[2500]: info: Stoppping Tunnel 1 Oct 25 18:37:34 volumio sudo[2989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 25 18:37:34 volumio sudo[2989]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:37:34 volumio sudo[2992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 25 18:37:34 volumio sudo[2989]: pam_unix(sudo:session): session closed for user root Oct 25 18:37:34 volumio sudo[2992]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:37:35 volumio volumio[2500]: error: Cannot start Volumio Streaming Daemon Oct 25 18:37:35 volumio volumio[2500]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 25 18:37:35 volumio volumio[2500]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 25 18:37:35 volumio sudo[2992]: pam_unix(sudo:session): session closed for user root Oct 25 18:37:35 volumio volumio[2500]: info: Remote SSH Stopped Oct 25 18:37:35 volumio volumio[2500]: info: Discovery: A device disappeared from network Oct 25 18:37:35 volumio volumio[2500]: info: Discovery: Device volumio disappeared from network Oct 25 18:37:35 volumio volumio[2500]: info: Discovery: adding 75c464f6-1278-4bd0-b48b-6fd15df63116 Oct 25 18:37:35 volumio volumio[2500]: info: Discovery: Found device Volumio Oct 25 18:37:35 volumio volumio[2500]: info: CoreCommandRouter::volumioGetState Oct 25 18:37:35 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Oct 25 18:37:35 volumio volumio[2838]: ..........................................................................................................................................................................++++ Oct 25 18:37:35 volumio volumio[2838]: e is 65537 (0x010001) Oct 25 18:37:35 volumio volumio[2838]: writing RSA key Oct 25 18:37:35 volumio volumio[2500]: info: Setting Geolocation for MyVolumio to as1 Oct 25 18:37:35 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 18:37:35 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 18:37:35 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 18:37:36 volumio volumio[2500]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 25 18:37:36 volumio volumio[2500]: info: Updating MyVolumio device info Oct 25 18:37:36 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 18:37:37 volumio volumio[2500]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 25 18:37:39 volumio systemd[1]: systemd-fsckd.service: Succeeded. Oct 25 18:37:40 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Oct 25 18:37:40 volumio volumio[2500]: info: MYVOLUMIO: Adding device Oct 25 18:37:40 volumio volumio[2500]: info: MYVOLUMIO: Evaluating Server Oct 25 18:37:41 volumio volumio[2500]: info: Setting Geolocation for MyVolumio to as1 Oct 25 18:37:41 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 18:37:41 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 18:37:41 volumio volumio[2500]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 25 18:37:42 volumio volumio[2500]: info: Updating MyVolumio device info Oct 25 18:37:42 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 18:37:42 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 18:37:42 volumio volumio[2500]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 25 18:37:45 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 25 18:37:45 volumio volumio[2500]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 25 18:37:45 volumio volumio[2500]: info: CoreCommandRouter::volumioGetState Oct 25 18:37:45 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Oct 25 18:37:48 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 25 18:37:48 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 18:37:48 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 25 18:37:48 volumio volumio-remote-updater[2320]: No test mode Oct 25 18:37:48 volumio volumio-remote-updater[2320]: No alpha test mode Oct 25 18:37:48 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 25 18:37:49 volumio volumio[2500]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 25 18:37:49 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 25 18:37:50 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 18:37:50 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 18:37:50 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 25 18:37:50 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 25 18:37:50 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 25 18:37:50 volumio volumio[2500]: info: CoreCommandRouter::volumioGetBrowseSources Oct 25 18:37:50 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 25 18:37:51 volumio volumio[2500]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 25 18:37:51 volumio volumio[2500]: info: Completed starting MyVolumio Plugin Oct 25 18:37:51 volumio volumio[2500]: info: BOOT COMPLETED Oct 25 18:37:51 volumio volumio[2500]: [Metrics] CommandRouter: 38s 377.44ms Oct 25 18:37:51 volumio volumio[2500]: info: CoreCommandRouter::volumiosetStartupVolume Oct 25 18:37:51 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 18:37:51 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 18:37:51 volumio volumio[2500]: info: CoreCommandRouter::Close All Modals sent Oct 25 18:37:51 volumio volumio[2500]: info: CoreCommandRouter::Close All Modals sent Oct 25 18:37:52 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 25 18:37:52 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 18:37:52 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 25 18:38:00 volumio kernel: fb: mem_free_work, free memory: addr:800000 Oct 25 18:38:08 volumio sudo[3053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 25 18:38:08 volumio sudo[3053]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:38:08 volumio sudo[3053]: pam_unix(sudo:session): session closed for user root Oct 25 18:38:08 volumio sudo[3055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 25 18:38:08 volumio sudo[3055]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:38:08 volumio sudo[3055]: pam_unix(sudo:session): session closed for user root Oct 25 18:38:08 volumio volumio[2500]: verbose: New Socket.io Connection to 192.168.0.13 from 192.168.0.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::volumioGetVisibleSources Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::volumioGetState Oct 25 18:38:09 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 25 18:38:09 volumio volumio[2500]: info: Received Get System Info Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 18:38:09 volumio volumio[2500]: info: Discovery: Getting this device information Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::volumioGetState Oct 25 18:38:09 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::volumioGetState Oct 25 18:38:09 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Oct 25 18:38:09 volumio volumio[2500]: info: Listing playlists Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 25 18:38:09 volumio sudo[3061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 25 18:38:09 volumio sudo[3061]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:38:09 volumio sudo[3061]: pam_unix(sudo:session): session closed for user root Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 25 18:38:09 volumio sudo[3063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 25 18:38:09 volumio sudo[3063]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:38:09 volumio sudo[3063]: pam_unix(sudo:session): session closed for user root Oct 25 18:38:09 volumio volumio[2500]: verbose: New Socket.io Connection to 192.168.0.13 from 192.168.0.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::volumioGetVisibleSources Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::volumioGetState Oct 25 18:38:09 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 25 18:38:09 volumio volumio[2500]: info: Received Get System Info Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 18:38:09 volumio volumio[2500]: info: Discovery: Getting this device information Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::volumioGetState Oct 25 18:38:09 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::volumioGetState Oct 25 18:38:09 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Oct 25 18:38:09 volumio volumio[2500]: info: Listing playlists Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 25 18:38:09 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 25 18:38:10 volumio volumio[2500]: info: CoreCommandRouter::volumioGetState Oct 25 18:38:10 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Oct 25 18:38:11 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 25 18:38:11 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 25 18:38:11 volumio volumio[2500]: info: Received Get System Info Oct 25 18:38:11 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 18:38:11 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 18:38:11 volumio volumio[2500]: info: Discovery: Getting this device information Oct 25 18:38:11 volumio volumio[2500]: info: CoreCommandRouter::volumioGetState Oct 25 18:38:11 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Oct 25 18:38:11 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 18:38:11 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 25 18:38:15 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 25 18:38:15 volumio volumio[2500]: info: Received Get System Info Oct 25 18:38:15 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 18:38:15 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 18:38:15 volumio volumio[2500]: info: Discovery: Getting this device information Oct 25 18:38:15 volumio volumio[2500]: info: CoreCommandRouter::volumioGetState Oct 25 18:38:15 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Oct 25 18:38:15 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 18:38:18 volumio volumio[2500]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 18:38:18 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 25 18:38:18 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 18:38:18 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 18:38:18 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 18:38:18 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 18:38:18 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 18:38:18 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 18:38:18 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 18:38:18 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 18:38:18 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 18:38:18 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 25 18:38:18 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 25 18:38:19 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 25 18:38:28 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 25 18:38:28 volumio volumio[2500]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 25 18:38:28 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 25 18:38:28 volumio volumio[2500]: info: Received Get System Version Oct 25 18:38:28 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 25 18:38:28 volumio volumio[2500]: info: Received Get System Info Oct 25 18:38:28 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 18:38:28 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 18:38:28 volumio volumio[2500]: info: Discovery: Getting this device information Oct 25 18:38:28 volumio volumio[2500]: info: CoreCommandRouter::volumioGetState Oct 25 18:38:28 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Oct 25 18:38:28 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 18:38:40 volumio volumio[2500]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Oct 25 18:38:40 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Oct 25 18:38:40 volumio volumio[2500]: info: CoreCommandRouter::volumioGetState Oct 25 18:38:40 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Oct 25 18:38:40 volumio volumio[2500]: info: Restoring Previous Volume level: false false Oct 25 18:38:40 volumio volumio[2500]: info: Volume configurations have been set Oct 25 18:38:40 volumio volumio[2500]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 25 18:38:40 volumio volumio[2500]: info: Updating Volume Controller Parameters: Device: 1 Name: E30 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 25 18:38:40 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 25 18:38:40 volumio volumio[2500]: info: Disabling external Volume Control Oct 25 18:38:40 volumio volumio[2500]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 18:38:40 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 25 18:38:40 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 18:38:40 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 18:38:40 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 18:38:40 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 18:38:40 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 18:38:40 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 18:38:40 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 18:38:40 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 18:38:40 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 18:38:40 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 25 18:38:40 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 25 18:38:40 volumio volumio[2500]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Oct 25 18:38:40 volumio volumio[2500]: info: VolumeController:: Volume=undefined Mute =false Oct 25 18:38:40 volumio volumio[2500]: info: CoreStateMachine::pushState Oct 25 18:38:40 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Oct 25 18:38:40 volumio volumio[2500]: info: CoreCommandRouter::volumioPushState Oct 25 18:38:43 volumio volumio[2500]: info: CoreCommandRouter::volumioGetState Oct 25 18:38:43 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Oct 25 18:38:44 volumio volumio[2500]: info: VolumeController::SetAlsaVolumeNaN Oct 25 18:38:44 volumio volumio[2500]: info: CoreStateMachine::pushState Oct 25 18:38:44 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Oct 25 18:38:44 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 18:38:44 volumio volumio[2500]: info: CoreCommandRouter::volumioPushState Oct 25 18:38:44 volumio volumio[2500]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Oct 25 18:38:46 volumio volumio[2500]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 18:38:46 volumio volumio[2500]: /bin/cat: /sys/class/net/wlan0/flags: No such file or directory Oct 25 18:38:46 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 25 18:38:46 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 25 18:38:46 volumio sudo[3124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 25 18:38:46 volumio sudo[3124]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:38:46 volumio sudo[3129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 25 18:38:46 volumio sudo[3124]: pam_unix(sudo:session): session closed for user root Oct 25 18:38:46 volumio sudo[3129]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:38:46 volumio sudo[3136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 25 18:38:46 volumio sudo[3136]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:38:46 volumio sudo[3129]: pam_unix(sudo:session): session closed for user root Oct 25 18:38:46 volumio sudo[3144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 25 18:38:46 volumio sudo[3144]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:38:46 volumio sudo[3154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 25 18:38:46 volumio sudo[3154]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:38:46 volumio sudo[3154]: pam_unix(sudo:session): session closed for user root Oct 25 18:38:46 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 25 18:38:46 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 25 18:38:46 volumio sudo[3136]: pam_unix(sudo:session): session closed for user root Oct 25 18:38:46 volumio sudo[3144]: pam_unix(sudo:session): session closed for user root Oct 25 18:38:46 volumio sudo[3161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 25 18:38:46 volumio sudo[3161]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:38:46 volumio sudo[3161]: pam_unix(sudo:session): session closed for user root Oct 25 18:38:46 volumio sudo[3172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 25 18:38:46 volumio sudo[3172]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:38:46 volumio sudo[3172]: pam_unix(sudo:session): session closed for user root Oct 25 18:38:46 volumio volumio[2500]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Oct 25 18:38:46 volumio volumio[2500]: wlan0 Interface doesn't support scanning. Oct 25 18:38:46 volumio volumio[2500]: info: Cannot use regular scanning, forcing with ap-force Oct 25 18:38:46 volumio sudo[3177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Oct 25 18:38:46 volumio sudo[3177]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:38:46 volumio sudo[3177]: pam_unix(sudo:session): session closed for user root Oct 25 18:38:46 volumio volumio[2500]: command failed: No such device (-19) Oct 25 18:38:46 volumio volumio[2500]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Oct 25 18:38:46 volumio volumio[2500]: command failed: No such device (-19) Oct 25 18:38:52 volumio volumio[2500]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Oct 25 18:38:52 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Oct 25 18:38:52 volumio sudo[3182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Oct 25 18:38:52 volumio sudo[3182]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:38:52 volumio sudo[3182]: pam_unix(sudo:session): session closed for user root Oct 25 18:38:52 volumio sudo[3184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Oct 25 18:38:52 volumio sudo[3184]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:38:52 volumio sudo[3184]: pam_unix(sudo:session): session closed for user root Oct 25 18:38:52 volumio volumio[2500]: info: Permissions for /etc/network/interfaces set Oct 25 18:38:52 volumio volumio[2500]: info: Restarting Wireless Network Oct 25 18:38:52 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Oct 25 18:38:52 volumio volumio[2500]: info: Discovery: Restarting Advertising due to device name change Oct 25 18:38:52 volumio sudo[3187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Oct 25 18:38:52 volumio sudo[3187]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:38:52 volumio systemd[1]: Starting Wireless Services... Oct 25 18:38:52 volumio wireless.js[3189]: WIRELESS: No wireless interface, exiting Oct 25 18:38:52 volumio iw[3206]: command failed: No such device (-19) Oct 25 18:38:52 volumio iwconfig[3209]: Error for wireless request "Set Power Management" (8B2C) : Oct 25 18:38:52 volumio iwconfig[3209]: SET failed on device wlan0 ; No such device. Oct 25 18:38:52 volumio systemd[1]: wireless.service: Succeeded. Oct 25 18:38:52 volumio systemd[1]: Started Wireless Services. Oct 25 18:38:52 volumio sudo[3187]: pam_unix(sudo:session): session closed for user root Oct 25 18:38:53 volumio volumio[2500]: info: Discovery: A device disappeared from network Oct 25 18:38:53 volumio volumio[2500]: info: Discovery: Device volumio disappeared from network Oct 25 18:38:57 volumio volumio[2500]: info: Discovery: Started advertising with name: Volumio Oct 25 18:38:57 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Oct 25 18:38:57 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Oct 25 18:38:57 volumio sudo[3214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 25 18:38:57 volumio sudo[3214]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:38:57 volumio sudo[3214]: pam_unix(sudo:session): session closed for user root Oct 25 18:38:58 volumio volumio[2500]: info: Discovery: adding 75c464f6-1278-4bd0-b48b-6fd15df63116 Oct 25 18:38:58 volumio volumio[2500]: info: Discovery: Found device Volumio Oct 25 18:38:58 volumio volumio[2500]: info: CoreCommandRouter::volumioGetState Oct 25 18:38:58 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Oct 25 18:38:58 volumio volumio[2500]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 18:38:58 volumio volumio[2500]: Error: The value NaN is not a number Oct 25 18:38:58 volumio volumio[2500]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Oct 25 18:38:58 volumio volumio[2500]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Oct 25 18:38:58 volumio volumio[2500]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Oct 25 18:38:58 volumio volumio[2500]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Oct 25 18:38:58 volumio volumio[2500]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Oct 25 18:38:58 volumio volumio[2500]: at Browser.emit (events.js:400:28) Oct 25 18:38:58 volumio volumio[2500]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Oct 25 18:38:58 volumio volumio[2500]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Oct 25 18:38:58 volumio volumio[2500]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Oct 25 18:38:58 volumio volumio[2500]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Oct 25 18:38:58 volumio volumio[2500]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Oct 25 18:38:58 volumio volumio[2500]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Oct 25 18:38:58 volumio volumio[2500]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Oct 25 18:38:58 volumio volumio[2500]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 18:38:58 volumio sudo[3228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-25 18:37 Oct 25 18:38:58 volumio sudo[3228]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Sep 2024 11:32:28 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="odroidc4" VOLUMIO_DEVICENAME="Odroid-C4" VOLUMIO_HASH="bcf63729a67a1fb0048ee35bec188024"