-- Logs begin at Sat 2025-06-14 14:21:53 CEST, end at Sat 2025-06-14 16:31:09 CEST. -- Jun 14 16:30:16 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 16:30:16 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 16:30:16 burgy-volumio volumio[3089]: info: Discovery: Getting this device information Jun 14 16:30:16 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetState Jun 14 16:30:16 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 16:30:31 burgy-volumio sudo[21509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 14 16:30:31 burgy-volumio sudo[21509]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 16:30:31 burgy-volumio sudo[21511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 14 16:30:31 burgy-volumio sudo[21511]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 16:30:31 burgy-volumio sudo[21509]: pam_unix(sudo:session): session closed for user root Jun 14 16:30:31 burgy-volumio sudo[21511]: pam_unix(sudo:session): session closed for user root Jun 14 16:30:31 burgy-volumio volumio[3089]: verbose: New Socket.io Connection to 192.168.1.4 from 192.168.1.59 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 14 16:30:32 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetVisibleSources Jun 14 16:30:32 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 16:30:32 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 14 16:30:32 burgy-volumio volumio[3089]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 14 16:30:32 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetState Jun 14 16:30:32 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 14 16:30:32 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 14 16:30:32 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 14 16:30:32 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 14 16:30:32 burgy-volumio sudo[21517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 14 16:30:32 burgy-volumio sudo[21517]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 16:30:32 burgy-volumio sudo[21517]: pam_unix(sudo:session): session closed for user root Jun 14 16:30:32 burgy-volumio sudo[21519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 14 16:30:32 burgy-volumio sudo[21519]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 16:30:32 burgy-volumio sudo[21519]: pam_unix(sudo:session): session closed for user root Jun 14 16:30:32 burgy-volumio volumio[3089]: verbose: New Socket.io Connection to 192.168.1.4 from 192.168.1.59 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jun 14 16:30:33 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetState Jun 14 16:30:33 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 14 16:30:33 burgy-volumio volumio[3089]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 14 16:30:33 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetVisibleSources Jun 14 16:30:33 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 16:30:33 burgy-volumio volumio[3089]: info: Listing playlists Jun 14 16:30:33 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 14 16:30:33 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 14 16:30:33 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 14 16:30:33 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 14 16:30:34 burgy-volumio volumio[3089]: info: VolumeController::SetAlsaVolume55 Jun 14 16:30:34 burgy-volumio volumio[3089]: info: CoreStateMachine::pushState Jun 14 16:30:34 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 16:30:34 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioPushState Jun 14 16:30:34 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetState Jun 14 16:30:34 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output update for this device Jun 14 16:30:34 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output Jun 14 16:30:34 burgy-volumio volumio[3089]: info: Signalling Playback active due to playback status change Jun 14 16:30:34 burgy-volumio volumio[3089]: info: Updating RAAT Signal Path Jun 14 16:30:34 burgy-volumio volumio[3089]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'SoftMaster',0,2,0) appears twice or more Jun 14 16:30:34 burgy-volumio volumio[3089]: amixer: Mixer hw:0 load error: Invalid argument Jun 14 16:30:35 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 14 16:30:35 burgy-volumio volumio[3089]: info: Received Get System Info Jun 14 16:30:35 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 16:30:35 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 16:30:35 burgy-volumio volumio[3089]: info: Discovery: Getting this device information Jun 14 16:30:35 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetState Jun 14 16:30:35 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 16:30:37 burgy-volumio volumio[3089]: info: VolumeController::SetAlsaVolume40 Jun 14 16:30:37 burgy-volumio volumio[3089]: info: CoreStateMachine::pushState Jun 14 16:30:37 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 16:30:37 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioPushState Jun 14 16:30:37 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetState Jun 14 16:30:37 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output update for this device Jun 14 16:30:37 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output Jun 14 16:30:37 burgy-volumio volumio[3089]: info: Signalling Playback active due to playback status change Jun 14 16:30:37 burgy-volumio volumio[3089]: info: Updating RAAT Signal Path Jun 14 16:30:37 burgy-volumio volumio[3089]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'SoftMaster',0,2,0) appears twice or more Jun 14 16:30:37 burgy-volumio volumio[3089]: amixer: Mixer hw:0 load error: Invalid argument Jun 14 16:30:38 burgy-volumio volumio[3089]: info: VolumeController::SetAlsaVolume+ Jun 14 16:30:38 burgy-volumio volumio[3089]: info: CoreStateMachine::pushState Jun 14 16:30:38 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 16:30:38 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioPushState Jun 14 16:30:38 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetState Jun 14 16:30:38 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output update for this device Jun 14 16:30:38 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output Jun 14 16:30:38 burgy-volumio volumio[3089]: info: Signalling Playback active due to playback status change Jun 14 16:30:38 burgy-volumio volumio[3089]: info: Updating RAAT Signal Path Jun 14 16:30:38 burgy-volumio volumio[3089]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jun 14 16:30:38 burgy-volumio volumio[3089]: xcb_connection_has_error() returned true Jun 14 16:30:38 burgy-volumio volumio[3089]: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'SoftMaster',0,2,0) appears twice or more Jun 14 16:30:38 burgy-volumio volumio[3089]: amixer: Mixer hw:0 load error: Invalid argument Jun 14 16:30:39 burgy-volumio volumio[3089]: info: VolumeController::SetAlsaVolume+ Jun 14 16:30:39 burgy-volumio volumio[3089]: info: CoreStateMachine::pushState Jun 14 16:30:39 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 16:30:39 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioPushState Jun 14 16:30:39 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetState Jun 14 16:30:39 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output update for this device Jun 14 16:30:39 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output Jun 14 16:30:39 burgy-volumio volumio[3089]: info: Signalling Playback active due to playback status change Jun 14 16:30:39 burgy-volumio volumio[3089]: info: Updating RAAT Signal Path Jun 14 16:30:39 burgy-volumio volumio[3089]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jun 14 16:30:39 burgy-volumio volumio[3089]: xcb_connection_has_error() returned true Jun 14 16:30:39 burgy-volumio volumio[3089]: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'SoftMaster',0,2,0) appears twice or more Jun 14 16:30:39 burgy-volumio volumio[3089]: amixer: Mixer hw:0 load error: Invalid argument Jun 14 16:30:39 burgy-volumio volumio[3089]: info: VolumeController::SetAlsaVolume41 Jun 14 16:30:39 burgy-volumio volumio[3089]: info: CoreStateMachine::pushState Jun 14 16:30:39 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 16:30:39 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioPushState Jun 14 16:30:39 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetState Jun 14 16:30:39 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output update for this device Jun 14 16:30:39 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output Jun 14 16:30:39 burgy-volumio volumio[3089]: info: Signalling Playback active due to playback status change Jun 14 16:30:39 burgy-volumio volumio[3089]: info: Updating RAAT Signal Path Jun 14 16:30:39 burgy-volumio volumio[3089]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jun 14 16:30:39 burgy-volumio volumio[3089]: xcb_connection_has_error() returned true Jun 14 16:30:39 burgy-volumio volumio[3089]: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'SoftMaster',0,2,0) appears twice or more Jun 14 16:30:39 burgy-volumio volumio[3089]: amixer: Mixer hw:0 load error: Invalid argument Jun 14 16:30:39 burgy-volumio volumio[3089]: info: VolumeController::SetAlsaVolume+ Jun 14 16:30:39 burgy-volumio volumio[3089]: info: CoreStateMachine::pushState Jun 14 16:30:39 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 16:30:39 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioPushState Jun 14 16:30:39 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetState Jun 14 16:30:39 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output update for this device Jun 14 16:30:39 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output Jun 14 16:30:39 burgy-volumio volumio[3089]: info: Signalling Playback active due to playback status change Jun 14 16:30:39 burgy-volumio volumio[3089]: info: Updating RAAT Signal Path Jun 14 16:30:39 burgy-volumio volumio[3089]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'SoftMaster',0,2,0) appears twice or more Jun 14 16:30:39 burgy-volumio volumio[3089]: amixer: Mixer hw:0 load error: Invalid argument Jun 14 16:30:40 burgy-volumio volumio[3089]: info: VolumeController::SetAlsaVolume- Jun 14 16:30:40 burgy-volumio volumio[3089]: info: CoreStateMachine::pushState Jun 14 16:30:40 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 16:30:40 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioPushState Jun 14 16:30:40 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetState Jun 14 16:30:40 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output update for this device Jun 14 16:30:40 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output Jun 14 16:30:40 burgy-volumio volumio[3089]: info: Signalling Playback active due to playback status change Jun 14 16:30:40 burgy-volumio volumio[3089]: info: Updating RAAT Signal Path Jun 14 16:30:40 burgy-volumio volumio[3089]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'SoftMaster',0,2,0) appears twice or more Jun 14 16:30:40 burgy-volumio volumio[3089]: amixer: Mixer hw:0 load error: Invalid argument Jun 14 16:30:41 burgy-volumio volumio[3089]: info: VolumeController::SetAlsaVolumemute Jun 14 16:30:41 burgy-volumio volumio[3089]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'SoftMaster',0,2,0) appears twice or more Jun 14 16:30:41 burgy-volumio volumio[3089]: amixer: Mixer hw:0 load error: Invalid argument Jun 14 16:30:41 burgy-volumio volumio[3089]: info: CoreStateMachine::pushState Jun 14 16:30:41 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 16:30:41 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioPushState Jun 14 16:30:41 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetState Jun 14 16:30:41 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output update for this device Jun 14 16:30:41 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output Jun 14 16:30:41 burgy-volumio volumio[3089]: info: Signalling Playback active due to playback status change Jun 14 16:30:41 burgy-volumio volumio[3089]: info: Updating RAAT Signal Path Jun 14 16:30:41 burgy-volumio volumio[3089]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'SoftMaster',0,2,0) appears twice or more Jun 14 16:30:41 burgy-volumio volumio[3089]: amixer: Mixer hw:0 load error: Invalid argument Jun 14 16:30:46 burgy-volumio volumio[3089]: info: VolumeController::SetAlsaVolumeunmute Jun 14 16:30:46 burgy-volumio volumio[3089]: info: CoreStateMachine::pushState Jun 14 16:30:46 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 16:30:46 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioPushState Jun 14 16:30:46 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetState Jun 14 16:30:46 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output update for this device Jun 14 16:30:46 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output Jun 14 16:30:46 burgy-volumio volumio[3089]: info: Signalling Playback active due to playback status change Jun 14 16:30:46 burgy-volumio volumio[3089]: info: Updating RAAT Signal Path Jun 14 16:30:46 burgy-volumio volumio[3089]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'SoftMaster',0,2,0) appears twice or more Jun 14 16:30:46 burgy-volumio volumio[3089]: amixer: Mixer hw:0 load error: Invalid argument Jun 14 16:30:46 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 16:30:46 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 16:30:46 burgy-volumio volumio[3089]: info: Discovery: Getting this device information Jun 14 16:30:46 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetState Jun 14 16:30:46 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 16:30:50 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 14 16:30:50 burgy-volumio volumio[3089]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 14 16:30:50 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 14 16:30:50 burgy-volumio volumio[3089]: info: Received Get System Version Jun 14 16:30:50 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 16:30:50 burgy-volumio volumio[3089]: info: Received Get System Info Jun 14 16:30:50 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 16:30:50 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 16:30:50 burgy-volumio volumio[3089]: info: Discovery: Getting this device information Jun 14 16:30:50 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetState Jun 14 16:30:50 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 16:31:06 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jun 14 16:31:06 burgy-volumio volumio[3089]: info: Pausing Remote Device: http://192.168.1.4 Jun 14 16:31:06 burgy-volumio volumio[3089]: info: Jun 14 16:31:06 burgy-volumio volumio[3089]: ---------------------------- Client requests Volumio pause Jun 14 16:31:06 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioPause Jun 14 16:31:06 burgy-volumio volumio[3089]: info: CoreStateMachine::pause Jun 14 16:31:06 burgy-volumio volumio[3089]: info: CoreStateMachine::stPlaybackTimer Jun 14 16:31:06 burgy-volumio volumio[3089]: info: CoreStateMachine::servicePause Jun 14 16:31:06 burgy-volumio volumio[3089]: info: CorePlayQueue::getTrack 0 Jun 14 16:31:06 burgy-volumio volumio[3089]: info: CoreCommandRouter::servicePause Jun 14 16:31:06 burgy-volumio volumio[3089]: info: [1749911466977] ControllerWebradio::pause Jun 14 16:31:06 burgy-volumio volumio[3089]: verbose: ControllerMpd::sendMpdCommand pause Jun 14 16:31:06 burgy-volumio kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Jun 14 16:31:06 burgy-volumio kernel: spdif_a keep clk continuous Jun 14 16:31:06 burgy-volumio kernel: aml_spdif_close Jun 14 16:31:06 burgy-volumio kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 14 16:31:06 burgy-volumio volumio[3089]: info: Jun 14 16:31:06 burgy-volumio volumio[3089]: ---------------------------- MPD announces state update: player Jun 14 16:31:06 burgy-volumio volumio[3089]: info: sendMpdCommand pause took 8 milliseconds Jun 14 16:31:06 burgy-volumio volumio[3089]: info: ControllerMpd::getState Jun 14 16:31:06 burgy-volumio volumio[3089]: verbose: ControllerMpd::sendMpdCommand status Jun 14 16:31:06 burgy-volumio volumio[3089]: info: sendMpdCommand status took 4 milliseconds Jun 14 16:31:06 burgy-volumio volumio[3089]: verbose: ControllerMpd::parseState Jun 14 16:31:06 burgy-volumio volumio[3089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 16:31:07 burgy-volumio volumio[3089]: info: Done pausing: Jun 14 16:31:07 burgy-volumio volumio[3089]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 14 16:31:07 burgy-volumio volumio[3089]: verbose: ControllerMpd::parseTrackInfo Jun 14 16:31:07 burgy-volumio volumio[3089]: info: ControllerMpd::pushState Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CoreCommandRouter::servicePushState Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CorePlayQueue::getTrack 0 Jun 14 16:31:07 burgy-volumio volumio[3089]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":6165551,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Marlon Hoffstadt & DJ Daddy Trance - Supersonic","artist":"SUNSHINE LIVE - Simulcast","album":null,"uri":"https://stream.sunshine-live.de/live/mp3-128/tunein/","trackType":""} Jun 14 16:31:07 burgy-volumio volumio[3089]: verbose: CURRENT POSITION 0 Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CoreStateMachine::syncState stateService pause Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CoreStateMachine::syncState currentStatus pause Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CoreStateMachine::pushState Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioPushState Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetState Jun 14 16:31:07 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output update for this device Jun 14 16:31:07 burgy-volumio volumio[3089]: info: MRS: Pushing multiroomSync output Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CoreStateMachine::stPlaybackTimer Jun 14 16:31:07 burgy-volumio volumio[3089]: info: ------------------------------ 40ms Jun 14 16:31:07 burgy-volumio volumio[3089]: info: Updating RAAT Signal Path Jun 14 16:31:07 burgy-volumio volumio[3089]: info: MCU Signalled Playback Inactive Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jun 14 16:31:07 burgy-volumio volumio[3089]: info: Playing Remote Device: http://192.168.1.4 Jun 14 16:31:07 burgy-volumio volumio[3089]: info: Jun 14 16:31:07 burgy-volumio volumio[3089]: ---------------------------- Client requests Volumio play Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioPlay Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CoreStateMachine::play index undefined Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CorePlayQueue::getTrack 0 Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CoreStateMachine::startPlaybackTimer Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CorePlayQueue::getTrack 0 Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CoreCommandRouter::volumioGetVisibleSources Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 14 16:31:07 burgy-volumio volumio[3089]: info: [1749911467897] ControllerWebradio::resume Jun 14 16:31:07 burgy-volumio volumio[3089]: verbose: ControllerMpd::sendMpdCommand play Jun 14 16:31:07 burgy-volumio kernel: aml_spdif_open Jun 14 16:31:07 burgy-volumio kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 14 16:31:07 burgy-volumio volumio[3089]: info: Done playing: Jun 14 16:31:07 burgy-volumio kernel: set normal 512 fs /4 fs Jun 14 16:31:07 burgy-volumio kernel: set spdifout clk:5644800, mpll:22579200 Jun 14 16:31:07 burgy-volumio kernel: get spdifout clk:5644797, mpll:22579186 Jun 14 16:31:07 burgy-volumio kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Jun 14 16:31:07 burgy-volumio kernel: set normal 512 fs /4 fs Jun 14 16:31:07 burgy-volumio kernel: set spdifout clk:5644800, mpll:22579200 Jun 14 16:31:07 burgy-volumio kernel: get spdifout clk:5644797, mpll:22579186 Jun 14 16:31:07 burgy-volumio kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jun 14 16:31:07 burgy-volumio kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Jun 14 16:31:07 burgy-volumio kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jun 14 16:31:07 burgy-volumio kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Jun 14 16:31:07 burgy-volumio volumio[3089]: info: Jun 14 16:31:07 burgy-volumio volumio[3089]: ---------------------------- MPD announces state update: player Jun 14 16:31:07 burgy-volumio volumio[3089]: info: sendMpdCommand play took 19 milliseconds Jun 14 16:31:07 burgy-volumio volumio[3089]: info: ControllerMpd::getState Jun 14 16:31:07 burgy-volumio volumio[3089]: verbose: ControllerMpd::sendMpdCommand status Jun 14 16:31:07 burgy-volumio volumio[3089]: info: sendMpdCommand status took 2 milliseconds Jun 14 16:31:07 burgy-volumio volumio[3089]: verbose: ControllerMpd::parseState Jun 14 16:31:07 burgy-volumio volumio[3089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 16:31:07 burgy-volumio volumio[3089]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 14 16:31:07 burgy-volumio volumio[3089]: verbose: ControllerMpd::parseTrackInfo Jun 14 16:31:07 burgy-volumio volumio[3089]: info: ControllerMpd::pushState Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CoreCommandRouter::servicePushState Jun 14 16:31:07 burgy-volumio volumio[3089]: info: CorePlayQueue::getTrack 0 Jun 14 16:31:07 burgy-volumio volumio[3089]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 14 16:31:07 burgy-volumio volumio[3089]: info: ------------------------------ 7ms Jun 14 16:31:08 burgy-volumio kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jun 14 16:31:09 burgy-volumio volumio[3089]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Jun 14 16:31:09 burgy-volumio volumio[3089]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Jun 14 16:31:09 burgy-volumio volumio[3089]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 16:31:09 burgy-volumio volumio[3089]: TypeError: Cannot read property 'then' of undefined Jun 14 16:31:09 burgy-volumio volumio[3089]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Jun 14 16:31:09 burgy-volumio volumio[3089]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Jun 14 16:31:09 burgy-volumio volumio[3089]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1456:26) Jun 14 16:31:09 burgy-volumio volumio[3089]: at Socket.emit (events.js:400:28) Jun 14 16:31:09 burgy-volumio volumio[3089]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 14 16:31:09 burgy-volumio volumio[3089]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jun 14 16:31:09 burgy-volumio volumio[3089]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 16:31:09 burgy-volumio sudo[21662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-14 16:30 Jun 14 16:31:09 burgy-volumio sudo[21662]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 07:34:23 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="c7ccfd1c436e37df77d047f13edf35d7"