-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Tue 2025-06-03 15:49:13 CEST. -- Jun 03 15:48:01 volumio wpa_supplicant[4341]: wlan0: Failed to initiate sched scan Jun 03 15:48:03 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:03 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:03 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:03 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:03 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:03 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:03 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:03 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:03 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:03 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:03 volumio shairport-sync[1206]: {"time":1748958470621,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Jun 03 15:48:03 volumio shairport-sync[1206]: Dload Upload Total Spent Left Speed Jun 03 15:48:03 volumio volumio[987]: info: Jun 03 15:48:03 volumio volumio[987]: ---------------------------- Client requests Stop Airplay Playback Jun 03 15:48:03 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Jun 03 15:48:03 volumio volumio[987]: info: Airplay playback stop Jun 03 15:48:03 volumio volumio[987]: info: Airplay Playback Stopped, clearing state Jun 03 15:48:03 volumio volumio[987]: info: CoreStateMachine::resetVolumioState Jun 03 15:48:03 volumio volumio[987]: info: CoreStateMachine::getcurrentVolume Jun 03 15:48:03 volumio volumio[987]: info: CoreCommandRouter::volumioRetrievevolume Jun 03 15:48:03 volumio shairport-sync[1206]: [158B blob data] Jun 03 15:48:03 volumio volumio[987]: Unable to parse: Jun 03 15:48:03 volumio volumio[987]: Simple mixer control 'PCM',0 Jun 03 15:48:03 volumio volumio[987]: Capabilities: pswitch pswitch-joined Jun 03 15:48:03 volumio volumio[987]: Playback channels: Mono Jun 03 15:48:03 volumio volumio[987]: Mono: Playback [on] Jun 03 15:48:03 volumio volumio[987]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 03 15:48:03 volumio volumio[987]: Unable to parse: Jun 03 15:48:03 volumio volumio[987]: Simple mixer control 'PCM',0 Jun 03 15:48:03 volumio volumio[987]: Capabilities: pswitch pswitch-joined Jun 03 15:48:03 volumio volumio[987]: Playback channels: Mono Jun 03 15:48:03 volumio volumio[987]: Mono: Playback [on] Jun 03 15:48:03 volumio volumio[987]: info: VolumeController:: Volume=undefined Mute =false Jun 03 15:48:03 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:03 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:03 volumio volumio[987]: info: CoreStateMachine::updateTrackBlock Jun 03 15:48:03 volumio volumio[987]: info: CorePlayQueue::getTrackBlock Jun 03 15:48:03 volumio volumio[987]: info: CoreCommandRouter::volumioRetrievevolume Jun 03 15:48:03 volumio volumio[987]: Unable to parse: Jun 03 15:48:03 volumio volumio[987]: Simple mixer control 'PCM',0 Jun 03 15:48:03 volumio volumio[987]: Capabilities: pswitch pswitch-joined Jun 03 15:48:03 volumio volumio[987]: Playback channels: Mono Jun 03 15:48:03 volumio volumio[987]: Mono: Playback [on] Jun 03 15:48:03 volumio volumio[987]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 03 15:48:03 volumio volumio[987]: Unable to parse: Jun 03 15:48:03 volumio volumio[987]: Simple mixer control 'PCM',0 Jun 03 15:48:03 volumio volumio[987]: Capabilities: pswitch pswitch-joined Jun 03 15:48:03 volumio volumio[987]: Playback channels: Mono Jun 03 15:48:03 volumio volumio[987]: Mono: Playback [on] Jun 03 15:48:03 volumio volumio[987]: info: VolumeController:: Volume=undefined Mute =false Jun 03 15:48:03 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:03 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:04 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 15:48:04 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 15:48:04 volumio volumio[987]: info: Discovery: Getting this device information Jun 03 15:48:04 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 03 15:48:04 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 15:48:04 volumio volumio[987]: verbose: New Socket.io Connection to 192.168.68.53:3000 from 192.168.68.50 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Jun 03 15:48:04 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 03 15:48:04 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 03 15:48:04 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 03 15:48:05 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 03 15:48:05 volumio volumio[987]: info: Airplay started streaming, receiving metadatas Jun 03 15:48:05 volumio shairport-sync[1206]: {"time":1748958483080,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Jun 03 15:48:05 volumio shairport-sync[1206]: Dload Upload Total Spent Left Speed Jun 03 15:48:05 volumio volumio[987]: info: Jun 03 15:48:05 volumio volumio[987]: ---------------------------- Client requests Start Airplay PlaybackRoutine Jun 03 15:48:05 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Jun 03 15:48:05 volumio volumio[987]: info: Airplay playback start Jun 03 15:48:05 volumio shairport-sync[1206]: [158B blob data] Jun 03 15:48:05 volumio sudo[23128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 03 15:48:05 volumio sudo[23128]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:05 volumio sudo[23128]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:05 volumio sudo[23130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 03 15:48:05 volumio sudo[23130]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:05 volumio sudo[23130]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:05 volumio volumio[987]: verbose: New Socket.io Connection to 192.168.68.53 from 192.168.68.50 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Jun 03 15:48:05 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 03 15:48:05 volumio sudo[23134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 03 15:48:05 volumio sudo[23134]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:05 volumio sudo[23134]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:05 volumio sudo[23136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 03 15:48:05 volumio sudo[23136]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:05 volumio sudo[23136]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:05 volumio volumio[987]: verbose: New Socket.io Connection to 192.168.68.53 from 192.168.68.50 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Jun 03 15:48:05 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 03 15:48:05 volumio volumio[987]: info: CoreCommandRouter::volumioGetVisibleSources Jun 03 15:48:05 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 15:48:05 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 03 15:48:05 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 03 15:48:05 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 03 15:48:05 volumio volumio[987]: info: Received Get System Info Jun 03 15:48:05 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 15:48:05 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 15:48:05 volumio volumio[987]: info: Discovery: Getting this device information Jun 03 15:48:05 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 03 15:48:05 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 15:48:05 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 03 15:48:05 volumio volumio[987]: info: Listing playlists Jun 03 15:48:05 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 03 15:48:05 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 03 15:48:06 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:06 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:06 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:06 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:06 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:06 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:06 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:06 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:06 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:06 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:06 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:06 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:06 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:07 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:07 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:07 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:07 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:07 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:07 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:07 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:07 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:07 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:07 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:07 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:07 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 03 15:48:07 volumio volumio[987]: info: Received Get System Info Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 15:48:07 volumio volumio[987]: info: Discovery: Getting this device information Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 03 15:48:07 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 15:48:08 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 15:48:08 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 15:48:08 volumio volumio[987]: info: Discovery: Getting this device information Jun 03 15:48:08 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 03 15:48:08 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:10 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:10 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:10 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:10 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:10 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:10 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:10 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:10 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:10 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:10 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:10 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:10 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:10 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:10 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:10 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:11 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:11 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:11 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:11 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:11 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:11 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:11 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:11 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:11 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:11 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:12 volumio volumio[987]: info: Updating volume from AirPlay: -18.12; 40% Jun 03 15:48:12 volumio volumio[987]: info: VolumeController::SetAlsaVolume40 Jun 03 15:48:12 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:12 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:12 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:13 volumio wpa_supplicant[4341]: wlan0: Failed to initiate sched scan Jun 03 15:48:15 volumio volumio[987]: info: Updating volume from AirPlay: -16.25; 46% Jun 03 15:48:15 volumio volumio[987]: info: VolumeController::SetAlsaVolume46 Jun 03 15:48:15 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:15 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:15 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:15 volumio volumio[987]: info: Updating volume from AirPlay: -14.37; 52% Jun 03 15:48:15 volumio volumio[987]: info: VolumeController::SetAlsaVolume52 Jun 03 15:48:15 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:15 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:15 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:15 volumio volumio[987]: info: Updating volume from AirPlay: -12.5; 58% Jun 03 15:48:15 volumio volumio[987]: info: VolumeController::SetAlsaVolume58 Jun 03 15:48:15 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:15 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:15 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:15 volumio volumio[987]: info: Updating volume from AirPlay: -10.62; 65% Jun 03 15:48:15 volumio volumio[987]: info: VolumeController::SetAlsaVolume65 Jun 03 15:48:15 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:15 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:15 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:15 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 03 15:48:16 volumio volumio[987]: info: Updating volume from AirPlay: -8.75; 71% Jun 03 15:48:16 volumio volumio[987]: info: VolumeController::SetAlsaVolume71 Jun 03 15:48:16 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:16 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:16 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:18 volumio volumio[987]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/D1MA/CLEOPATRA/2486da28-3f5c-41c5-bf2a-4e01f678a3a1.png' Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 03 15:48:20 volumio volumio[987]: info: Retrieving Cloud Streaming UI Jun 03 15:48:20 volumio volumio[987]: info: Getting Tidal Cloud Configuration Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 03 15:48:20 volumio volumio[987]: info: Getting Qobuz Cloud Configuration Jun 03 15:48:20 volumio volumio[987]: info: Asking plugin for UI Config Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 03 15:48:20 volumio volumio[987]: info: Getting Spotify Cloud Configuration Jun 03 15:48:20 volumio volumio[987]: info: Asking plugin for UI Config Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 03 15:48:20 volumio volumio[987]: info: Saving Spotify Acccount Jun 03 15:48:20 volumio volumio[987]: info: Got Tidal Cloud Configuration Jun 03 15:48:20 volumio volumio[987]: info: Got it Jun 03 15:48:20 volumio volumio[987]: info: Got it Jun 03 15:48:20 volumio volumio[987]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 03 15:48:20 volumio volumio[987]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::volumioGetBrowseSources Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::volumioGetBrowseSources Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::volumioGetBrowseSources Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 03 15:48:20 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 03 15:48:24 volumio wpa_supplicant[4341]: wlan0: Failed to initiate sched scan Jun 03 15:48:24 volumio volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 03 15:48:24 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 03 15:48:24 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 15:48:24 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 03 15:48:24 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 15:48:24 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 15:48:24 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 15:48:24 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 15:48:24 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 15:48:24 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 15:48:24 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 15:48:24 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 03 15:48:24 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 03 15:48:29 volumio volumio[987]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 03 15:48:29 volumio volumio[987]: info: Restoring Previous Volume level: 71 false true Jun 03 15:48:29 volumio volumio[987]: info: VolumeController::SetAlsaVolume100 Jun 03 15:48:29 volumio volumio[987]: info: Enable softmixer device for audio device number 5 Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::volumioStop Jun 03 15:48:29 volumio volumio[987]: info: CoreStateMachine::stop Jun 03 15:48:29 volumio volumio[987]: info: CoreStateMachine::serviceStop Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::serviceStop Jun 03 15:48:29 volumio volumio[987]: info: Airplay Stop Jun 03 15:48:29 volumio volumio[987]: info: Stopping Airplay Playback and sending pause command to client via USR2 Jun 03 15:48:29 volumio volumio[987]: info: Enable softmixer device for audio device undefined Jun 03 15:48:29 volumio sudo[23238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1206 Jun 03 15:48:29 volumio sudo[23238]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 03 15:48:29 volumio volumio[987]: info: Updating Volume Controller Parameters: Device: 5 Name: Schiit Modi 3 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 03 15:48:29 volumio sudo[23238]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 03 15:48:29 volumio volumio[987]: info: Disabling external Volume Control Jun 03 15:48:29 volumio volumio[987]: info: Output device has changed, restarting MPD Jun 03 15:48:29 volumio sudo[23243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 03 15:48:29 volumio sudo[23243]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:29 volumio volumio[987]: info: Output device has changed, restarting Shairport Sync Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 15:48:29 volumio sudo[23243]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:29 volumio sudo[23245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 03 15:48:29 volumio sudo[23245]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:29 volumio systemd[1]: Stopping Music Player Daemon... Jun 03 15:48:29 volumio volumio[987]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 03 15:48:29 volumio volumio[987]: info: Volume configurations have been set Jun 03 15:48:29 volumio systemd[1]: mpd.service: Succeeded. Jun 03 15:48:29 volumio systemd[1]: Stopped Music Player Daemon. Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 03 15:48:29 volumio volumio[987]: info: Updating Volume Controller Parameters: Device: 5 Name: Schiit Modi 3 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 03 15:48:29 volumio volumio[987]: info: Disabling external Volume Control Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 03 15:48:29 volumio systemd[1]: Starting Music Player Daemon... Jun 03 15:48:29 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:29 volumio volumio[987]: info: MPD Permissions set Jun 03 15:48:29 volumio volumio[987]: Unable to parse: Jun 03 15:48:29 volumio volumio[987]: Simple mixer control 'PCM',0 Jun 03 15:48:29 volumio volumio[987]: Capabilities: pswitch pswitch-joined Jun 03 15:48:29 volumio volumio[987]: Playback channels: Mono Jun 03 15:48:29 volumio volumio[987]: Mono: Playback [on] Jun 03 15:48:29 volumio volumio[987]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 03 15:48:29 volumio volumio[987]: info: Shairport-Sync paused with USR2 Jun 03 15:48:29 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:29 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:30 volumio volumio[987]: info: Software Volume ALSA configuration written Jun 03 15:48:30 volumio volumio[987]: info: Preparing to generate the ALSA configuration file Jun 03 15:48:30 volumio volumio[987]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 03 15:48:30 volumio volumio[987]: info: Reading ALSA contributions from plugins. Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:30 volumio sudo[23252]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 03 15:48:30 volumio sudo[23252]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:30 volumio sudo[23252]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 03 15:48:30 volumio volumio[987]: Unable to parse: Jun 03 15:48:30 volumio volumio[987]: Simple mixer control 'PCM',0 Jun 03 15:48:30 volumio volumio[987]: Capabilities: pswitch pswitch-joined Jun 03 15:48:30 volumio volumio[987]: Playback channels: Mono Jun 03 15:48:30 volumio volumio[987]: Mono: Playback [on] Jun 03 15:48:30 volumio volumio[987]: info: VolumeController:: Volume=undefined Mute =false Jun 03 15:48:30 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:30 volumio volumio[987]: info: Starting Shairport Sync Jun 03 15:48:30 volumio volumio[987]: info: VolumeController::SetAlsaVolume0 Jun 03 15:48:30 volumio sudo[23273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 03 15:48:30 volumio sudo[23273]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:30 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:30 volumio volumio[987]: info: Asound.conf file written Jun 03 15:48:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 03 15:48:30 volumio shairport-sync[1206]: {"time":1748958485203,"response":"startAirplayPlayback Success"} Jun 03 15:48:30 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 03 15:48:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 03 15:48:30 volumio sudo[23278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 03 15:48:30 volumio sudo[23278]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:30 volumio sudo[23278]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 03 15:48:30 volumio sudo[23273]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:30 volumio volumio[987]: No state is present for card b1 Jun 03 15:48:30 volumio volumio[987]: /usr/share/alsa/init/bcm2835:14: control element not found Jun 03 15:48:30 volumio volumio[987]: No state is present for card b1 Jun 03 15:48:30 volumio volumio[987]: No state is present for card Headphones Jun 03 15:48:30 volumio volumio[987]: /usr/share/alsa/init/bcm2835:20: control element not found Jun 03 15:48:30 volumio volumio[987]: No state is present for card Headphones Jun 03 15:48:30 volumio volumio[987]: No state is present for card S3 Jun 03 15:48:30 volumio volumio[987]: Found hardware: "USB-Audio" "USB Mixer" "USB0d8c:0066" "" "" Jun 03 15:48:30 volumio volumio[987]: Hardware is initialized using a generic method Jun 03 15:48:30 volumio volumio[987]: No state is present for card S3 Jun 03 15:48:30 volumio volumio[987]: info: Output device has changed, restarting MPD Jun 03 15:48:30 volumio sudo[23285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 03 15:48:30 volumio sudo[23285]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:30 volumio volumio[987]: info: Output device has changed, restarting Shairport Sync Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 15:48:30 volumio sudo[23285]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:30 volumio sudo[23287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 03 15:48:30 volumio sudo[23287]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:30 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 03 15:48:30 volumio systemd[1]: mpd.service: Succeeded. Jun 03 15:48:30 volumio systemd[1]: Stopped Music Player Daemon. Jun 03 15:48:30 volumio volumio[987]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 03 15:48:30 volumio systemd[1]: Starting Music Player Daemon... Jun 03 15:48:30 volumio sudo[23293]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 03 15:48:30 volumio sudo[23293]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:30 volumio sudo[23293]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:31 volumio mpd[23297]: Jun 03 15:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 03 15:48:31 volumio systemd[1]: Started Music Player Daemon. Jun 03 15:48:31 volumio sudo[23287]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:31 volumio sudo[23245]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:31 volumio volumio[987]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jun 03 15:48:31 volumio volumio[987]: No state is present for card b1 Jun 03 15:48:31 volumio volumio[987]: /usr/share/alsa/init/bcm2835:14: control element not found Jun 03 15:48:31 volumio volumio[987]: No state is present for card b1 Jun 03 15:48:31 volumio volumio[987]: No state is present for card Headphones Jun 03 15:48:31 volumio volumio[987]: /usr/share/alsa/init/bcm2835:20: control element not found Jun 03 15:48:31 volumio volumio[987]: No state is present for card Headphones Jun 03 15:48:31 volumio volumio[987]: No state is present for card S3 Jun 03 15:48:31 volumio volumio[987]: Found hardware: "USB-Audio" "USB Mixer" "USB0d8c:0066" "" "" Jun 03 15:48:31 volumio volumio[987]: Hardware is initialized using a generic method Jun 03 15:48:31 volumio volumio[987]: No state is present for card S3 Jun 03 15:48:31 volumio volumio[987]: info: Output device has changed, restarting MPD Jun 03 15:48:31 volumio sudo[23304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 03 15:48:31 volumio sudo[23304]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:31 volumio volumio[987]: info: Output device has changed, restarting Shairport Sync Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 15:48:31 volumio sudo[23304]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:31 volumio sudo[23306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 03 15:48:31 volumio sudo[23306]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:31 volumio systemd[1]: Stopping Music Player Daemon... Jun 03 15:48:31 volumio volumio[987]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 03 15:48:31 volumio systemd[1]: mpd.service: Succeeded. Jun 03 15:48:31 volumio systemd[1]: Stopped Music Player Daemon. Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 03 15:48:31 volumio volumio[987]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 03 15:48:31 volumio volumio[987]: info: Disabling external Volume Control Jun 03 15:48:31 volumio volumio[987]: info: Restarting Shairport-Sync after stop Jun 03 15:48:31 volumio systemd[1]: Starting Music Player Daemon... Jun 03 15:48:31 volumio volumio[987]: info: VolumeController::SetAlsaVolume-4 Jun 03 15:48:31 volumio sudo[23323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 03 15:48:31 volumio sudo[23323]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:31 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:31 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 03 15:48:31 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:31 volumio volumio[987]: info: MPD Permissions set Jun 03 15:48:31 volumio volumio[987]: info: MPD Permissions set Jun 03 15:48:31 volumio volumio[987]: info: Shairport-Sync Started Jun 03 15:48:31 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 03 15:48:31 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:31 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:31 volumio sudo[23323]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:31 volumio sudo[23314]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 03 15:48:31 volumio sudo[23314]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:31 volumio sudo[23314]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 15:48:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 15:48:31 volumio volumio[987]: info: Shairport-Sync retarted Jun 03 15:48:31 volumio volumio[987]: info: Starting Shairport Sync Jun 03 15:48:31 volumio volumio[987]: info: Starting Shairport Sync Jun 03 15:48:32 volumio sudo[23340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 03 15:48:32 volumio sudo[23340]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 03 15:48:32 volumio sudo[23343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 03 15:48:32 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 03 15:48:32 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 03 15:48:32 volumio sudo[23343]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 03 15:48:32 volumio sudo[23340]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:32 volumio volumio[987]: info: Shairport-Sync Started Jun 03 15:48:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 03 15:48:32 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 03 15:48:32 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 03 15:48:32 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 03 15:48:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 03 15:48:32 volumio sudo[23343]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:32 volumio volumio[987]: info: Shairport-Sync Started Jun 03 15:48:32 volumio mpd[23332]: Jun 03 15:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 03 15:48:32 volumio systemd[1]: Started Music Player Daemon. Jun 03 15:48:32 volumio sudo[23306]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:32 volumio volumio[987]: error: MPD error: The expression evaluated to a falsy value: Jun 03 15:48:32 volumio volumio[987]: assert.ok(self.idling) Jun 03 15:48:32 volumio volumio[987]: error: The expression evaluated to a falsy value: Jun 03 15:48:32 volumio volumio[987]: assert.ok(self.idling) Jun 03 15:48:32 volumio volumio[987]: error: updateQueue error: null Jun 03 15:48:32 volumio volumio[987]: error: updateQueue error: null Jun 03 15:48:33 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 15:48:33 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 15:48:33 volumio volumio[987]: info: Discovery: Getting this device information Jun 03 15:48:33 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 03 15:48:33 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 15:48:33 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 03 15:48:33 volumio sudo[23352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 03 15:48:33 volumio sudo[23352]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:33 volumio systemd[1]: Stopping Music Player Daemon... Jun 03 15:48:33 volumio systemd[1]: mpd.service: Succeeded. Jun 03 15:48:33 volumio systemd[1]: Stopped Music Player Daemon. Jun 03 15:48:33 volumio systemd[1]: Starting Music Player Daemon... Jun 03 15:48:33 volumio sudo[23354]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 03 15:48:33 volumio sudo[23354]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:33 volumio sudo[23354]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:34 volumio mpd[23356]: Jun 03 15:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 03 15:48:34 volumio systemd[1]: Started Music Player Daemon. Jun 03 15:48:34 volumio sudo[23352]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:34 volumio volumio[987]: error: updateQueue error: null Jun 03 15:48:34 volumio volumio[987]: info: VolumeController::SetAlsaVolume71 Jun 03 15:48:34 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:34 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:34 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:35 volumio wpa_supplicant[4341]: wlan0: Failed to initiate sched scan Jun 03 15:48:35 volumio volumio[987]: info: CoreCommandRouter::volumioPause Jun 03 15:48:35 volumio volumio[987]: info: CoreStateMachine::pause Jun 03 15:48:35 volumio volumio[987]: info: CoreStateMachine::stPlaybackTimer Jun 03 15:48:35 volumio volumio[987]: info: CoreStateMachine::servicePause Jun 03 15:48:35 volumio volumio[987]: info: CoreCommandRouter::servicePause Jun 03 15:48:35 volumio volumio[987]: info: Airplay Pause with DBUS Call Jun 03 15:48:35 volumio volumio[987]: info: Error Airplay DBUS Call Pause: Error: Command failed: /usr/bin/dbus-send --system --print-reply --type=method_call --dest=org.gnome.ShairportSync '/org/gnome/ShairportSync' org.gnome.ShairportSync.RemoteControl.Pause Jun 03 15:48:35 volumio volumio[987]: Error org.freedesktop.DBus.Error.ServiceUnknown: The name org.gnome.ShairportSync was not provided by any .service files Jun 03 15:48:35 volumio volumio[987]: info: CoreCommandRouter::volumioPlay Jun 03 15:48:35 volumio volumio[987]: verbose: UNSET VOLATILE: Service: airplay_emulation Jun 03 15:48:35 volumio volumio[987]: info: Stopping Airplay Playback and sending pause command to client via USR2 Jun 03 15:48:35 volumio volumio[987]: info: CoreStateMachine::play index undefined Jun 03 15:48:35 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 15:48:35 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 03 15:48:35 volumio sudo[23364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 23346 Jun 03 15:48:35 volumio sudo[23364]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:35 volumio sudo[23364]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:35 volumio volumio[987]: info: Shairport-Sync paused with USR2 Jun 03 15:48:36 volumio volumio[987]: info: Restarting Shairport-Sync after stop Jun 03 15:48:36 volumio sudo[23367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 03 15:48:36 volumio sudo[23367]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 15:48:36 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 03 15:48:36 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 03 15:48:36 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 03 15:48:36 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 03 15:48:36 volumio sudo[23367]: pam_unix(sudo:session): session closed for user root Jun 03 15:48:36 volumio volumio[987]: info: Shairport-Sync retarted Jun 03 15:48:39 volumio volumio[987]: info: Airplay started streaming, receiving metadatas Jun 03 15:48:39 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 03 15:48:39 volumio volumio[987]: info: CoreCommandRouter::volumioStop Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::stop Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::updateTrackBlock Jun 03 15:48:39 volumio volumio[987]: info: CorePlayQueue::getTrackBlock Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::stPlaybackTimer Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:39 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 03 15:48:39 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:39 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::serviceStop Jun 03 15:48:39 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 03 15:48:39 volumio volumio[987]: info: ControllerMpd::stop Jun 03 15:48:39 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 15:48:39 volumio volumio[987]: info: Airplay started streaming, receiving metadatas Jun 03 15:48:39 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 03 15:48:39 volumio volumio[987]: info: CoreCommandRouter::volumioStop Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::stop Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 15:48:39 volumio volumio[987]: info: Airplay started streaming, receiving metadatas Jun 03 15:48:39 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 03 15:48:39 volumio volumio[987]: info: CoreCommandRouter::volumioStop Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::stop Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 15:48:39 volumio volumio[987]: info: Airplay started streaming, receiving metadatas Jun 03 15:48:39 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 03 15:48:39 volumio volumio[987]: info: CoreCommandRouter::volumioStop Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::stop Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 15:48:39 volumio volumio[987]: info: Airplay started streaming, receiving metadatas Jun 03 15:48:39 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 03 15:48:39 volumio volumio[987]: info: CoreCommandRouter::volumioStop Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::stop Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 15:48:39 volumio volumio[987]: info: Airplay started streaming, receiving metadatas Jun 03 15:48:39 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 03 15:48:39 volumio volumio[987]: info: CoreCommandRouter::volumioStop Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::stop Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 15:48:39 volumio volumio[987]: info: sendMpdCommand stop took 16 milliseconds Jun 03 15:48:39 volumio shairport-sync[23369]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 03 15:48:39 volumio shairport-sync[23369]: Dload Upload Total Spent Left Speed Jun 03 15:48:39 volumio volumio[987]: info: Jun 03 15:48:39 volumio volumio[987]: ---------------------------- Client requests Start Airplay PlaybackRoutine Jun 03 15:48:39 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Jun 03 15:48:39 volumio volumio[987]: info: Airplay playback start Jun 03 15:48:39 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 03 15:48:39 volumio volumio[987]: info: CoreCommandRouter::volumioStop Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::stop Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 15:48:39 volumio volumio[987]: info: Updating volume from AirPlay: -20; 33% Jun 03 15:48:39 volumio volumio[987]: info: VolumeController::SetAlsaVolume33 Jun 03 15:48:39 volumio shairport-sync[23369]: [158B blob data] Jun 03 15:48:39 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:39 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 03 15:48:39 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:39 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:40 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:40 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:40 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:40 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:40 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:40 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:40 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:40 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:40 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:40 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:40 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:40 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:40 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:40 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:40 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:40 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:40 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:40 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:40 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:40 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:40 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:40 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:41 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:41 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:41 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:41 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:41 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:41 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:41 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:41 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:41 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:41 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:41 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:41 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:41 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:45 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:45 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:46 volumio wpa_supplicant[4341]: wlan0: Failed to initiate sched scan Jun 03 15:48:46 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:46 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:46 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:46 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:46 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:46 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:46 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:46 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:46 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:46 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:46 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:46 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:46 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:46 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:46 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:46 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:46 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:46 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:46 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:46 volumio volumio[987]: info: Updating volume from AirPlay: -18.12; 40% Jun 03 15:48:46 volumio volumio[987]: info: VolumeController::SetAlsaVolume40 Jun 03 15:48:46 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:46 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:46 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:47 volumio volumio[987]: info: Updating volume from AirPlay: -16.25; 46% Jun 03 15:48:47 volumio volumio[987]: info: VolumeController::SetAlsaVolume46 Jun 03 15:48:47 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:47 volumio volumio[987]: info: Updating volume from AirPlay: -14.37; 52% Jun 03 15:48:47 volumio volumio[987]: info: VolumeController::SetAlsaVolume52 Jun 03 15:48:47 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:47 volumio volumio[987]: info: Updating volume from AirPlay: -12.5; 58% Jun 03 15:48:47 volumio volumio[987]: info: VolumeController::SetAlsaVolume58 Jun 03 15:48:47 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:47 volumio volumio[987]: info: Updating volume from AirPlay: -10.62; 65% Jun 03 15:48:47 volumio volumio[987]: info: VolumeController::SetAlsaVolume65 Jun 03 15:48:47 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:47 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:47 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:47 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:47 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:47 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:47 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:48 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:48 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:48 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:48 volumio shairport-sync[23369]: {"time":1748958519646,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Jun 03 15:48:48 volumio shairport-sync[23369]: Dload Upload Total Spent Left Speed Jun 03 15:48:48 volumio volumio[987]: info: Jun 03 15:48:48 volumio volumio[987]: ---------------------------- Client requests Stop Airplay Playback Jun 03 15:48:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Jun 03 15:48:48 volumio volumio[987]: info: Airplay playback stop Jun 03 15:48:48 volumio volumio[987]: info: Airplay Playback Stopped, clearing state Jun 03 15:48:48 volumio volumio[987]: info: CoreStateMachine::resetVolumioState Jun 03 15:48:48 volumio volumio[987]: info: CoreStateMachine::getcurrentVolume Jun 03 15:48:48 volumio volumio[987]: info: CoreCommandRouter::volumioRetrievevolume Jun 03 15:48:48 volumio shairport-sync[23369]: [158B blob data] Jun 03 15:48:48 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:48 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:48 volumio volumio[987]: info: CoreStateMachine::updateTrackBlock Jun 03 15:48:48 volumio volumio[987]: info: CorePlayQueue::getTrackBlock Jun 03 15:48:48 volumio volumio[987]: info: CoreCommandRouter::volumioRetrievevolume Jun 03 15:48:48 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:48 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:53 volumio volumio[987]: info: Airplay started streaming, receiving metadatas Jun 03 15:48:53 volumio shairport-sync[23369]: {"time":1748958528029,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Jun 03 15:48:53 volumio shairport-sync[23369]: Dload Upload Total Spent Left Speed Jun 03 15:48:53 volumio volumio[987]: info: Jun 03 15:48:53 volumio volumio[987]: ---------------------------- Client requests Start Airplay PlaybackRoutine Jun 03 15:48:53 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Jun 03 15:48:53 volumio volumio[987]: info: Airplay playback start Jun 03 15:48:53 volumio shairport-sync[23369]: [158B blob data] Jun 03 15:48:53 volumio volumio[987]: info: Updating volume from AirPlay: 0; 100% Jun 03 15:48:53 volumio volumio[987]: info: VolumeController::SetAlsaVolume100 Jun 03 15:48:53 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:53 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:53 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:54 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:54 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:54 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:54 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:54 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:54 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:54 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:54 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:54 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:54 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:54 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:54 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:54 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:54 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:54 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:54 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:54 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:54 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:54 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:54 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:54 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:55 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:55 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:55 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:55 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:55 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:55 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:55 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:55 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:55 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:55 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:55 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jun 03 15:48:55 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:55 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:56 volumio volumio[987]: info: Updating volume from AirPlay: -1.88; 94% Jun 03 15:48:56 volumio volumio[987]: info: VolumeController::SetAlsaVolume94 Jun 03 15:48:56 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:56 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:56 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:56 volumio volumio[987]: info: Updating volume from AirPlay: -3.75; 88% Jun 03 15:48:56 volumio volumio[987]: info: VolumeController::SetAlsaVolume88 Jun 03 15:48:56 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:56 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:56 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:56 volumio volumio[987]: info: Updating volume from AirPlay: -5.62; 81% Jun 03 15:48:56 volumio volumio[987]: info: VolumeController::SetAlsaVolume81 Jun 03 15:48:56 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:56 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:56 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:56 volumio volumio[987]: info: Updating volume from AirPlay: -7.5; 75% Jun 03 15:48:56 volumio volumio[987]: info: VolumeController::SetAlsaVolume75 Jun 03 15:48:56 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:56 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:56 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:56 volumio volumio[987]: info: Updating volume from AirPlay: -9.38; 69% Jun 03 15:48:56 volumio volumio[987]: info: VolumeController::SetAlsaVolume69 Jun 03 15:48:56 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:56 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:56 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:56 volumio volumio[987]: info: Updating volume from AirPlay: -13.12; 56% Jun 03 15:48:56 volumio volumio[987]: info: VolumeController::SetAlsaVolume56 Jun 03 15:48:56 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:56 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:56 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:56 volumio volumio[987]: info: Updating volume from AirPlay: -18.75; 38% Jun 03 15:48:56 volumio volumio[987]: info: VolumeController::SetAlsaVolume38 Jun 03 15:48:57 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:57 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:57 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:57 volumio volumio[987]: info: Updating volume from AirPlay: -22.5; 25% Jun 03 15:48:57 volumio volumio[987]: info: VolumeController::SetAlsaVolume25 Jun 03 15:48:57 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:57 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:57 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:57 volumio volumio[987]: info: Updating volume from AirPlay: -24.38; 19% Jun 03 15:48:57 volumio volumio[987]: info: VolumeController::SetAlsaVolume19 Jun 03 15:48:57 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:57 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:57 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:57 volumio wpa_supplicant[4341]: wlan0: Failed to initiate sched scan Jun 03 15:48:57 volumio volumio[987]: info: Updating volume from AirPlay: -22.5; 25% Jun 03 15:48:57 volumio volumio[987]: info: VolumeController::SetAlsaVolume25 Jun 03 15:48:57 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:57 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:57 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:57 volumio volumio[987]: info: Updating volume from AirPlay: -20.62; 31% Jun 03 15:48:57 volumio volumio[987]: info: VolumeController::SetAlsaVolume31 Jun 03 15:48:57 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:57 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:57 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:58 volumio volumio[987]: info: Updating volume from AirPlay: -18.75; 38% Jun 03 15:48:58 volumio volumio[987]: info: VolumeController::SetAlsaVolume38 Jun 03 15:48:58 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:58 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:58 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:58 volumio volumio[987]: info: Updating volume from AirPlay: -16.88; 44% Jun 03 15:48:58 volumio volumio[987]: info: VolumeController::SetAlsaVolume44 Jun 03 15:48:58 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:58 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:58 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:48:58 volumio volumio[987]: info: Updating volume from AirPlay: -15; 50% Jun 03 15:48:58 volumio volumio[987]: info: VolumeController::SetAlsaVolume50 Jun 03 15:48:58 volumio volumio[987]: info: CoreStateMachine::pushState Jun 03 15:48:58 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 15:48:58 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jun 03 15:49:03 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 15:49:03 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 15:49:03 volumio volumio[987]: info: Discovery: Getting this device information Jun 03 15:49:03 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 03 15:49:03 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 15:49:08 volumio wpa_supplicant[4341]: wlan0: Failed to initiate sched scan Jun 03 15:49:12 volumio volumio[987]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 15:49:12 volumio volumio[987]: Error: Client network socket disconnected before secure TLS connection was established Jun 03 15:49:12 volumio volumio[987]: at connResetException (internal/errors.js:607:14) Jun 03 15:49:12 volumio volumio[987]: at TLSSocket.onConnectEnd (_tls_wrap.js:1544:19) Jun 03 15:49:12 volumio volumio[987]: at TLSSocket.emit (events.js:327:22) Jun 03 15:49:12 volumio volumio[987]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 03 15:49:12 volumio volumio[987]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jun 03 15:49:12 volumio volumio[987]: code: 'ECONNRESET', Jun 03 15:49:12 volumio volumio[987]: path: null, Jun 03 15:49:12 volumio volumio[987]: host: 'lastfm.freetls.fastly.net', Jun 03 15:49:12 volumio volumio[987]: port: 443, Jun 03 15:49:12 volumio volumio[987]: localAddress: undefined Jun 03 15:49:12 volumio volumio[987]: } Jun 03 15:49:12 volumio volumio[987]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 15:49:13 volumio sudo[23537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-03 15:48 Jun 03 15:49:13 volumio sudo[23537]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"