-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Sat 2025-04-19 15:03:36 EDT. -- Apr 19 15:02:03 volumio volumio[1048]: info: CALLMETHOD: system_controller system saveGeneralSettings [object Object] Apr 19 15:02:03 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Apr 19 15:02:19 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard Apr 19 15:02:19 volumio volumio[1048]: info: CoreCommandRouter::Reload Ui Apr 19 15:02:20 volumio sudo[1705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 15:02:20 volumio sudo[1705]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:20 volumio sudo[1705]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:20 volumio sudo[1707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 15:02:20 volumio sudo[1707]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:20 volumio sudo[1707]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:20 volumio volumio[1048]: verbose: New Socket.io Connection to 192.168.0.51 from 192.168.0.26 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 9 Apr 19 15:02:20 volumio volumio[1048]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 15:02:20 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 15:02:20 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Apr 19 15:02:20 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Apr 19 15:02:20 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Apr 19 15:02:20 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Apr 19 15:02:20 volumio volumio[1048]: info: Received Get System Info Apr 19 15:02:20 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 15:02:20 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 15:02:20 volumio volumio[1048]: info: Discovery: Getting this device information Apr 19 15:02:20 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Apr 19 15:02:20 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 15:02:20 volumio volumio-remote-updater[590]: No test mode Apr 19 15:02:20 volumio volumio-remote-updater[590]: No alpha test mode Apr 19 15:02:20 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 19 15:02:20 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 15:02:20 volumio volumio[1048]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 15:02:20 volumio volumio[1048]: info: Retrieving Cloud Streaming UI Apr 19 15:02:20 volumio volumio[1048]: info: Getting Tidal Cloud Configuration Apr 19 15:02:20 volumio volumio[1048]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 15:02:20 volumio volumio[1048]: info: Getting Qobuz Cloud Configuration Apr 19 15:02:20 volumio volumio[1048]: info: Asking plugin for UI Config Apr 19 15:02:20 volumio volumio[1048]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 15:02:20 volumio volumio[1048]: info: Getting Spotify Cloud Configuration Apr 19 15:02:20 volumio volumio[1048]: info: Asking plugin for UI Config Apr 19 15:02:20 volumio volumio[1048]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 15:02:20 volumio volumio[1048]: info: Saving Spotify Acccount Apr 19 15:02:20 volumio volumio[1048]: info: Got Tidal Cloud Configuration Apr 19 15:02:20 volumio volumio[1048]: info: Got it Apr 19 15:02:20 volumio volumio[1048]: info: Got it Apr 19 15:02:20 volumio volumio[1048]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 19 15:02:20 volumio volumio[1048]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 15:02:20 volumio volumio[1048]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 19 15:02:20 volumio volumio[1048]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 19 15:02:20 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 19 15:02:21 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Apr 19 15:02:21 volumio volumio[1048]: info: Loading i18n strings for locale en Apr 19 15:02:21 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key LOCALSETTINGS. It is ignored. Apr 19 15:02:21 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE. It is ignored. Apr 19 15:02:21 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE_DOC. It is ignored. Apr 19 15:02:21 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key NBARCHANGE. It is ignored. Apr 19 15:02:21 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Apr 19 15:02:21 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Apr 19 15:02:21 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key DOWNLOAD. It is ignored. Apr 19 15:02:21 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Apr 19 15:02:21 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Apr 19 15:02:21 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key LIST_FAIL_UPDATE. It is ignored. Apr 19 15:02:21 volumio volumio[1048]: info: [now-playing] Volumio language changed - pushing localization settings Apr 19 15:02:21 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Apr 19 15:02:21 volumio volumio[1048]: info: Setting timezone to America/Toronto Apr 19 15:02:21 volumio sudo[1715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Apr 19 15:02:21 volumio sudo[1715]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:21 volumio sudo[1715]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:21 volumio sudo[1718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Toronto /etc/localtime Apr 19 15:02:21 volumio sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:21 volumio sudo[1718]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:21 volumio sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Apr 19 15:02:21 volumio sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:21 volumio sudo[1721]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:21 volumio sudo[1724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Toronto Apr 19 15:02:21 volumio sudo[1724]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:21 volumio dbus-daemon[580]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.19' (uid=0 pid=1725 comm="/usr/bin/timedatectl set-timezone America/Toronto ") Apr 19 15:02:21 volumio systemd[1]: Starting Time & Date Service... Apr 19 15:02:21 volumio dbus-daemon[580]: [system] Successfully activated service 'org.freedesktop.timedate1' Apr 19 15:02:21 volumio systemd[1]: Started Time & Date Service. Apr 19 15:02:22 volumio sudo[1724]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:22 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Apr 19 15:02:22 volumio volumio[1048]: info: Loading i18n strings for locale en Apr 19 15:02:22 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key LOCALSETTINGS. It is ignored. Apr 19 15:02:22 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE. It is ignored. Apr 19 15:02:22 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE_DOC. It is ignored. Apr 19 15:02:22 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key NBARCHANGE. It is ignored. Apr 19 15:02:22 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Apr 19 15:02:22 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Apr 19 15:02:22 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key DOWNLOAD. It is ignored. Apr 19 15:02:22 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Apr 19 15:02:22 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Apr 19 15:02:22 volumio volumio[1048]: info: Plugin peppyspectrum has duplicated i18n key LIST_FAIL_UPDATE. It is ignored. Apr 19 15:02:22 volumio volumio[1048]: info: [now-playing] Volumio language changed - pushing localization settings Apr 19 15:02:22 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Apr 19 15:02:22 volumio volumio[1048]: info: Setting timezone to America/Toronto Apr 19 15:02:22 volumio sudo[1728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Apr 19 15:02:22 volumio sudo[1728]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:22 volumio sudo[1728]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:22 volumio sudo[1731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Toronto /etc/localtime Apr 19 15:02:22 volumio sudo[1731]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:22 volumio sudo[1731]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:22 volumio sudo[1738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Apr 19 15:02:22 volumio sudo[1738]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:22 volumio sudo[1738]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:22 volumio sudo[1751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Toronto Apr 19 15:02:22 volumio sudo[1751]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:22 volumio sudo[1751]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:22 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 19 15:02:22 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 19 15:02:22 volumio volumio[1048]: info: Fetching Streaming Services browse cache Apr 19 15:02:22 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 15:02:22 volumio sudo[1754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 19 15:02:22 volumio sudo[1754]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:22 volumio volumio[1048]: Updating browse sources language Apr 19 15:02:22 volumio volumio[1048]: Cannot find translation for source YouTube2 Apr 19 15:02:22 volumio volumio[1048]: Cannot find translation for source Randomizer Apr 19 15:02:22 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 15:02:22 volumio volumio[1048]: Cannot find translation for source YouTube2 Apr 19 15:02:22 volumio volumio[1048]: Cannot find translation for source Randomizer Apr 19 15:02:22 volumio volumio[1048]: Updating browse sources language Apr 19 15:02:22 volumio volumio[1048]: Cannot find translation for source YouTube2 Apr 19 15:02:22 volumio volumio[1048]: Cannot find translation for source Randomizer Apr 19 15:02:22 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 15:02:22 volumio volumio[1048]: Cannot find translation for source YouTube2 Apr 19 15:02:22 volumio volumio[1048]: Cannot find translation for source Randomizer Apr 19 15:02:22 volumio volumio[1048]: info: Fetching Streaming Services browse cache Apr 19 15:02:23 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 15:02:25 volumio sudo[1754]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:26 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Apr 19 15:02:26 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Apr 19 15:02:26 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 19 15:02:26 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 15:02:26 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 19 15:02:31 volumio volumio[1048]: info: Preparing to save Alsa Options, stopping services first Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Apr 19 15:02:31 volumio volumio[1048]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"1","label":"Headphones"},"disallowPush":true} Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 19 15:02:31 volumio volumio[1048]: info: Found match in Cards Database: setting mixer PCM for card Headphones Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 19 15:02:31 volumio volumio[1048]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 19 15:02:31 volumio volumio[1048]: info: Disabling external Volume Control Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 15:02:31 volumio volumio[1048]: info: Preparing to generate the ALSA configuration file Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 15:02:31 volumio volumio[1048]: info: Retrieving Cloud Streaming UI Apr 19 15:02:31 volumio volumio[1048]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Apr 19 15:02:31 volumio volumio[1048]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Apr 19 15:02:31 volumio volumio[1048]: info: Reading ALSA contributions from plugins. Apr 19 15:02:31 volumio volumio[1048]: info: Getting Tidal Cloud Configuration Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 15:02:31 volumio volumio[1048]: info: Getting Qobuz Cloud Configuration Apr 19 15:02:31 volumio volumio[1048]: info: Asking plugin for UI Config Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 15:02:31 volumio volumio[1048]: info: Getting Spotify Cloud Configuration Apr 19 15:02:31 volumio volumio[1048]: info: Asking plugin for UI Config Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 15:02:31 volumio volumio[1048]: info: Saving Spotify Acccount Apr 19 15:02:31 volumio volumio[1048]: info: Got Tidal Cloud Configuration Apr 19 15:02:31 volumio volumio[1048]: info: Got it Apr 19 15:02:31 volumio volumio[1048]: info: Got it Apr 19 15:02:31 volumio volumio[1048]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 19 15:02:31 volumio volumio[1048]: info: VolumeController:: Volume=0 Mute =false Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Apr 19 15:02:31 volumio volumio[1048]: info: CoreStateMachine::pushState Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Apr 19 15:02:31 volumio volumio[1048]: info: PeppyMeterBasic ---peppymeterbasic status Apr 19 15:02:31 volumio volumio[1048]: info: PeppySpectrum ---peppyspectrum status Apr 19 15:02:31 volumio volumio[1048]: info: [ASDebug] CurState: PrevState: undefined Apr 19 15:02:31 volumio volumio[1048]: info: [ASDebug] Togle GPIO: ON Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 15:02:31 volumio volumio[1048]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 19 15:02:31 volumio volumio[1048]: info: Asound.conf file unchanged, so no further update is needed Apr 19 15:02:31 volumio volumio[1048]: info: Output device has changed, restarting MPD Apr 19 15:02:31 volumio sudo[1774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 15:02:31 volumio sudo[1774]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:31 volumio sudo[1774]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:31 volumio volumio[1048]: info: Output device has changed, restarting Shairport Sync Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 15:02:31 volumio sudo[1777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 15:02:31 volumio sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:31 volumio systemd[1]: Stopping Music Player Daemon... Apr 19 15:02:31 volumio volumio[1048]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 19 15:02:31 volumio volumio[1048]: info: MPD Permissions set Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 15:02:31 volumio volumio[1048]: info: Starting Shairport Sync Apr 19 15:02:32 volumio sudo[1787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 19 15:02:32 volumio sudo[1787]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 19 15:02:32 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 19 15:02:32 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 19 15:02:32 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Apr 19 15:02:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 19 15:02:32 volumio sudo[1787]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:32 volumio volumio[1048]: info: Shairport-Sync Started Apr 19 15:02:32 volumio systemd[1]: mpd.service: Succeeded. Apr 19 15:02:32 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 15:02:32 volumio systemd[1]: Starting Music Player Daemon... Apr 19 15:02:32 volumio sudo[1790]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 15:02:32 volumio sudo[1790]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:32 volumio sudo[1790]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:33 volumio mpd[1808]: Apr 19 15:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 19 15:02:33 volumio systemd[1]: Started Music Player Daemon. Apr 19 15:02:33 volumio sudo[1777]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:33 volumio volumio[1048]: error: updateQueue error: null Apr 19 15:02:34 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Apr 19 15:02:34 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Apr 19 15:02:34 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Apr 19 15:02:34 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Apr 19 15:02:34 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Apr 19 15:02:35 volumio volumio[1048]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 19 15:02:35 volumio volumio[1048]: info: CoreStateMachine::getcurrentVolume Apr 19 15:02:35 volumio volumio[1048]: info: CoreCommandRouter::volumioRetrievevolume Apr 19 15:02:35 volumio volumio[1048]: info: VolumeController:: Volume=0 Mute =false Apr 19 15:02:35 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Apr 19 15:02:35 volumio volumio[1048]: info: CoreStateMachine::pushState Apr 19 15:02:35 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 15:02:35 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Apr 19 15:02:35 volumio volumio[1048]: info: CoreStateMachine::updateTrackBlock Apr 19 15:02:35 volumio volumio[1048]: info: CorePlayQueue::getTrackBlock Apr 19 15:02:35 volumio volumio[1048]: info: CoreCommandRouter::volumioRetrievevolume Apr 19 15:02:35 volumio volumio[1048]: info: PeppyMeterBasic ---peppymeterbasic status Apr 19 15:02:35 volumio volumio[1048]: info: PeppySpectrum ---peppyspectrum status Apr 19 15:02:35 volumio volumio[1048]: info: [ASDebug] CurState: PrevState: Apr 19 15:02:35 volumio volumio[1048]: info: VolumeController:: Volume=0 Mute =false Apr 19 15:02:35 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Apr 19 15:02:35 volumio volumio[1048]: info: CoreStateMachine::pushState Apr 19 15:02:35 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Apr 19 15:02:35 volumio volumio[1048]: info: PeppyMeterBasic ---peppymeterbasic status undefined Apr 19 15:02:35 volumio volumio[1048]: info: PeppySpectrum ---peppyspectrum status undefined Apr 19 15:02:35 volumio volumio[1048]: info: [ASDebug] CurState: undefined PrevState: Apr 19 15:02:35 volumio volumio[1048]: info: [ASDebug] Togle GPIO: ON Apr 19 15:02:36 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Apr 19 15:02:36 volumio volumio[1048]: info: CoreCommandRouter::Reload Ui Apr 19 15:02:36 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Apr 19 15:02:36 volumio volumio[1048]: info: Wizard terminated Successfully Apr 19 15:02:36 volumio volumio[1048]: info: CoreCommandRouter::volumiosetStartupVolume Apr 19 15:02:36 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 15:02:37 volumio sudo[1823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 15:02:37 volumio sudo[1823]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:37 volumio sudo[1823]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:37 volumio sudo[1826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 15:02:37 volumio sudo[1826]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:37 volumio sudo[1826]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:37 volumio volumio[1048]: verbose: New Socket.io Connection to 192.168.0.51 from 192.168.0.26 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 9 Apr 19 15:02:37 volumio sudo[1829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 15:02:37 volumio sudo[1829]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:37 volumio sudo[1829]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:37 volumio sudo[1832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 15:02:37 volumio sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:02:37 volumio sudo[1832]: pam_unix(sudo:session): session closed for user root Apr 19 15:02:37 volumio volumio[1048]: verbose: New Socket.io Connection to 192.168.0.51 from 192.168.0.26 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 10 Apr 19 15:02:37 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 15:02:37 volumio volumio[1048]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 15:02:37 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 15:02:37 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Apr 19 15:02:37 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 19 15:02:37 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 19 15:02:37 volumio volumio[1048]: info: Received Get System Info Apr 19 15:02:37 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 15:02:37 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 15:02:37 volumio volumio[1048]: info: Discovery: Getting this device information Apr 19 15:02:37 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Apr 19 15:02:37 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 15:02:37 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Apr 19 15:02:37 volumio volumio[1048]: info: Listing playlists Apr 19 15:02:37 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 19 15:02:37 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 15:02:37 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 19 15:02:38 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Apr 19 15:02:39 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 19 15:02:39 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 15:02:39 volumio volumio[1048]: info: Received Get System Info Apr 19 15:02:39 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 15:02:39 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 15:02:39 volumio volumio[1048]: info: Discovery: Getting this device information Apr 19 15:02:39 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Apr 19 15:02:39 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 15:02:40 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 15:02:40 volumio volumio[1048]: info: Received Get System Info Apr 19 15:02:40 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 15:02:40 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 15:02:40 volumio volumio[1048]: info: Discovery: Getting this device information Apr 19 15:02:40 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Apr 19 15:02:40 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 15:02:42 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Apr 19 15:02:43 volumio volumio[1048]: info: CoreCommandRouter::volumioPlay Apr 19 15:02:43 volumio volumio[1048]: info: CoreStateMachine::play index undefined Apr 19 15:02:43 volumio volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 15:02:43 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Apr 19 15:02:43 volumio volumio[1048]: info: CoreStateMachine::startPlaybackTimer Apr 19 15:02:43 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Apr 19 15:02:43 volumio volumio[1048]: info: [1745089363411] ControllerWebradio::clearAddPlayTrack Apr 19 15:02:43 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 15:02:43 volumio volumio[1048]: info: sendMpdCommand stop took 1 milliseconds Apr 19 15:02:43 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 15:02:43 volumio volumio[1048]: info: Apr 19 15:02:43 volumio volumio[1048]: ---------------------------- MPD announces system playlist update Apr 19 15:02:43 volumio volumio[1048]: info: Ignoring MPD Status Update Apr 19 15:02:43 volumio volumio[1048]: info: sendMpdCommand clear took 2 milliseconds Apr 19 15:02:43 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand load "https://playerservices.streamtheworld.com/pls/CHOMFM.pls?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0NTA4OTA5MCwiaXNzIjoidGlzcnYifQ.naIg6VaI-qKNNi7eLmLX2rXm9hTAcUwpm6xCSbPHvME" Apr 19 15:02:43 volumio volumio[1048]: error: updateQueue error: null Apr 19 15:02:43 volumio volumio[1048]: info: ------------------------------ 4ms Apr 19 15:02:43 volumio volumio[1048]: info: sendMpdCommand load "https://playerservices.streamtheworld.com/pls/CHOMFM.pls?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0NTA4OTA5MCwiaXNzIjoidGlzcnYifQ.naIg6VaI-qKNNi7eLmLX2rXm9hTAcUwpm6xCSbPHvME" took 382 milliseconds Apr 19 15:02:43 volumio volumio[1048]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 15:02:43 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand play Apr 19 15:02:43 volumio volumio[1048]: info: Apr 19 15:02:43 volumio volumio[1048]: ---------------------------- MPD announces system playlist update Apr 19 15:02:43 volumio volumio[1048]: info: Ignoring MPD Status Update Apr 19 15:02:43 volumio volumio[1048]: info: sendMpdCommand play took 4 milliseconds Apr 19 15:02:43 volumio volumio[1048]: info: ------------------------------ 3ms Apr 19 15:02:45 volumio volumio[1048]: info: Apr 19 15:02:45 volumio volumio[1048]: ---------------------------- MPD announces state update: player Apr 19 15:02:45 volumio volumio[1048]: info: ControllerMpd::getState Apr 19 15:02:45 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Apr 19 15:02:45 volumio volumio[1048]: info: Apr 19 15:02:45 volumio volumio[1048]: ---------------------------- MPD announces state update: player Apr 19 15:02:45 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 19 15:02:45 volumio volumio[1048]: info: sendMpdCommand status took 29 milliseconds Apr 19 15:02:45 volumio volumio[1048]: info: ControllerMpd::getState Apr 19 15:02:45 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Apr 19 15:02:45 volumio volumio[1048]: verbose: ControllerMpd::parseState Apr 19 15:02:45 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 15:02:45 volumio volumio[1048]: info: sendMpdCommand clearerror took 4 milliseconds Apr 19 15:02:45 volumio volumio[1048]: info: sendMpdCommand status took 3 milliseconds Apr 19 15:02:45 volumio volumio[1048]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 19 15:02:45 volumio volumio[1048]: verbose: ControllerMpd::parseState Apr 19 15:02:45 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 15:02:45 volumio volumio[1048]: verbose: ControllerMpd::parseTrackInfo Apr 19 15:02:45 volumio volumio[1048]: info: ControllerMpd::pushState Apr 19 15:02:45 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Apr 19 15:02:45 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Apr 19 15:02:45 volumio volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CHOMFM_SC","artist":null,"album":null,"uri":"https://22823.live.streamtheworld.com:443/CHOMFM_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0NTA4OTA5MCwiaXNzIjoidGlzcnYifQ.naIg6VaI-qKNNi7eLmLX2rXm9hTAcUwpm6xCSbPHvME&gdpr=0","trackType":""} Apr 19 15:02:45 volumio volumio[1048]: verbose: CURRENT POSITION 0 Apr 19 15:02:45 volumio volumio[1048]: info: CoreStateMachine::syncState stateService pause Apr 19 15:02:45 volumio volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Apr 19 15:02:45 volumio volumio[1048]: info: CoreStateMachine::pushState Apr 19 15:02:45 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 15:02:45 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Apr 19 15:02:45 volumio volumio[1048]: info: ------------------------------ 56ms Apr 19 15:02:45 volumio volumio[1048]: info: PeppyMeterBasic ---peppymeterbasic status Apr 19 15:02:45 volumio volumio[1048]: info: PeppySpectrum ---peppyspectrum status Apr 19 15:02:45 volumio volumio[1048]: info: [ASDebug] CurState: PrevState: undefined Apr 19 15:02:45 volumio volumio[1048]: info: [ASDebug] Togle GPIO: ON Apr 19 15:02:45 volumio volumio[1048]: info: sendMpdCommand playlistinfo took 35 milliseconds Apr 19 15:02:45 volumio volumio[1048]: verbose: ControllerMpd::parseTrackInfo Apr 19 15:02:45 volumio volumio[1048]: info: ControllerMpd::pushState Apr 19 15:02:45 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Apr 19 15:02:45 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Apr 19 15:02:45 volumio volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CHOMFM_SC","artist":null,"album":null,"uri":"https://22823.live.streamtheworld.com:443/CHOMFM_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0NTA4OTA5MCwiaXNzIjoidGlzcnYifQ.naIg6VaI-qKNNi7eLmLX2rXm9hTAcUwpm6xCSbPHvME&gdpr=0","trackType":""} Apr 19 15:02:45 volumio volumio[1048]: verbose: CURRENT POSITION 0 Apr 19 15:02:45 volumio volumio[1048]: info: CoreStateMachine::syncState stateService pause Apr 19 15:02:45 volumio volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Apr 19 15:02:45 volumio volumio[1048]: info: CoreStateMachine::pushState Apr 19 15:02:45 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 15:02:45 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Apr 19 15:02:45 volumio volumio[1048]: info: ------------------------------ 82ms Apr 19 15:02:45 volumio volumio[1048]: info: PeppyMeterBasic ---peppymeterbasic status Apr 19 15:02:45 volumio volumio[1048]: info: PeppySpectrum ---peppyspectrum status Apr 19 15:02:45 volumio volumio[1048]: info: [ASDebug] CurState: PrevState: Apr 19 15:02:47 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 19 15:02:49 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 15:02:49 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 19 15:02:52 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 19 15:02:52 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 15:02:52 volumio volumio-remote-updater[590]: No test mode Apr 19 15:02:52 volumio volumio-remote-updater[590]: No alpha test mode Apr 19 15:02:52 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 19 15:02:52 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 19 15:02:52 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 15:02:52 volumio volumio[1048]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 19 15:02:52 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 19 15:02:52 volumio systemd[1]: systemd-timedated.service: Succeeded. Apr 19 15:02:52 volumio volumio-remote-updater[590]: No test mode Apr 19 15:02:52 volumio volumio-remote-updater[590]: No alpha test mode Apr 19 15:02:52 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 19 15:02:52 volumio volumio[1048]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 19 15:02:52 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 19 15:02:56 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 15:02:56 volumio volumio[1048]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 19 15:02:56 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 19 15:02:56 volumio volumio[1048]: info: Received Get System Version Apr 19 15:02:56 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 15:02:56 volumio volumio[1048]: info: Received Get System Info Apr 19 15:02:56 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 15:02:56 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 15:02:56 volumio volumio[1048]: info: Discovery: Getting this device information Apr 19 15:02:56 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Apr 19 15:02:56 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 15:02:57 volumio volumio[1048]: info: [ASDebug] Port: 24 Apr 19 15:02:57 volumio volumio[1048]: info: [ASDebug] Inverted: false Apr 19 15:02:57 volumio volumio[1048]: info: [ASDebug] Delay: 720 Apr 19 15:03:32 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Apr 19 15:03:32 volumio volumio[1048]: info: CoreCommandRouter::volumioGetQueue Apr 19 15:03:32 volumio volumio[1048]: info: CoreStateMachine::getQueue Apr 19 15:03:32 volumio volumio[1048]: info: CorePlayQueue::getQueue Apr 19 15:03:35 volumio volumio[1048]: info: Preload queue cleared Apr 19 15:03:35 volumio volumio[1048]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 19 15:03:35 volumio volumio[1048]: info: CoreStateMachine::ClearQueue Apr 19 15:03:35 volumio volumio[1048]: info: CoreStateMachine::stop Apr 19 15:03:35 volumio volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 15:03:35 volumio volumio[1048]: info: CorePlayQueue::clearPlayQueue Apr 19 15:03:35 volumio volumio[1048]: info: CorePlayQueue::saveQueue Apr 19 15:03:35 volumio volumio[1048]: info: CoreCommandRouter::volumioPushQueue Apr 19 15:03:35 volumio volumio[1048]: info: CoreStateMachine::addQueueItems Apr 19 15:03:35 volumio volumio[1048]: info: CorePlayQueue::addQueueItems Apr 19 15:03:35 volumio volumio[1048]: info: Preload queue cleared Apr 19 15:03:35 volumio volumio[1048]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s7707 Apr 19 15:03:35 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 19 15:03:35 volumio volumio[1048]: info: CoreCommandRouter::volumioPushQueue Apr 19 15:03:35 volumio volumio[1048]: info: CorePlayQueue::saveQueue Apr 19 15:03:35 volumio volumio[1048]: info: CoreStateMachine::updateTrackBlock Apr 19 15:03:35 volumio volumio[1048]: info: CorePlayQueue::getTrackBlock Apr 19 15:03:35 volumio volumio[1048]: info: CoreCommandRouter::volumioPlay Apr 19 15:03:35 volumio volumio[1048]: info: CoreStateMachine::play index 0 Apr 19 15:03:35 volumio volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 15:03:35 volumio volumio[1048]: info: CoreStateMachine::stop Apr 19 15:03:35 volumio volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 15:03:35 volumio volumio[1048]: info: CoreStateMachine::play index undefined Apr 19 15:03:35 volumio volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 15:03:35 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:35 volumio volumio[1048]: info: CoreStateMachine::startPlaybackTimer Apr 19 15:03:35 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:35 volumio volumio[1048]: info: [1745089415369] ControllerWebradio::clearAddPlayTrack Apr 19 15:03:35 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 15:03:35 volumio volumio[1048]: info: sendMpdCommand stop took 109 milliseconds Apr 19 15:03:35 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 15:03:35 volumio volumio[1048]: info: Apr 19 15:03:35 volumio volumio[1048]: ---------------------------- MPD announces state update: player Apr 19 15:03:35 volumio volumio[1048]: info: Apr 19 15:03:35 volumio volumio[1048]: ---------------------------- MPD announces system playlist update Apr 19 15:03:35 volumio volumio[1048]: info: Ignoring MPD Status Update Apr 19 15:03:35 volumio volumio[1048]: info: ControllerMpd::getState Apr 19 15:03:35 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Apr 19 15:03:35 volumio volumio[1048]: info: sendMpdCommand clear took 2 milliseconds Apr 19 15:03:35 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand load "http://stream2.statsradio.com:8110/stream" Apr 19 15:03:35 volumio volumio[1048]: error: updateQueue error: null Apr 19 15:03:35 volumio volumio[1048]: info: sendMpdCommand status took 4 milliseconds Apr 19 15:03:35 volumio volumio[1048]: info: ------------------------------ 5ms Apr 19 15:03:35 volumio volumio[1048]: verbose: ControllerMpd::parseState Apr 19 15:03:35 volumio volumio[1048]: info: ControllerMpd::pushState Apr 19 15:03:35 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Apr 19 15:03:35 volumio volumio[1048]: info: CoreStateMachine::pushState Apr 19 15:03:35 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:35 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 15:03:35 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:35 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:35 volumio volumio[1048]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 19 15:03:35 volumio volumio[1048]: info: ------------------------------ 26ms Apr 19 15:03:35 volumio volumio[1048]: info: PeppyMeterBasic ---peppymeterbasic status stop Apr 19 15:03:35 volumio volumio[1048]: info: PeppySpectrum ---peppyspectrum status stop Apr 19 15:03:35 volumio sudo[1982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 19 15:03:35 volumio sudo[1982]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:35 volumio volumio[1048]: info: [ASDebug] CurState: stop PrevState: Apr 19 15:03:35 volumio volumio[1048]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 19 15:03:35 volumio sudo[1985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 19 15:03:35 volumio sudo[1985]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:35 volumio sudo[1982]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:35 volumio volumio[1048]: info: peppymeterbasic Daemon Stop Apr 19 15:03:35 volumio sudo[1985]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:35 volumio volumio[1048]: info: peppyspectrum Daemon Stop Apr 19 15:03:36 volumio volumio[1048]: info: [ASDebug] Togle GPIO: OFF Apr 19 15:03:36 volumio volumio[1048]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 15:03:36 volumio volumio[1048]: Error: EBADF: bad file descriptor, write Apr 19 15:03:36 volumio volumio[1048]: at Object.writeSync (fs.js:711:3) Apr 19 15:03:36 volumio volumio[1048]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Apr 19 15:03:36 volumio volumio[1048]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Apr 19 15:03:36 volumio volumio[1048]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Apr 19 15:03:36 volumio volumio[1048]: at listOnTimeout (internal/timers.js:554:17) Apr 19 15:03:36 volumio volumio[1048]: at processTimers (internal/timers.js:497:7) { Apr 19 15:03:36 volumio volumio[1048]: errno: -9, Apr 19 15:03:36 volumio volumio[1048]: syscall: 'write', Apr 19 15:03:36 volumio volumio[1048]: code: 'EBADF' Apr 19 15:03:36 volumio volumio[1048]: } Apr 19 15:03:36 volumio volumio[1048]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 15:03:36 volumio sudo[1996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 15:02 Apr 19 15:03:36 volumio sudo[1996]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"