-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Mon 2025-02-17 17:44:54 CST. -- Feb 17 17:43:27 volumio volumio[870]: info: CoreCommandRouter::volumioPause Feb 17 17:43:27 volumio volumio[870]: info: CoreStateMachine::pause Feb 17 17:43:27 volumio volumio[870]: info: CoreStateMachine::stPlaybackTimer Feb 17 17:43:27 volumio volumio[870]: info: CoreStateMachine::servicePause Feb 17 17:43:27 volumio volumio[870]: info: CorePlayQueue::getTrack 0 Feb 17 17:43:27 volumio volumio[870]: info: CoreCommandRouter::servicePause Feb 17 17:43:27 volumio volumio[870]: info: [1739785407615] ControllerUPNPBrowser::pause Feb 17 17:43:27 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand pause Feb 17 17:43:27 volumio volumio[870]: info: sendMpdCommand pause took 3 milliseconds Feb 17 17:43:27 volumio volumio[870]: info: Feb 17 17:43:27 volumio volumio[870]: ---------------------------- MPD announces state update: player Feb 17 17:43:27 volumio volumio[870]: info: ControllerMpd::getState Feb 17 17:43:27 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand status Feb 17 17:43:27 volumio volumio[870]: info: Feb 17 17:43:27 volumio volumio[870]: ---------------------------- MPD announces state update: player Feb 17 17:43:27 volumio volumio[870]: info: ControllerMpd::getState Feb 17 17:43:27 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand status Feb 17 17:43:27 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:43:27 volumio volumio[870]: info: sendMpdCommand status took 4 milliseconds Feb 17 17:43:27 volumio volumio[870]: verbose: ControllerMpd::parseState Feb 17 17:43:27 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 17:43:27 volumio volumio[870]: info: Feb 17 17:43:27 volumio volumio[870]: ---------------------------- MPD announces state update: player Feb 17 17:43:27 volumio volumio[870]: info: ControllerMpd::getState Feb 17 17:43:27 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand status Feb 17 17:43:27 volumio volumio[870]: info: sendMpdCommand status took 9 milliseconds Feb 17 17:43:27 volumio volumio[870]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 17 17:43:27 volumio volumio[870]: info: sendMpdCommand status took 5 milliseconds Feb 17 17:43:27 volumio volumio[870]: verbose: ControllerMpd::parseState Feb 17 17:43:27 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 17:43:27 volumio volumio[870]: verbose: ControllerMpd::parseTrackInfo Feb 17 17:43:27 volumio volumio[870]: verbose: ControllerMpd::parseState Feb 17 17:43:27 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 17:43:27 volumio volumio[870]: info: ControllerMpd::pushState Feb 17 17:43:27 volumio volumio[870]: info: CoreCommandRouter::servicePushState Feb 17 17:43:27 volumio volumio[870]: info: CorePlayQueue::getTrack 0 Feb 17 17:43:27 volumio volumio[870]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":80356,"duration":229,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1794 Kbps","isStreaming":false,"title":"Fortnight","artist":"Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/271.flac","trackType":"flac"} Feb 17 17:43:27 volumio volumio[870]: verbose: CURRENT POSITION 0 Feb 17 17:43:27 volumio volumio[870]: info: CoreStateMachine::syncState stateService pause Feb 17 17:43:27 volumio volumio[870]: info: CoreStateMachine::syncState currentStatus pause Feb 17 17:43:27 volumio volumio[870]: info: CoreStateMachine::pushState Feb 17 17:43:27 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 17:43:27 volumio volumio[870]: info: CoreCommandRouter::volumioPushState Feb 17 17:43:27 volumio volumio[870]: info: CoreStateMachine::stPlaybackTimer Feb 17 17:43:27 volumio volumio[870]: info: ------------------------------ 45ms Feb 17 17:43:27 volumio volumio[870]: info: sendMpdCommand playlistinfo took 30 milliseconds Feb 17 17:43:27 volumio volumio[870]: info: sendMpdCommand playlistinfo took 29 milliseconds Feb 17 17:43:27 volumio volumio[870]: verbose: ControllerMpd::parseTrackInfo Feb 17 17:43:27 volumio volumio[870]: verbose: ControllerMpd::parseTrackInfo Feb 17 17:43:27 volumio volumio[870]: info: ControllerMpd::pushState Feb 17 17:43:27 volumio volumio[870]: info: CoreCommandRouter::servicePushState Feb 17 17:43:27 volumio volumio[870]: info: CorePlayQueue::getTrack 0 Feb 17 17:43:27 volumio volumio[870]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":80356,"duration":229,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1794 Kbps","isStreaming":false,"title":"Fortnight","artist":"Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/271.flac","trackType":"flac"} Feb 17 17:43:27 volumio volumio[870]: verbose: CURRENT POSITION 0 Feb 17 17:43:27 volumio volumio[870]: info: CoreStateMachine::syncState stateService pause Feb 17 17:43:27 volumio volumio[870]: info: CoreStateMachine::syncState currentStatus pause Feb 17 17:43:27 volumio volumio[870]: info: CoreStateMachine::pushState Feb 17 17:43:27 volumio volumio[870]: info: CoreCommandRouter::volumioPushState Feb 17 17:43:27 volumio volumio[870]: info: CoreStateMachine::stPlaybackTimer Feb 17 17:43:27 volumio volumio[870]: info: ControllerMpd::pushState Feb 17 17:43:27 volumio volumio[870]: info: CoreCommandRouter::servicePushState Feb 17 17:43:27 volumio volumio[870]: info: CorePlayQueue::getTrack 0 Feb 17 17:43:27 volumio volumio[870]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":80356,"duration":229,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1794 Kbps","isStreaming":false,"title":"Fortnight","artist":"Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/271.flac","trackType":"flac"} Feb 17 17:43:27 volumio volumio[870]: verbose: CURRENT POSITION 0 Feb 17 17:43:27 volumio volumio[870]: info: CoreStateMachine::syncState stateService pause Feb 17 17:43:27 volumio volumio[870]: info: CoreStateMachine::syncState currentStatus pause Feb 17 17:43:27 volumio volumio[870]: info: CoreStateMachine::pushState Feb 17 17:43:27 volumio volumio[870]: info: CoreCommandRouter::volumioPushState Feb 17 17:43:27 volumio volumio[870]: info: CoreStateMachine::stPlaybackTimer Feb 17 17:43:27 volumio volumio[870]: info: ------------------------------ 98ms Feb 17 17:43:27 volumio volumio[870]: info: ------------------------------ 93ms Feb 17 17:43:27 volumio volumio[870]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:43:27 volumio volumio[870]: info: CoreStateMachine::getQueue Feb 17 17:43:27 volumio volumio[870]: info: CorePlayQueue::getQueue Feb 17 17:43:27 volumio volumio[870]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:43:27 volumio volumio[870]: info: CoreStateMachine::getQueue Feb 17 17:43:27 volumio volumio[870]: info: CorePlayQueue::getQueue Feb 17 17:43:27 volumio volumio[870]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:43:27 volumio volumio[870]: info: CoreStateMachine::getQueue Feb 17 17:43:27 volumio volumio[870]: info: CorePlayQueue::getQueue Feb 17 17:43:31 volumio volumio[870]: info: CoreCommandRouter::volumioGetState Feb 17 17:43:35 volumio volumio[870]: info: CoreCommandRouter::volumioGetState Feb 17 17:43:39 volumio volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 17:43:39 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 17 17:43:39 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 17 17:43:39 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 17 17:43:39 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 17 17:43:40 volumio volumio[870]: info: Received Get System Version Feb 17 17:43:40 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 17:43:54 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard Feb 17 17:43:54 volumio volumio[870]: info: CoreCommandRouter::Reload Ui Feb 17 17:43:55 volumio sudo[2799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 17:43:55 volumio sudo[2799]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:43:55 volumio sudo[2799]: pam_unix(sudo:session): session closed for user root Feb 17 17:43:55 volumio sudo[2802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 17:43:55 volumio sudo[2802]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:43:55 volumio sudo[2802]: pam_unix(sudo:session): session closed for user root Feb 17 17:43:56 volumio volumio[870]: verbose: New Socket.io Connection to 192.168.3.3 from 192.168.3.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 17 17:43:56 volumio volumio[870]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 17:43:56 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 17:43:56 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Feb 17 17:43:56 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Feb 17 17:43:56 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 17 17:43:56 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 17 17:43:56 volumio volumio[870]: info: Received Get System Info Feb 17 17:43:56 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 17:43:56 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 17:43:56 volumio volumio[870]: info: Discovery: Getting this device information Feb 17 17:43:56 volumio volumio[870]: info: CoreCommandRouter::volumioGetState Feb 17 17:43:56 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 17:43:56 volumio volumio-remote-updater[587]: No test mode Feb 17 17:43:56 volumio volumio-remote-updater[587]: No alpha test mode Feb 17 17:43:56 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 17 17:43:56 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 17 17:43:56 volumio volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 17:43:56 volumio volumio[870]: info: Retrieving Cloud Streaming UI Feb 17 17:43:56 volumio volumio[870]: info: Getting Tidal Cloud Configuration Feb 17 17:43:56 volumio volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 17:43:56 volumio volumio[870]: info: Getting Qobuz Cloud Configuration Feb 17 17:43:56 volumio volumio[870]: info: Asking plugin for UI Config Feb 17 17:43:56 volumio volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 17:43:56 volumio volumio[870]: info: Getting Spotify Cloud Configuration Feb 17 17:43:56 volumio volumio[870]: info: Asking plugin for UI Config Feb 17 17:43:56 volumio volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 17:43:56 volumio volumio[870]: info: Saving Spotify Acccount Feb 17 17:43:56 volumio volumio[870]: info: Got Tidal Cloud Configuration Feb 17 17:43:56 volumio volumio[870]: info: Got it Feb 17 17:43:56 volumio volumio[870]: info: Got it Feb 17 17:43:56 volumio volumio[870]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 17 17:43:56 volumio volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 17:43:56 volumio volumio[870]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Feb 17 17:43:57 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 17 17:43:57 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 17 17:43:57 volumio volumio[870]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 17 17:43:57 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 17 17:43:58 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 17 17:43:58 volumio volumio[870]: info: Loading i18n strings for locale zh Feb 17 17:43:58 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 17 17:43:58 volumio volumio[870]: info: Setting timezone to Asia/Shanghai Feb 17 17:43:58 volumio sudo[2809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 17 17:43:58 volumio sudo[2809]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:43:58 volumio sudo[2809]: pam_unix(sudo:session): session closed for user root Feb 17 17:43:58 volumio sudo[2812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime Feb 17 17:43:58 volumio sudo[2812]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:43:58 volumio sudo[2812]: pam_unix(sudo:session): session closed for user root Feb 17 17:43:58 volumio sudo[2815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 17 17:43:58 volumio sudo[2815]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:43:58 volumio sudo[2815]: pam_unix(sudo:session): session closed for user root Feb 17 17:43:58 volumio sudo[2818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai Feb 17 17:43:58 volumio sudo[2818]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:43:58 volumio dbus-daemon[604]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.19' (uid=0 pid=2819 comm="/usr/bin/timedatectl set-timezone Asia/Shanghai ") Feb 17 17:43:58 volumio systemd[1]: Starting Time & Date Service... Feb 17 17:43:58 volumio dbus-daemon[604]: [system] Successfully activated service 'org.freedesktop.timedate1' Feb 17 17:43:58 volumio systemd[1]: Started Time & Date Service. Feb 17 17:43:58 volumio sudo[2818]: pam_unix(sudo:session): session closed for user root Feb 17 17:43:58 volumio volumio[870]: info: Fetching Streaming Services browse cache Feb 17 17:43:58 volumio volumio[870]: Updating browse sources language Feb 17 17:43:58 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 17:43:59 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 17 17:44:01 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 17 17:44:02 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 17 17:44:02 volumio volumio[870]: info: Loading i18n strings for locale zh Feb 17 17:44:02 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 17 17:44:02 volumio volumio[870]: info: Setting timezone to Asia/Shanghai Feb 17 17:44:02 volumio sudo[2839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 17 17:44:02 volumio sudo[2839]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:44:02 volumio sudo[2839]: pam_unix(sudo:session): session closed for user root Feb 17 17:44:02 volumio sudo[2842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime Feb 17 17:44:02 volumio sudo[2842]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:44:02 volumio sudo[2842]: pam_unix(sudo:session): session closed for user root Feb 17 17:44:02 volumio sudo[2845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 17 17:44:02 volumio sudo[2845]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:44:02 volumio sudo[2845]: pam_unix(sudo:session): session closed for user root Feb 17 17:44:02 volumio sudo[2848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai Feb 17 17:44:02 volumio sudo[2848]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:44:02 volumio sudo[2848]: pam_unix(sudo:session): session closed for user root Feb 17 17:44:02 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 17 17:44:02 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 17 17:44:02 volumio sudo[2851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 17 17:44:02 volumio sudo[2851]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:44:02 volumio volumio[870]: Updating browse sources language Feb 17 17:44:02 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 17:44:02 volumio volumio[870]: info: Fetching Streaming Services browse cache Feb 17 17:44:04 volumio sudo[2851]: pam_unix(sudo:session): session closed for user root Feb 17 17:44:04 volumio volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 17:44:04 volumio volumio[870]: info: Retrieving Cloud Streaming UI Feb 17 17:44:04 volumio volumio[870]: info: Getting Tidal Cloud Configuration Feb 17 17:44:04 volumio volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 17:44:04 volumio volumio[870]: info: Getting Qobuz Cloud Configuration Feb 17 17:44:04 volumio volumio[870]: info: Asking plugin for UI Config Feb 17 17:44:04 volumio volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 17:44:04 volumio volumio[870]: info: Getting Spotify Cloud Configuration Feb 17 17:44:04 volumio volumio[870]: info: Asking plugin for UI Config Feb 17 17:44:04 volumio volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 17:44:04 volumio volumio[870]: info: Saving Spotify Acccount Feb 17 17:44:04 volumio volumio[870]: info: Got Tidal Cloud Configuration Feb 17 17:44:04 volumio volumio[870]: info: Got it Feb 17 17:44:04 volumio volumio[870]: info: Got it Feb 17 17:44:04 volumio volumio[870]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 17 17:44:04 volumio volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 17:44:04 volumio volumio[870]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Feb 17 17:44:06 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Feb 17 17:44:06 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 17 17:44:06 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 17:44:06 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 17 17:44:13 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 17 17:44:13 volumio volumio[870]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 17 17:44:13 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 17 17:44:13 volumio volumio[870]: info: Received Get System Version Feb 17 17:44:13 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 17:44:13 volumio volumio[870]: info: Received Get System Info Feb 17 17:44:13 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 17:44:13 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 17:44:13 volumio volumio[870]: info: Discovery: Getting this device information Feb 17 17:44:13 volumio volumio[870]: info: CoreCommandRouter::volumioGetState Feb 17 17:44:13 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 17 17:44:14 volumio volumio[870]: info: Preparing to save Alsa Options, stopping services first Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::volumioGetState Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::volumioPause Feb 17 17:44:14 volumio volumio[870]: info: CoreStateMachine::pause Feb 17 17:44:14 volumio volumio[870]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"label":"Audiophonics I-Sabre ES9028Q2M"},"output_device":{"value":1,"label":"Audiophonics I-Sabre ES9028Q2M"},"disallowPush":true} Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 17:44:14 volumio volumio[870]: info: Found match in i2s Card Database: setting mixer Digital for card Audiophonics I-Sabre ES9028Q2M Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 17 17:44:14 volumio volumio[870]: info: Updating Volume Controller Parameters: Device: 2 Name: Audiophonics I-Sabre ES9028Q2M Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 17 17:44:14 volumio volumio[870]: info: Disabling external Volume Control Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 17:44:14 volumio volumio[870]: info: Preparing to generate the ALSA configuration file Feb 17 17:44:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 17 17:44:14 volumio sudo[2919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 17 17:44:14 volumio sudo[2919]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:44:14 volumio sudo[2921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 17 17:44:14 volumio sudo[2921]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:44:14 volumio sudo[2921]: pam_unix(sudo:session): session closed for user root Feb 17 17:44:14 volumio volumio[870]: info: Asound.conf file unchanged, so no further update is needed Feb 17 17:44:14 volumio volumio[870]: info: Output device has changed, restarting MPD Feb 17 17:44:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Feb 17 17:44:14 volumio volumio[870]: info: Output device has changed, restarting Shairport Sync Feb 17 17:44:14 volumio sudo[2926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 17:44:14 volumio sudo[2926]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 17:44:14 volumio sudo[2926]: pam_unix(sudo:session): session closed for user root Feb 17 17:44:14 volumio sudo[2928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 17:44:14 volumio sudo[2928]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:44:14 volumio systemd[1]: Stopping Music Player Daemon... Feb 17 17:44:14 volumio volumio[870]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 17:44:14 volumio systemd[1]: mpd.service: Succeeded. Feb 17 17:44:14 volumio systemd[1]: Stopped Music Player Daemon. Feb 17 17:44:14 volumio systemd[1]: Starting Music Player Daemon... Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 17:44:14 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 17 17:44:14 volumio volumio[870]: info: Getting Alsa Cards List without I2S DAC Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 17:44:15 volumio sudo[2935]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 17 17:44:15 volumio sudo[2935]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:44:15 volumio sudo[2935]: pam_unix(sudo:session): session closed for user root Feb 17 17:44:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Feb 17 17:44:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 17 17:44:15 volumio volumio[870]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 17 17:44:15 volumio volumio[870]: wlan0 Interface doesn't support scanning : Device or resource busy Feb 17 17:44:15 volumio volumio[870]: info: Cannot use regular scanning, forcing with ap-force Feb 17 17:44:15 volumio sudo[2947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 17 17:44:15 volumio sudo[2947]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:44:15 volumio sudo[2947]: pam_unix(sudo:session): session closed for user root Feb 17 17:44:15 volumio volumio[870]: command failed: Device or resource busy (-16) Feb 17 17:44:15 volumio volumio[870]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 17 17:44:15 volumio volumio[870]: command failed: Device or resource busy (-16) Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 17:44:15 volumio volumio[870]: info: Retrieving Cloud Streaming UI Feb 17 17:44:15 volumio volumio[870]: info: MPD Permissions set Feb 17 17:44:15 volumio volumio[870]: info: VolumeController:: Volume=100 Mute =true Feb 17 17:44:15 volumio volumio[870]: info: CoreStateMachine::pushState Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::volumioPushState Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:44:15 volumio volumio[870]: info: CoreStateMachine::getQueue Feb 17 17:44:15 volumio volumio[870]: info: CorePlayQueue::getQueue Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 17:44:15 volumio volumio[870]: info: Getting Tidal Cloud Configuration Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 17:44:15 volumio volumio[870]: info: Getting Qobuz Cloud Configuration Feb 17 17:44:15 volumio volumio[870]: info: Asking plugin for UI Config Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 17:44:15 volumio volumio[870]: info: Getting Spotify Cloud Configuration Feb 17 17:44:15 volumio volumio[870]: info: Asking plugin for UI Config Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 17:44:15 volumio volumio[870]: info: Saving Spotify Acccount Feb 17 17:44:15 volumio volumio[870]: info: Got Tidal Cloud Configuration Feb 17 17:44:15 volumio volumio[870]: info: Got it Feb 17 17:44:15 volumio volumio[870]: info: Got it Feb 17 17:44:15 volumio volumio[870]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 17 17:44:15 volumio volumio[870]: info: Starting Shairport Sync Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 17:44:15 volumio volumio[870]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Feb 17 17:44:15 volumio sudo[2954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 17:44:15 volumio sudo[2954]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:44:15 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 17 17:44:15 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 17 17:44:15 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 17 17:44:15 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 17 17:44:15 volumio sudo[2954]: pam_unix(sudo:session): session closed for user root Feb 17 17:44:15 volumio volumio[870]: info: Shairport-Sync Started Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Feb 17 17:44:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Feb 17 17:44:16 volumio sudo[2919]: pam_unix(sudo:session): session closed for user root Feb 17 17:44:17 volumio mpd[2945]: Feb 17 17:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 17 17:44:17 volumio systemd[1]: Started Music Player Daemon. Feb 17 17:44:17 volumio sudo[2928]: pam_unix(sudo:session): session closed for user root Feb 17 17:44:17 volumio volumio[870]: error: updateQueue error: null Feb 17 17:44:18 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Feb 17 17:44:18 volumio volumio[870]: info: CoreCommandRouter::Reload Ui Feb 17 17:44:18 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Feb 17 17:44:18 volumio volumio[870]: info: Wizard terminated Successfully Feb 17 17:44:18 volumio volumio[870]: info: CoreCommandRouter::volumiosetStartupVolume Feb 17 17:44:18 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 17:44:18 volumio volumio[870]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 17 17:44:18 volumio volumio[870]: info: CoreStateMachine::getcurrentVolume Feb 17 17:44:18 volumio volumio[870]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 17:44:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Feb 17 17:44:18 volumio volumio[870]: info: VolumeController:: Volume=100 Mute =true Feb 17 17:44:18 volumio volumio[870]: info: CoreStateMachine::pushState Feb 17 17:44:18 volumio volumio[870]: info: CoreCommandRouter::volumioPushState Feb 17 17:44:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Feb 17 17:44:18 volumio volumio[870]: info: CoreStateMachine::updateTrackBlock Feb 17 17:44:18 volumio volumio[870]: info: CorePlayQueue::getTrackBlock Feb 17 17:44:18 volumio volumio[870]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 17:44:18 volumio volumio[870]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:44:18 volumio volumio[870]: info: CoreStateMachine::getQueue Feb 17 17:44:18 volumio volumio[870]: info: CorePlayQueue::getQueue Feb 17 17:44:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Feb 17 17:44:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Feb 17 17:44:18 volumio volumio[870]: info: VolumeController:: Volume=100 Mute =true Feb 17 17:44:18 volumio volumio[870]: info: CoreStateMachine::pushState Feb 17 17:44:18 volumio volumio[870]: info: CoreCommandRouter::volumioPushState Feb 17 17:44:18 volumio volumio[870]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:44:18 volumio volumio[870]: info: CoreStateMachine::getQueue Feb 17 17:44:18 volumio volumio[870]: info: CorePlayQueue::getQueue Feb 17 17:44:19 volumio sudo[2975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 17:44:19 volumio sudo[2975]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:44:19 volumio sudo[2975]: pam_unix(sudo:session): session closed for user root Feb 17 17:44:19 volumio sudo[2978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 17:44:19 volumio sudo[2978]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:44:19 volumio sudo[2978]: pam_unix(sudo:session): session closed for user root Feb 17 17:44:19 volumio volumio[870]: verbose: New Socket.io Connection to 192.168.3.3 from 192.168.3.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 17 17:44:19 volumio sudo[2981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 17:44:19 volumio sudo[2981]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:44:19 volumio sudo[2981]: pam_unix(sudo:session): session closed for user root Feb 17 17:44:19 volumio sudo[2983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 17:44:19 volumio sudo[2983]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 17:44:19 volumio sudo[2983]: pam_unix(sudo:session): session closed for user root Feb 17 17:44:19 volumio volumio[870]: verbose: New Socket.io Connection to 192.168.3.3 from 192.168.3.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 17 17:44:19 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 17 17:44:19 volumio volumio[870]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 17:44:19 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 17:44:19 volumio volumio[870]: info: CoreCommandRouter::volumioGetState Feb 17 17:44:19 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 17 17:44:19 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 17 17:44:19 volumio volumio[870]: info: Received Get System Info Feb 17 17:44:19 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 17:44:19 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 17:44:19 volumio volumio[870]: info: Discovery: Getting this device information Feb 17 17:44:19 volumio volumio[870]: info: CoreCommandRouter::volumioGetState Feb 17 17:44:19 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 17:44:19 volumio volumio[870]: info: CoreCommandRouter::volumioGetState Feb 17 17:44:20 volumio volumio[870]: info: Listing playlists Feb 17 17:44:20 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 17 17:44:20 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 17 17:44:20 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 17 17:44:20 volumio volumio[870]: info: CoreCommandRouter::volumioGetState Feb 17 17:44:21 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 17 17:44:21 volumio volumio[870]: info: Received Get System Info Feb 17 17:44:21 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 17:44:21 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 17:44:21 volumio volumio[870]: info: Discovery: Getting this device information Feb 17 17:44:21 volumio volumio[870]: info: CoreCommandRouter::volumioGetState Feb 17 17:44:21 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 17:44:21 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 17 17:44:22 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 17 17:44:22 volumio volumio[870]: info: Received Get System Info Feb 17 17:44:22 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 17:44:22 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 17:44:22 volumio volumio[870]: info: Discovery: Getting this device information Feb 17 17:44:22 volumio volumio[870]: info: CoreCommandRouter::volumioGetState Feb 17 17:44:22 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 17:44:24 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Feb 17 17:44:26 volumio volumio[870]: info: CoreCommandRouter::volumioPlay Feb 17 17:44:26 volumio volumio[870]: info: CoreStateMachine::play index undefined Feb 17 17:44:26 volumio volumio[870]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 17:44:26 volumio volumio[870]: info: CorePlayQueue::getTrack 0 Feb 17 17:44:26 volumio volumio[870]: info: CoreStateMachine::startPlaybackTimer Feb 17 17:44:26 volumio volumio[870]: info: CorePlayQueue::getTrack 0 Feb 17 17:44:26 volumio volumio[870]: info: [1739785466382] ControllerUPNPBrowser::resume Feb 17 17:44:26 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand play Feb 17 17:44:26 volumio volumio[870]: info: sendMpdCommand play took 2 milliseconds Feb 17 17:44:28 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Feb 17 17:44:28 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 17 17:44:29 volumio volumio-remote-updater[587]: No test mode Feb 17 17:44:29 volumio volumio-remote-updater[587]: No alpha test mode Feb 17 17:44:29 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 17 17:44:29 volumio volumio[870]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:44:29 volumio volumio[870]: info: CoreStateMachine::getQueue Feb 17 17:44:29 volumio volumio[870]: info: CorePlayQueue::getQueue Feb 17 17:44:29 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 17 17:44:29 volumio volumio[870]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 17 17:44:29 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 17 17:44:32 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Feb 17 17:44:32 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 17 17:44:32 volumio systemd[1]: systemd-timedated.service: Succeeded. Feb 17 17:44:33 volumio volumio-remote-updater[587]: No test mode Feb 17 17:44:33 volumio volumio-remote-updater[587]: No alpha test mode Feb 17 17:44:33 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 17 17:44:34 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 17:44:34 volumio volumio[870]: info: Preload queue cleared Feb 17 17:44:36 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 17:44:36 volumio volumio[870]: info: Preload queue cleared Feb 17 17:44:37 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 17 17:44:37 volumio volumio[870]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 17 17:44:37 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 17 17:44:37 volumio volumio[870]: info: Received Get System Version Feb 17 17:44:37 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 17:44:37 volumio volumio[870]: info: Received Get System Info Feb 17 17:44:37 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 17:44:37 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 17:44:37 volumio volumio[870]: info: Discovery: Getting this device information Feb 17 17:44:37 volumio volumio[870]: info: CoreCommandRouter::volumioGetState Feb 17 17:44:37 volumio volumio[870]: info: CorePlayQueue::getTrack 0 Feb 17 17:44:37 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 17:44:39 volumio volumio[870]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 17 17:44:39 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 17 17:44:39 volumio volumio[870]: info: Preload queue cleared Feb 17 17:44:39 volumio volumio[870]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 17 17:44:39 volumio volumio[870]: info: CoreStateMachine::ClearQueue Feb 17 17:44:39 volumio volumio[870]: info: CoreStateMachine::stop Feb 17 17:44:39 volumio volumio[870]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 17:44:39 volumio volumio[870]: info: CoreStateMachine::updateTrackBlock Feb 17 17:44:39 volumio volumio[870]: info: CorePlayQueue::getTrackBlock Feb 17 17:44:39 volumio volumio[870]: info: CoreStateMachine::stPlaybackTimer Feb 17 17:44:39 volumio volumio[870]: info: CoreStateMachine::pushState Feb 17 17:44:39 volumio volumio[870]: info: CorePlayQueue::getTrack 0 Feb 17 17:44:39 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 17:44:39 volumio volumio[870]: info: CoreCommandRouter::volumioPushState Feb 17 17:44:39 volumio volumio[870]: info: CoreStateMachine::serviceStop Feb 17 17:44:39 volumio volumio[870]: info: CorePlayQueue::getTrack 0 Feb 17 17:44:39 volumio volumio[870]: info: CoreCommandRouter::serviceStop Feb 17 17:44:39 volumio volumio[870]: info: [1739785479975] ControllerUPNPBrowser::stop Feb 17 17:44:39 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 17:44:39 volumio volumio[870]: info: CorePlayQueue::clearPlayQueue Feb 17 17:44:39 volumio volumio[870]: info: CorePlayQueue::saveQueue Feb 17 17:44:39 volumio volumio[870]: info: CoreCommandRouter::volumioPushQueue Feb 17 17:44:39 volumio volumio[870]: info: CoreStateMachine::addQueueItems Feb 17 17:44:39 volumio volumio[870]: info: CorePlayQueue::addQueueItems Feb 17 17:44:39 volumio volumio[870]: info: Preload queue cleared Feb 17 17:44:39 volumio volumio[870]: info: Adding Item to queue: upnp/folder/http://192.168.3.24:8200/ctl/ContentDir@1$7$7 Feb 17 17:44:39 volumio volumio[870]: info: Exploding uri upnp/folder/http://192.168.3.24:8200/ctl/ContentDir@1$7$7 in service upnp_browser Feb 17 17:44:40 volumio volumio[870]: info: sendMpdCommand stop took 47 milliseconds Feb 17 17:44:40 volumio volumio[870]: info: CoreCommandRouter::volumioPushQueue Feb 17 17:44:40 volumio volumio[870]: info: CorePlayQueue::saveQueue Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::updateTrackBlock Feb 17 17:44:40 volumio volumio[870]: info: CorePlayQueue::getTrackBlock Feb 17 17:44:40 volumio volumio[870]: info: CoreCommandRouter::volumioPlay Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::play index 0 Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::stop Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::play index undefined Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 17:44:40 volumio volumio[870]: info: CorePlayQueue::getTrack 0 Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::startPlaybackTimer Feb 17 17:44:40 volumio volumio[870]: info: CorePlayQueue::getTrack 0 Feb 17 17:44:40 volumio volumio[870]: info: [1739785480102] ControllerUPNPBrowser::clearAddPlayTrack Feb 17 17:44:40 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 17:44:40 volumio volumio[870]: info: sendMpdCommand stop took 15 milliseconds Feb 17 17:44:40 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand clear Feb 17 17:44:40 volumio volumio[870]: info: Feb 17 17:44:40 volumio volumio[870]: ---------------------------- MPD announces system playlist update Feb 17 17:44:40 volumio volumio[870]: info: Ignoring MPD Status Update Feb 17 17:44:40 volumio volumio[870]: info: sendMpdCommand clear took 6 milliseconds Feb 17 17:44:40 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.3.24:8201/MediaItems/295.mp3" Feb 17 17:44:40 volumio volumio[870]: error: updateQueue error: null Feb 17 17:44:40 volumio volumio[870]: info: ------------------------------ 16ms Feb 17 17:44:40 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.3.24:8201/MediaItems/295.mp3" Feb 17 17:44:40 volumio volumio[870]: info: Feb 17 17:44:40 volumio volumio[870]: ---------------------------- MPD announces system playlist update Feb 17 17:44:40 volumio volumio[870]: info: Ignoring MPD Status Update Feb 17 17:44:40 volumio volumio[870]: info: sendMpdCommand add "http://192.168.3.24:8201/MediaItems/295.mp3" took 4 milliseconds Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 17 17:44:40 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand play Feb 17 17:44:40 volumio volumio[870]: info: ------------------------------ 10ms Feb 17 17:44:40 volumio volumio[870]: info: sendMpdCommand play took 7 milliseconds Feb 17 17:44:40 volumio volumio[870]: info: Feb 17 17:44:40 volumio volumio[870]: ---------------------------- MPD announces state update: player Feb 17 17:44:40 volumio volumio[870]: info: ControllerMpd::getState Feb 17 17:44:40 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand status Feb 17 17:44:40 volumio volumio[870]: info: Feb 17 17:44:40 volumio volumio[870]: ---------------------------- MPD announces state update: player Feb 17 17:44:40 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:40 volumio volumio[870]: info: sendMpdCommand status took 6 milliseconds Feb 17 17:44:40 volumio volumio[870]: info: ControllerMpd::getState Feb 17 17:44:40 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand status Feb 17 17:44:40 volumio volumio[870]: verbose: ControllerMpd::parseState Feb 17 17:44:40 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 17:44:40 volumio volumio[870]: info: sendMpdCommand status took 8 milliseconds Feb 17 17:44:40 volumio volumio[870]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 17 17:44:40 volumio volumio[870]: verbose: ControllerMpd::parseState Feb 17 17:44:40 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 17:44:40 volumio volumio[870]: verbose: ControllerMpd::parseTrackInfo Feb 17 17:44:40 volumio volumio[870]: info: ControllerMpd::pushState Feb 17 17:44:40 volumio volumio[870]: info: CoreCommandRouter::servicePushState Feb 17 17:44:40 volumio volumio[870]: info: CorePlayQueue::getTrack 0 Feb 17 17:44:40 volumio volumio[870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"295.mp3","artist":null,"album":null,"uri":"http://192.168.3.24:8201/MediaItems/295.mp3","trackType":"mp3"} Feb 17 17:44:40 volumio volumio[870]: verbose: CURRENT POSITION 0 Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::syncState stateService play Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::syncState currentStatus stop Feb 17 17:44:40 volumio volumio[870]: info: ------------------------------ 48ms Feb 17 17:44:40 volumio volumio[870]: info: Feb 17 17:44:40 volumio volumio[870]: ---------------------------- MPD announces system playlist update Feb 17 17:44:40 volumio volumio[870]: info: Ignoring MPD Status Update Feb 17 17:44:40 volumio volumio[870]: info: Feb 17 17:44:40 volumio volumio[870]: ---------------------------- MPD announces state update: player Feb 17 17:44:40 volumio volumio[870]: info: sendMpdCommand playlistinfo took 26 milliseconds Feb 17 17:44:40 volumio volumio[870]: info: ControllerMpd::getState Feb 17 17:44:40 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand status Feb 17 17:44:40 volumio volumio[870]: verbose: ControllerMpd::parseTrackInfo Feb 17 17:44:40 volumio volumio[870]: info: ControllerMpd::pushState Feb 17 17:44:40 volumio volumio[870]: info: CoreCommandRouter::servicePushState Feb 17 17:44:40 volumio volumio[870]: info: CorePlayQueue::getTrack 0 Feb 17 17:44:40 volumio volumio[870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"295.mp3","artist":null,"album":null,"uri":"http://192.168.3.24:8201/MediaItems/295.mp3","trackType":"mp3"} Feb 17 17:44:40 volumio volumio[870]: verbose: CURRENT POSITION 0 Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::syncState stateService play Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::syncState currentStatus play Feb 17 17:44:40 volumio volumio[870]: info: Received an update from plugin. extracting info from payload Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::pushState Feb 17 17:44:40 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 17:44:40 volumio volumio[870]: info: CoreCommandRouter::volumioPushState Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::pushState Feb 17 17:44:40 volumio volumio[870]: info: CoreCommandRouter::volumioPushState Feb 17 17:44:40 volumio volumio[870]: info: ------------------------------ 103ms Feb 17 17:44:40 volumio volumio[870]: info: ------------------------------ 65ms Feb 17 17:44:40 volumio volumio[870]: info: sendMpdCommand status took 61 milliseconds Feb 17 17:44:40 volumio volumio[870]: verbose: ControllerMpd::parseState Feb 17 17:44:40 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 17:44:40 volumio volumio[870]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 17 17:44:40 volumio volumio[870]: verbose: ControllerMpd::parseTrackInfo Feb 17 17:44:40 volumio volumio[870]: info: ControllerMpd::pushState Feb 17 17:44:40 volumio volumio[870]: info: CoreCommandRouter::servicePushState Feb 17 17:44:40 volumio volumio[870]: info: CorePlayQueue::getTrack 0 Feb 17 17:44:40 volumio volumio[870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"张三的歌","artist":"蔡琴","album":"惠威试音碟1","uri":"http://192.168.3.24:8201/MediaItems/295.mp3","trackType":"mp3"} Feb 17 17:44:40 volumio volumio[870]: verbose: CURRENT POSITION 0 Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::syncState stateService play Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::syncState currentStatus play Feb 17 17:44:40 volumio volumio[870]: info: Received an update from plugin. extracting info from payload Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::pushState Feb 17 17:44:40 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 17:44:40 volumio volumio[870]: info: CoreCommandRouter::volumioPushState Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::pushState Feb 17 17:44:40 volumio volumio[870]: info: CoreCommandRouter::volumioPushState Feb 17 17:44:40 volumio volumio[870]: info: ------------------------------ 146ms Feb 17 17:44:40 volumio volumio[870]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::getQueue Feb 17 17:44:40 volumio volumio[870]: info: CorePlayQueue::getQueue Feb 17 17:44:40 volumio volumio[870]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:44:40 volumio volumio[870]: info: CoreStateMachine::getQueue Feb 17 17:44:40 volumio volumio[870]: info: CorePlayQueue::getQueue Feb 17 17:44:44 volumio volumio[870]: info: VolumeController::SetAlsaVolume82 Feb 17 17:44:44 volumio volumio[870]: info: CoreStateMachine::pushState Feb 17 17:44:44 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 17:44:44 volumio volumio[870]: info: CoreCommandRouter::volumioPushState Feb 17 17:44:44 volumio volumio[870]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:44:44 volumio volumio[870]: info: CoreStateMachine::getQueue Feb 17 17:44:44 volumio volumio[870]: info: CorePlayQueue::getQueue Feb 17 17:44:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Feb 17 17:44:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -5 Feb 17 17:44:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Feb 17 17:44:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:44 volumio volumio[870]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Invalid command! Feb 17 17:44:45 volumio volumio[870]: info: VolumeController::SetAlsaVolume100 Feb 17 17:44:45 volumio volumio[870]: info: CoreStateMachine::pushState Feb 17 17:44:45 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 17:44:45 volumio volumio[870]: info: CoreCommandRouter::volumioPushState Feb 17 17:44:45 volumio volumio[870]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:44:45 volumio volumio[870]: info: CoreStateMachine::getQueue Feb 17 17:44:45 volumio volumio[870]: info: CorePlayQueue::getQueue Feb 17 17:44:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Feb 17 17:44:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Feb 17 17:44:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Feb 17 17:44:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Feb 17 17:44:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Feb 17 17:44:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Feb 17 17:44:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Feb 17 17:44:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Feb 17 17:44:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 17 17:44:46 volumio volumio[870]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Invalid command! Feb 17 17:44:53 volumio volumio[870]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 17:44:53 volumio volumio[870]: Error: Client network socket disconnected before secure TLS connection was established Feb 17 17:44:53 volumio volumio[870]: at connResetException (internal/errors.js:607:14) Feb 17 17:44:53 volumio volumio[870]: at TLSSocket.onConnectEnd (_tls_wrap.js:1544:19) Feb 17 17:44:53 volumio volumio[870]: at TLSSocket.emit (events.js:327:22) Feb 17 17:44:53 volumio volumio[870]: at endReadableNT (internal/streams/readable.js:1327:12) Feb 17 17:44:53 volumio volumio[870]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Feb 17 17:44:53 volumio volumio[870]: code: 'ECONNRESET', Feb 17 17:44:53 volumio volumio[870]: path: null, Feb 17 17:44:53 volumio volumio[870]: host: 'lastfm.freetls.fastly.net', Feb 17 17:44:53 volumio volumio[870]: port: 443, Feb 17 17:44:53 volumio volumio[870]: localAddress: undefined Feb 17 17:44:53 volumio volumio[870]: } Feb 17 17:44:53 volumio volumio[870]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 17:44:54 volumio sudo[3076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-17 17:43 Feb 17 17:44:54 volumio sudo[3076]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"