-- Logs begin at Tue 2024-08-27 22:36:52 CST, end at Tue 2024-08-27 22:50:13 CST. -- Aug 27 22:49:02 volumio sudo[4093]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:04 volumio sudo[4155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 22:49:04 volumio sudo[4155]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:04 volumio sudo[4155]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:04 volumio sudo[4153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 22:49:04 volumio sudo[4153]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:04 volumio sudo[4153]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:04 volumio volumio[1036]: verbose: New Socket.io Connection to 192.168.0.109 from 192.168.0.104 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 15 Aug 27 22:49:04 volumio volumio[1036]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 22:49:04 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:49:04 volumio volumio[1036]: info: Received Get System Info Aug 27 22:49:04 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 22:49:04 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 22:49:04 volumio volumio[1036]: info: Discovery: Getting this device information Aug 27 22:49:04 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:49:04 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:04 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 22:49:09 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Aug 27 22:49:09 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 22:49:09 volumio volumio[1036]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Aug 27 22:49:09 volumio volumio[1036]: info: Retrying Login Due to Network Error Aug 27 22:49:14 volumio volumio[1036]: info: Received Get System Info Aug 27 22:49:14 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 22:49:14 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 22:49:14 volumio volumio[1036]: info: Discovery: Getting this device information Aug 27 22:49:14 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:49:14 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:14 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 22:49:15 volumio sudo[4183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 22:49:15 volumio sudo[4183]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:16 volumio sudo[4183]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:16 volumio sudo[4185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 22:49:16 volumio sudo[4185]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:16 volumio sudo[4185]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:16 volumio volumio[1036]: verbose: New Socket.io Connection to 192.168.0.109 from 192.168.0.104 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 11 Aug 27 22:49:16 volumio volumio[1036]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 22:49:16 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:49:16 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Aug 27 22:49:16 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Aug 27 22:49:16 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Aug 27 22:49:16 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Aug 27 22:49:16 volumio volumio[1036]: info: Received Get System Info Aug 27 22:49:16 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 22:49:16 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 22:49:16 volumio volumio[1036]: info: Discovery: Getting this device information Aug 27 22:49:16 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:49:16 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:16 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 22:49:16 volumio volumio-remote-updater[694]: No test mode Aug 27 22:49:16 volumio volumio-remote-updater[694]: No alpha test mode Aug 27 22:49:16 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 22:49:16 volumio volumio[1036]: info: Retrieving Cloud Streaming UI Aug 27 22:49:16 volumio volumio[1036]: info: Getting Tidal Cloud Configuration Aug 27 22:49:16 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 22:49:16 volumio volumio[1036]: info: Getting Qobuz Cloud Configuration Aug 27 22:49:16 volumio volumio[1036]: info: Asking plugin for UI Config Aug 27 22:49:16 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 22:49:16 volumio volumio[1036]: info: Getting Spotify Cloud Configuration Aug 27 22:49:16 volumio volumio[1036]: info: Asking plugin for UI Config Aug 27 22:49:16 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 22:49:16 volumio volumio[1036]: info: Saving Spotify Acccount Aug 27 22:49:16 volumio volumio[1036]: info: Got Tidal Cloud Configuration Aug 27 22:49:16 volumio volumio[1036]: info: Got it Aug 27 22:49:16 volumio volumio[1036]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 27 22:49:16 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 22:49:16 volumio volumio[1036]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 27 22:49:17 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Aug 27 22:49:17 volumio volumio[1036]: info: Loading i18n strings for locale zh Aug 27 22:49:17 volumio volumio[1036]: Updating browse sources language Aug 27 22:49:17 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:49:17 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Aug 27 22:49:17 volumio volumio[1036]: info: Setting timezone to Asia/Shanghai Aug 27 22:49:17 volumio sudo[4201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Aug 27 22:49:17 volumio sudo[4201]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:17 volumio sudo[4201]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:17 volumio sudo[4204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime Aug 27 22:49:17 volumio sudo[4204]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:17 volumio sudo[4204]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:17 volumio systemd[1]: Starting Daily apt download activities... Aug 27 22:49:17 volumio sudo[4208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Aug 27 22:49:17 volumio sudo[4208]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:17 volumio sudo[4208]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:17 volumio sudo[4211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai Aug 27 22:49:17 volumio sudo[4211]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:17 volumio sudo[4211]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:18 volumio volumio[1036]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 27 22:49:18 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 27 22:49:18 volumio volumio[1036]: info: Fetching Streaming Services browse cache Aug 27 22:49:19 volumio volumio[1036]: info: MyVolumio login type: Token Aug 27 22:49:21 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Aug 27 22:49:21 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 22:49:21 volumio systemd[1]: apt-daily.service: Succeeded. Aug 27 22:49:21 volumio systemd[1]: Started Daily apt download activities. Aug 27 22:49:22 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Aug 27 22:49:22 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Aug 27 22:49:22 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 27 22:49:22 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:49:22 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 22:49:28 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Aug 27 22:49:28 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 22:49:37 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 27 22:49:37 volumio volumio[1036]: info: Preparing to save Alsa Options, stopping services first Aug 27 22:49:37 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:49:37 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:37 volumio volumio[1036]: info: CoreCommandRouter::volumioPause Aug 27 22:49:37 volumio volumio[1036]: info: CoreStateMachine::pause Aug 27 22:49:37 volumio volumio[1036]: info: CoreStateMachine::stPlaybackTimer Aug 27 22:49:37 volumio volumio[1036]: info: CoreStateMachine::servicePause Aug 27 22:49:37 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:37 volumio volumio[1036]: info: CoreCommandRouter::servicePause Aug 27 22:49:37 volumio volumio[1036]: info: ControllerMpd::pause Aug 27 22:49:37 volumio volumio[1036]: verbose: ControllerMpd::sendMpdCommand pause Aug 27 22:49:37 volumio volumio[1036]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"1,0","label":"Analog Out"},"disallowPush":true} Aug 27 22:49:37 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 22:49:37 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 22:49:37 volumio volumio[1036]: info: Found match in Cards Database: setting mixer for card Analog Out Aug 27 22:49:37 volumio volumio[1036]: info: Setting mixer Master for card Analog Out Aug 27 22:49:37 volumio volumio[1036]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 27 22:49:37 volumio volumio[1036]: info: Updating Volume Controller Parameters: Device: 1,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 27 22:49:37 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 27 22:49:37 volumio volumio[1036]: info: Disabling external Volume Control Aug 27 22:49:37 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 22:49:37 volumio volumio[1036]: info: Preparing to generate the ALSA configuration file Aug 27 22:49:37 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 22:49:37 volumio volumio[1036]: info: Retrieving Cloud Streaming UI Aug 27 22:49:37 volumio volumio[1036]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Aug 27 22:49:37 volumio volumio[1036]: info: Reading ALSA contributions from plugins. Aug 27 22:49:37 volumio volumio[1036]: info: Aug 27 22:49:37 volumio volumio[1036]: ---------------------------- MPD announces state update: player Aug 27 22:49:37 volumio volumio[1036]: info: sendMpdCommand pause took 444 milliseconds Aug 27 22:49:37 volumio volumio[1036]: info: ControllerMpd::getState Aug 27 22:49:37 volumio volumio[1036]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:49:37 volumio volumio[1036]: info: Aug 27 22:49:37 volumio volumio[1036]: ---------------------------- MPD announces state update: player Aug 27 22:49:37 volumio volumio[1036]: info: ControllerMpd::getState Aug 27 22:49:37 volumio volumio[1036]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:49:37 volumio volumio[1036]: info: Aug 27 22:49:37 volumio volumio[1036]: ---------------------------- MPD announces state update: player Aug 27 22:49:37 volumio volumio[1036]: info: ControllerMpd::getState Aug 27 22:49:37 volumio volumio[1036]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:49:37 volumio volumio[1036]: info: Aug 27 22:49:37 volumio volumio[1036]: ---------------------------- MPD announces state update: player Aug 27 22:49:38 volumio volumio[1036]: info: ControllerMpd::getState Aug 27 22:49:38 volumio volumio[1036]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:49:38 volumio volumio[1036]: info: sendMpdCommand status took 169 milliseconds Aug 27 22:49:38 volumio volumio[1036]: info: sendMpdCommand status took 175 milliseconds Aug 27 22:49:38 volumio volumio[1036]: info: sendMpdCommand status took 162 milliseconds Aug 27 22:49:38 volumio volumio[1036]: info: sendMpdCommand status took 141 milliseconds Aug 27 22:49:38 volumio volumio[1036]: verbose: ControllerMpd::parseState Aug 27 22:49:38 volumio volumio[1036]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 22:49:38 volumio volumio[1036]: verbose: ControllerMpd::parseState Aug 27 22:49:38 volumio volumio[1036]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 22:49:38 volumio volumio[1036]: verbose: ControllerMpd::parseState Aug 27 22:49:38 volumio volumio[1036]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 22:49:38 volumio volumio[1036]: verbose: ControllerMpd::parseState Aug 27 22:49:38 volumio volumio[1036]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 22:49:38 volumio volumio[1036]: info: sendMpdCommand playlistinfo took 55 milliseconds Aug 27 22:49:38 volumio volumio[1036]: info: sendMpdCommand playlistinfo took 39 milliseconds Aug 27 22:49:38 volumio volumio[1036]: info: sendMpdCommand playlistinfo took 53 milliseconds Aug 27 22:49:38 volumio volumio[1036]: info: sendMpdCommand playlistinfo took 61 milliseconds Aug 27 22:49:38 volumio volumio[1036]: verbose: ControllerMpd::parseTrackInfo Aug 27 22:49:38 volumio volumio[1036]: info: ControllerMpd::pushState Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::servicePushState Aug 27 22:49:38 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:38 volumio volumio[1036]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":216736,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"839 Kbps","isStreaming":false,"title":"Reality","artist":"Richard Sanderson","album":"Reality","uri":"USB/Richard Sanderson - Reality.flac","trackType":"flac"} Aug 27 22:49:38 volumio volumio[1036]: verbose: CURRENT POSITION 2 Aug 27 22:49:38 volumio volumio[1036]: info: CoreStateMachine::syncState stateService pause Aug 27 22:49:38 volumio volumio[1036]: info: CoreStateMachine::syncState currentStatus pause Aug 27 22:49:38 volumio volumio[1036]: info: CoreStateMachine::pushState Aug 27 22:49:38 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::volumioPushState Aug 27 22:49:38 volumio volumio[1036]: info: CoreStateMachine::stPlaybackTimer Aug 27 22:49:38 volumio volumio[1036]: info: ControllerMpd::pushState Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::servicePushState Aug 27 22:49:38 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:38 volumio volumio[1036]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":216736,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"839 Kbps","isStreaming":false,"title":"Reality","artist":"Richard Sanderson","album":"Reality","uri":"USB/Richard Sanderson - Reality.flac","trackType":"flac"} Aug 27 22:49:38 volumio volumio[1036]: verbose: CURRENT POSITION 2 Aug 27 22:49:38 volumio volumio[1036]: info: CoreStateMachine::syncState stateService pause Aug 27 22:49:38 volumio volumio[1036]: info: CoreStateMachine::syncState currentStatus pause Aug 27 22:49:38 volumio volumio[1036]: info: CoreStateMachine::pushState Aug 27 22:49:38 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::volumioPushState Aug 27 22:49:38 volumio volumio[1036]: info: CoreStateMachine::stPlaybackTimer Aug 27 22:49:38 volumio volumio[1036]: info: ControllerMpd::pushState Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::servicePushState Aug 27 22:49:38 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:38 volumio volumio[1036]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":216736,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"839 Kbps","isStreaming":false,"title":"Reality","artist":"Richard Sanderson","album":"Reality","uri":"USB/Richard Sanderson - Reality.flac","trackType":"flac"} Aug 27 22:49:38 volumio volumio[1036]: verbose: CURRENT POSITION 2 Aug 27 22:49:38 volumio volumio[1036]: info: CoreStateMachine::syncState stateService pause Aug 27 22:49:38 volumio volumio[1036]: info: CoreStateMachine::syncState currentStatus pause Aug 27 22:49:38 volumio volumio[1036]: info: CoreStateMachine::pushState Aug 27 22:49:38 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::volumioPushState Aug 27 22:49:38 volumio volumio[1036]: info: CoreStateMachine::stPlaybackTimer Aug 27 22:49:38 volumio volumio[1036]: info: ControllerMpd::pushState Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::servicePushState Aug 27 22:49:38 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:38 volumio volumio[1036]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":216736,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"839 Kbps","isStreaming":false,"title":"Reality","artist":"Richard Sanderson","album":"Reality","uri":"USB/Richard Sanderson - Reality.flac","trackType":"flac"} Aug 27 22:49:38 volumio volumio[1036]: verbose: CURRENT POSITION 2 Aug 27 22:49:38 volumio volumio[1036]: info: CoreStateMachine::syncState stateService pause Aug 27 22:49:38 volumio volumio[1036]: info: CoreStateMachine::syncState currentStatus pause Aug 27 22:49:38 volumio volumio[1036]: info: CoreStateMachine::pushState Aug 27 22:49:38 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::volumioPushState Aug 27 22:49:38 volumio volumio[1036]: info: CoreStateMachine::stPlaybackTimer Aug 27 22:49:38 volumio volumio[1036]: info: ------------------------------ 660ms Aug 27 22:49:38 volumio volumio[1036]: info: ------------------------------ 650ms Aug 27 22:49:38 volumio volumio[1036]: info: ------------------------------ 634ms Aug 27 22:49:38 volumio volumio[1036]: info: ------------------------------ 633ms Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 27 22:49:38 volumio volumio[1036]: info: Getting Tidal Cloud Configuration Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 22:49:38 volumio volumio[1036]: info: Getting Qobuz Cloud Configuration Aug 27 22:49:38 volumio volumio[1036]: info: Asking plugin for UI Config Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 22:49:38 volumio volumio[1036]: info: Getting Spotify Cloud Configuration Aug 27 22:49:38 volumio volumio[1036]: info: Asking plugin for UI Config Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 22:49:38 volumio volumio[1036]: info: Saving Spotify Acccount Aug 27 22:49:38 volumio volumio[1036]: info: Got Tidal Cloud Configuration Aug 27 22:49:38 volumio volumio[1036]: info: Got it Aug 27 22:49:38 volumio volumio[1036]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 27 22:49:38 volumio volumio[1036]: info: VolumeController:: Volume=41 Mute =false Aug 27 22:49:38 volumio volumio[1036]: info: CoreStateMachine::pushState Aug 27 22:49:38 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::volumioPushState Aug 27 22:49:38 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 22:49:38 volumio volumio[1036]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 27 22:49:38 volumio volumio[1036]: info: Asound.conf file written Aug 27 22:49:38 volumio screensaver.sh[684]: pygame 1.9.4.post1 Aug 27 22:49:38 volumio screensaver.sh[684]: Hello from the pygame community. https://www.pygame.org/contribute.html Aug 27 22:49:39 volumio sudo[4342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 27 22:49:39 volumio sudo[4342]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:39 volumio sudo[4342]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:39 volumio volumio[1036]: info: Output device has changed, restarting MPD Aug 27 22:49:39 volumio sudo[2109]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:39 volumio sudo[4347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 22:49:39 volumio sudo[4347]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:39 volumio volumio[1036]: info: Output device has changed, restarting Shairport Sync Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:49:39 volumio sudo[4347]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:39 volumio sudo[4352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 22:49:39 volumio sudo[4352]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:39 volumio systemd[1]: Stopping Music Player Daemon... Aug 27 22:49:39 volumio volumio[1036]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 22:49:39 volumio volumio[1036]: info: Output device has changed, restarting MPD Aug 27 22:49:39 volumio systemd[1]: mpd.service: Succeeded. Aug 27 22:49:39 volumio systemd[1]: Stopped Music Player Daemon. Aug 27 22:49:39 volumio volumio[1036]: info: Output device has changed, restarting Shairport Sync Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:49:39 volumio systemd[1]: Starting Music Player Daemon... Aug 27 22:49:39 volumio sudo[4358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 22:49:39 volumio sudo[4358]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:39 volumio sudo[4358]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:39 volumio sudo[4361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 22:49:39 volumio sudo[4361]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:39 volumio volumio[1036]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Aug 27 22:49:39 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 27 22:49:39 volumio systemd[1]: mpd.service: Succeeded. Aug 27 22:49:39 volumio systemd[1]: Stopped Music Player Daemon. Aug 27 22:49:39 volumio systemd[1]: Starting Music Player Daemon... Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Aug 27 22:49:39 volumio volumio[1036]: info: MPD Permissions set Aug 27 22:49:39 volumio volumio[1036]: info: MPD Permissions set Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:49:39 volumio sudo[4368]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 22:49:39 volumio sudo[4368]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:39 volumio sudo[4368]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:49:39 volumio volumio[1036]: info: Starting Shairport Sync Aug 27 22:49:39 volumio volumio[1036]: info: Starting Shairport Sync Aug 27 22:49:39 volumio sudo[4392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 22:49:39 volumio sudo[4392]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:39 volumio sudo[4390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 22:49:39 volumio sudo[4390]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:49:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 27 22:49:40 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 27 22:49:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 27 22:49:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 27 22:49:40 volumio sudo[4392]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:40 volumio sudo[4390]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:40 volumio volumio[1036]: info: Shairport-Sync Started Aug 27 22:49:40 volumio volumio[1036]: info: Shairport-Sync Started Aug 27 22:49:41 volumio mpd[4380]: Aug 27 22:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 22:49:41 volumio systemd[1]: Started Music Player Daemon. Aug 27 22:49:41 volumio sudo[4352]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:41 volumio sudo[4361]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:41 volumio volumio[1036]: error: MPD error: The expression evaluated to a falsy value: Aug 27 22:49:41 volumio volumio[1036]: assert.ok(self.idling) Aug 27 22:49:41 volumio volumio[1036]: error: The expression evaluated to a falsy value: Aug 27 22:49:41 volumio volumio[1036]: assert.ok(self.idling) Aug 27 22:49:41 volumio volumio[1036]: error: updateQueue error: null Aug 27 22:49:41 volumio volumio[1036]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 27 22:49:41 volumio volumio[1036]: info: CoreStateMachine::getcurrentVolume Aug 27 22:49:41 volumio volumio[1036]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 22:49:41 volumio volumio[1036]: info: VolumeController:: Volume=41 Mute =false Aug 27 22:49:41 volumio volumio[1036]: info: CoreStateMachine::pushState Aug 27 22:49:41 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:41 volumio volumio[1036]: info: CoreCommandRouter::volumioPushState Aug 27 22:49:42 volumio volumio[1036]: info: CoreStateMachine::updateTrackBlock Aug 27 22:49:42 volumio volumio[1036]: info: CorePlayQueue::getTrackBlock Aug 27 22:49:42 volumio volumio[1036]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 22:49:42 volumio volumio[1036]: info: VolumeController:: Volume=41 Mute =false Aug 27 22:49:42 volumio volumio[1036]: info: CoreStateMachine::pushState Aug 27 22:49:42 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:42 volumio volumio[1036]: info: CoreCommandRouter::volumioPushState Aug 27 22:49:43 volumio volumio[1036]: error: Upnp client error: Error: This socket has been ended by the other party Aug 27 22:49:44 volumio volumio[1036]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Aug 27 22:49:44 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Aug 27 22:49:44 volumio volumio[1036]: error: Failed callmethod call: TypeError: Cannot read property 'value' of undefined Aug 27 22:49:46 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Aug 27 22:49:46 volumio volumio[1036]: info: CoreCommandRouter::Reload Ui Aug 27 22:49:46 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Aug 27 22:49:46 volumio volumio[1036]: info: Wizard terminated Successfully Aug 27 22:49:46 volumio volumio[1036]: info: CoreCommandRouter::volumiosetStartupVolume Aug 27 22:49:46 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:49:47 volumio sudo[4410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 22:49:47 volumio sudo[4410]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:47 volumio sudo[4408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 22:49:47 volumio sudo[4408]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:47 volumio sudo[4410]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:47 volumio sudo[4408]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:47 volumio volumio[1036]: verbose: New Socket.io Connection to 192.168.0.109 from 192.168.0.104 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8 Aug 27 22:49:47 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 22:49:47 volumio volumio[1036]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 22:49:47 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:49:47 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:49:47 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:47 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 27 22:49:47 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 22:49:47 volumio volumio[1036]: info: Received Get System Info Aug 27 22:49:47 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 22:49:47 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 22:49:47 volumio volumio[1036]: info: Discovery: Getting this device information Aug 27 22:49:47 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:49:47 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:47 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 22:49:47 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:49:47 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:47 volumio volumio[1036]: info: Listing playlists Aug 27 22:49:47 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Aug 27 22:49:47 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 22:49:47 volumio systemd[1]: systemd-timedated.service: Succeeded. Aug 27 22:49:48 volumio sudo[4416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 22:49:48 volumio sudo[4418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 22:49:48 volumio sudo[4418]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:48 volumio sudo[4416]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 22:49:48 volumio sudo[4416]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:48 volumio sudo[4418]: pam_unix(sudo:session): session closed for user root Aug 27 22:49:49 volumio volumio[1036]: verbose: New Socket.io Connection to 192.168.0.109 from 192.168.0.104 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8 Aug 27 22:49:49 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 22:49:49 volumio volumio[1036]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 22:49:49 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:49:49 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:49:49 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:49 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 27 22:49:49 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 22:49:49 volumio volumio[1036]: info: Received Get System Info Aug 27 22:49:49 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 22:49:49 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 22:49:49 volumio volumio[1036]: info: Discovery: Getting this device information Aug 27 22:49:49 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:49:49 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:49 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 22:49:49 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:49:49 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:49 volumio volumio[1036]: info: Listing playlists Aug 27 22:49:49 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 27 22:49:49 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 27 22:49:49 volumio volumio[1036]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Aug 27 22:49:49 volumio volumio[1036]: info: Retrying Login Due to Network Error Aug 27 22:49:50 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 22:49:50 volumio volumio[1036]: info: Received Get System Info Aug 27 22:49:50 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 22:49:50 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 22:49:50 volumio volumio[1036]: info: Discovery: Getting this device information Aug 27 22:49:50 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:49:50 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:50 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 22:49:50 volumio volumio[1036]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 27 22:49:50 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:49:50 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:51 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 27 22:49:52 volumio volumio[1036]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 27 22:49:53 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 22:49:53 volumio kernel: perf: interrupt took too long (2522 > 2500), lowering kernel.perf_event_max_sample_rate to 79250 Aug 27 22:49:54 volumio volumio[1036]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 22:49:54 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:49:54 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:49:54 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:54 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 27 22:49:54 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 22:49:54 volumio volumio[1036]: info: Received Get System Info Aug 27 22:49:54 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 22:49:54 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 22:49:54 volumio volumio[1036]: info: Discovery: Getting this device information Aug 27 22:49:54 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:49:54 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:54 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 22:49:54 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:49:54 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:54 volumio volumio[1036]: info: Listing playlists Aug 27 22:49:54 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:49:54 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:54 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 27 22:49:54 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 27 22:49:54 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 22:49:54 volumio volumio[1036]: info: Received Get System Info Aug 27 22:49:54 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 22:49:54 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 22:49:54 volumio volumio[1036]: info: Discovery: Getting this device information Aug 27 22:49:54 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:49:54 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:49:54 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 22:49:56 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 27 22:49:59 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 27 22:49:59 volumio volumio[1036]: info: MyVolumio login type: Token Aug 27 22:50:01 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Aug 27 22:50:01 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Aug 27 22:50:01 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:50:01 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:50:02 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 27 22:50:12 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 27 22:50:12 volumio volumio[1036]: info: CorePlayQueue::getTrack 2 Aug 27 22:50:13 volumio volumio[1036]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 22:50:13 volumio volumio[1036]: Error: connect ETIMEDOUT 103.73.161.52:80 Aug 27 22:50:13 volumio volumio[1036]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Aug 27 22:50:13 volumio volumio[1036]: errno: -110, Aug 27 22:50:13 volumio volumio[1036]: code: 'ETIMEDOUT', Aug 27 22:50:13 volumio volumio[1036]: syscall: 'connect', Aug 27 22:50:13 volumio volumio[1036]: address: '103.73.161.52', Aug 27 22:50:13 volumio volumio[1036]: port: 80 Aug 27 22:50:13 volumio volumio[1036]: } Aug 27 22:50:13 volumio volumio[1036]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 22:50:13 volumio sudo[4502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-27 22:49 Aug 27 22:50:13 volumio sudo[4502]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"