Feb 09 20:38:35 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:35 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:35 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:38:35 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:35 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:35 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:35 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:35 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:35 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:35 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:35 volumio shairport-sync[2222]: {"time":1770698226143,"response":"startAirplayPlayback Success"} Feb 09 20:38:35 volumio shairport-sync[2624]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 09 20:38:35 volumio shairport-sync[2624]: Dload Upload Total Spent Left Speed Feb 09 20:38:35 volumio volumio[1274]: info: Feb 09 20:38:35 volumio volumio[1274]: ---------------------------- Client requests Stop Airplay Playback Feb 09 20:38:35 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Feb 09 20:38:35 volumio volumio[1274]: info: Airplay playback stop Feb 09 20:38:35 volumio volumio[1274]: info: Airplay Playback Stopped, clearing state Feb 09 20:38:35 volumio volumio[1274]: info: CoreStateMachine::resetVolumioState Feb 09 20:38:35 volumio volumio[1274]: info: CoreStateMachine::getcurrentVolume Feb 09 20:38:35 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 09 20:38:35 volumio shairport-sync[2624]: [158B blob data] Feb 09 20:38:35 volumio volumio[1274]: info: VolumeController:: Volume=33 Mute =false Feb 09 20:38:35 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:35 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:35 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Feb 09 20:38:35 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Feb 09 20:38:35 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 09 20:38:35 volumio volumio[1274]: info: VolumeController:: Volume=33 Mute =false Feb 09 20:38:35 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:35 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:45 volumio volumio[1274]: info: Airplay started streaming, receiving metadatas Feb 09 20:38:45 volumio volumio[1274]: info: Airplay started streaming, receiving metadatas Feb 09 20:38:45 volumio volumio[1274]: info: Airplay started streaming, receiving metadatas Feb 09 20:38:45 volumio shairport-sync[2624]: {"time":1770698315937,"response":"stopAirplayPlayback Success"} Feb 09 20:38:45 volumio shairport-sync[2671]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 09 20:38:45 volumio shairport-sync[2671]: Dload Upload Total Spent Left Speed Feb 09 20:38:45 volumio volumio[1274]: info: Feb 09 20:38:45 volumio volumio[1274]: ---------------------------- Client requests Start Airplay PlaybackRoutine Feb 09 20:38:45 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Feb 09 20:38:45 volumio volumio[1274]: info: Airplay playback start Feb 09 20:38:45 volumio shairport-sync[2671]: [158B blob data] Feb 09 20:38:46 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:46 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:46 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:38:46 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:46 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:46 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:46 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:46 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:46 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:46 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:46 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:46 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:46 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:47 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:47 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:47 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:38:47 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:47 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:47 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:47 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:47 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:47 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:47 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:47 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:47 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:47 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:38:47 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:47 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:47 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:47 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:47 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:47 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:47 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:58 volumio volumio[1274]: verbose: New Socket.io Connection to 192.168.1.7 from 192.168.1.3 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AB%A5%E5%AE%89%E6%A0%BC/%E7%AB%A5%E6%A8%82%E6%9C%83/f3437be6-dc67-437e-be41-4f07fe9863d9.jpg' Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioGetVisibleSources Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 09 20:38:59 volumio volumio[1274]: info: Received Get System Info Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 20:38:59 volumio volumio[1274]: info: Discovery: Getting this device information Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 09 20:38:59 volumio volumio[1274]: info: Listing playlists Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AB%A5%E5%AE%89%E6%A0%BC/%E7%AB%A5%E6%A8%82%E6%9C%83/f3437be6-dc67-437e-be41-4f07fe9863d9.jpg' Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AB%A5%E5%AE%89%E6%A0%BC/%E7%AB%A5%E6%A8%82%E6%9C%83/f3437be6-dc67-437e-be41-4f07fe9863d9.jpg' Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:38:59 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:00 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:01 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:03 volumio volumio[1274]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 09 20:39:03 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 09 20:39:03 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 20:39:03 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 09 20:39:03 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:39:03 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:39:03 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:39:03 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:39:03 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:39:03 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:39:03 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:39:03 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 09 20:39:03 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 09 20:39:07 volumio volumio[1274]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 09 20:39:07 volumio volumio[1274]: info: Preparing to save Alsa Options, stopping services first Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::volumioPause Feb 09 20:39:07 volumio volumio[1274]: info: CoreStateMachine::pause Feb 09 20:39:07 volumio volumio[1274]: info: CoreStateMachine::stPlaybackTimer Feb 09 20:39:07 volumio volumio[1274]: info: CoreStateMachine::servicePause Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::servicePause Feb 09 20:39:07 volumio volumio[1274]: info: Airplay Pause with DBUS Call Feb 09 20:39:07 volumio volumio[1274]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"xCORE USB Audio 2.0"}} Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 09 20:39:07 volumio volumio[1274]: info: Setting mixer xCORE USB Audio 2.0 for card xCORE USB Audio 2.0 Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 09 20:39:07 volumio volumio[1274]: info: Updating Volume Controller Parameters: Device: 5 Name: xCORE USB Audio 2.0 Mixer: xCORE USB Audio 2.0 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 09 20:39:07 volumio volumio[1274]: info: Disabling external Volume Control Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 09 20:39:07 volumio volumio[1274]: info: Preparing to generate the ALSA configuration file Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 09 20:39:07 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 09 20:39:07 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:07 volumio volumio[1274]: info: Asound.conf file written Feb 09 20:39:07 volumio sudo[2761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 09 20:39:07 volumio sudo[2761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 20:39:07 volumio sudo[2761]: pam_unix(sudo:session): session closed for user root Feb 09 20:39:07 volumio volumio[1274]: No state is present for card x20 Feb 09 20:39:07 volumio volumio[1274]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:0002" "" "" Feb 09 20:39:07 volumio volumio[1274]: Hardware is initialized using a generic method Feb 09 20:39:07 volumio volumio[1274]: No state is present for card x20 Feb 09 20:39:07 volumio volumio[1274]: info: Output device has changed, restarting MPD Feb 09 20:39:07 volumio sudo[2767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 09 20:39:07 volumio volumio[1274]: info: Output device has changed, restarting Shairport Sync Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 20:39:07 volumio sudo[2767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 20:39:07 volumio sudo[2767]: pam_unix(sudo:session): session closed for user root Feb 09 20:39:07 volumio sudo[2769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 09 20:39:07 volumio sudo[2769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 20:39:07 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 09 20:39:07 volumio volumio[1274]: info: Output device has changed, restarting MPD Feb 09 20:39:07 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 09 20:39:07 volumio volumio[1274]: info: Output device has changed, restarting Shairport Sync Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 20:39:07 volumio sudo[2778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 09 20:39:07 volumio sudo[2778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 20:39:07 volumio sudo[2778]: pam_unix(sudo:session): session closed for user root Feb 09 20:39:07 volumio sudo[2779]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 09 20:39:07 volumio sudo[2779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 20:39:07 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 09 20:39:07 volumio volumio[1274]: info: MPD Permissions set Feb 09 20:39:07 volumio volumio[1274]: info: MPD Permissions set Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:39:07 volumio volumio[1274]: info: Starting Shairport Sync Feb 09 20:39:07 volumio volumio[1274]: info: Starting Shairport Sync Feb 09 20:39:07 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 09 20:39:07 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 09 20:39:07 volumio systemd[1]: mpd.service: Consumed 1.109s CPU time. Feb 09 20:39:07 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 09 20:39:07 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 09 20:39:07 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 09 20:39:07 volumio sudo[2803]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 09 20:39:07 volumio sudo[2803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 20:39:07 volumio sudo[2806]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 09 20:39:07 volumio sudo[2806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::volumioPause Feb 09 20:39:07 volumio volumio[1274]: info: CoreStateMachine::pause Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:07 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:07 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Feb 09 20:39:07 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 09 20:39:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 09 20:39:07 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 09 20:39:07 volumio shairport-sync[2671]: {"time":1770698325897,"response":"startAirplayPlayback Success"} Feb 09 20:39:07 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 09 20:39:07 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 09 20:39:07 volumio systemd[1]: shairport-sync.service: Consumed 4.060s CPU time. Feb 09 20:39:07 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 09 20:39:07 volumio sudo[2803]: pam_unix(sudo:session): session closed for user root Feb 09 20:39:07 volumio sudo[2806]: pam_unix(sudo:session): session closed for user root Feb 09 20:39:07 volumio volumio[1274]: info: Shairport-Sync Started Feb 09 20:39:07 volumio volumio[1274]: info: Shairport-Sync Started Feb 09 20:39:07 volumio sudo[2809]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 09 20:39:07 volumio sudo[2809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 09 20:39:07 volumio sudo[2809]: pam_unix(sudo:session): session closed for user root Feb 09 20:39:07 volumio volumio[1274]: info: VolumeController::SetAlsaVolume105 Feb 09 20:39:07 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:07 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:08 volumio mpd[2826]: 2026-02-09T20:39:08 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 09 20:39:08 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 09 20:39:08 volumio sudo[2779]: pam_unix(sudo:session): session closed for user root Feb 09 20:39:08 volumio sudo[2769]: pam_unix(sudo:session): session closed for user root Feb 09 20:39:08 volumio volumio[1274]: error: MPD error: The expression evaluated to a falsy value: Feb 09 20:39:08 volumio volumio[1274]: assert.ok(self.idling) Feb 09 20:39:08 volumio volumio[1274]: error: The expression evaluated to a falsy value: Feb 09 20:39:08 volumio volumio[1274]: assert.ok(self.idling) Feb 09 20:39:08 volumio volumio[1274]: error: updateQueue error: null Feb 09 20:39:11 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 09 20:39:11 volumio volumio[1274]: info: CoreStateMachine::getcurrentVolume Feb 09 20:39:11 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 09 20:39:11 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 09 20:39:11 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:39:11 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:11 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Feb 09 20:39:11 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Feb 09 20:39:11 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 09 20:39:11 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 09 20:39:11 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 09 20:39:11 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 09 20:39:22 volumio volumio[1274]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 20:39:22 volumio volumio[1274]: Error: connect ETIMEDOUT 104.23.125.189:443 Feb 09 20:39:22 volumio volumio[1274]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 09 20:39:22 volumio volumio[1274]: errno: -110, Feb 09 20:39:22 volumio volumio[1274]: code: 'ETIMEDOUT', Feb 09 20:39:22 volumio volumio[1274]: syscall: 'connect', Feb 09 20:39:22 volumio volumio[1274]: address: '104.23.125.189', Feb 09 20:39:22 volumio volumio[1274]: port: 443 Feb 09 20:39:22 volumio volumio[1274]: } Feb 09 20:39:22 volumio volumio[1274]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 20:39:22 volumio sudo[2879]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-09 20:38' Feb 09 20:39:22 volumio sudo[2879]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:38:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00e933ebe3692ad8aea9910d2289bf23"