May 23 13:48:05 volumio volumio[2156]: info: May 23 13:48:05 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:48:05 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:48:05 volumio volumio[2156]: info: May 23 13:48:05 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:48:05 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:48:05 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:48:05 volumio volumio[2156]: info: ------------------------------ 2ms May 23 13:48:05 volumio volumio[2156]: info: sendMpdCommand status took 2 milliseconds May 23 13:48:05 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:48:05 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:48:05 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 1 milliseconds May 23 13:48:05 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:48:05 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:48:05 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:48:05 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:48:05 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":58102,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Platz 247 - ÁSDÍS - Touch Me","artist":"Sdtirol 1","album":null,"uri":"https://stream.suedtirol1.it/live/mp3-192/tunein","trackType":""} May 23 13:48:05 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:48:05 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:48:05 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus play May 23 13:48:05 volumio volumio[2156]: info: Received an update from plugin. extracting info from payload May 23 13:48:05 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:48:05 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:48:05 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:48:05 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:48:05 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:48:05 volumio volumio[2156]: info: ------------------------------ 12ms May 23 13:48:20 volumio volumio[2156]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] May 23 13:48:20 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq May 23 13:48:20 volumio volumio[2156]: info: FusionDsp - value distance L------- 0 R 0 May 23 13:48:20 volumio volumio[2156]: info: FusionDsp - no delay needed May 23 13:48:20 volumio volumio[2156]: info: VolumeController::SetAlsaVolume+ May 23 13:48:20 volumio volumio[2156]: info: VolumeController::SetAlsaVolume- May 23 13:48:20 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:20 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:20 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:48:20 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:48:20 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:48:20 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:20 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:20 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:48:20 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:48:20 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 23 13:48:21 volumio dbus-daemon[675]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.73' (uid=0 pid=8269 comm="timedatectl show --property=NTPSynchronized --valu") May 23 13:48:21 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 23 13:48:21 volumio dbus-daemon[675]: [system] Successfully activated service 'org.freedesktop.timedate1' May 23 13:48:21 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 23 13:48:21 volumio setdatetime-helper.sh[8266]: Time is already synchronized. May 23 13:48:21 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 23 13:48:21 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 23 13:48:21 volumio volumio[2156]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 13:48:21 volumio volumio[2156]: info: FusionDsp - Loudness is ON true May 23 13:48:21 volumio volumio[2156]: warn: FusionDsp - Monitor WebSocket not open, cannot send ResetClippedSamples May 23 13:48:22 volumio volumio[2156]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 13:48:22 volumio volumio[2156]: info: CoreCommandRouter::Close All Modals sent May 23 13:48:29 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 13:48:29 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 13:48:29 volumio volumio[2156]: info: Discovery: Getting this device information May 23 13:48:29 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:29 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 13:48:31 volumio volumio[2156]: info: VolumeController::SetAlsaVolume28 May 23 13:48:31 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:31 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:31 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:48:31 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:48:31 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:48:32 volumio volumio[2156]: info: VolumeController::SetAlsaVolume24 May 23 13:48:32 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:32 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:32 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:48:32 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:48:32 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:48:33 volumio volumio[2156]: info: VolumeController::SetAlsaVolume28 May 23 13:48:33 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:33 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:33 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:48:33 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:48:33 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:48:34 volumio volumio[2156]: info: VolumeController::SetAlsaVolume33 May 23 13:48:34 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:34 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:34 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:48:34 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:48:34 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:48:35 volumio volumio[2156]: info: VolumeController::SetAlsaVolume28 May 23 13:48:35 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:35 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:35 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:48:35 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:48:35 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:48:51 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 23 13:48:51 volumio volumio[2156]: info: VolumeController::SetAlsaVolume26 May 23 13:48:51 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:51 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:51 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:48:51 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:48:51 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:48:51 volumio volumio[2156]: info: VolumeController::SetAlsaVolume25 May 23 13:48:51 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:51 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:51 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:48:51 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:48:51 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:48:59 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 13:48:59 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 13:48:59 volumio volumio[2156]: info: Discovery: Getting this device information May 23 13:48:59 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:48:59 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 13:49:07 volumio volumio[2156]: info: Preload queue cleared May 23 13:49:07 volumio volumio[2156]: info: CoreCommandRouter::volumioReplaceandPlayItems May 23 13:49:07 volumio volumio[2156]: info: CoreStateMachine::ClearQueue May 23 13:49:07 volumio volumio[2156]: info: CoreStateMachine::stop May 23 13:49:07 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:07 volumio volumio[2156]: info: CoreStateMachine::stPlaybackTimer May 23 13:49:07 volumio volumio[2156]: info: CoreStateMachine::updateTrackBlock May 23 13:49:07 volumio volumio[2156]: info: CorePlayQueue::getTrackBlock May 23 13:49:07 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:07 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:07 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:07 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:07 volumio volumio[2156]: info: CoreStateMachine::serviceStop May 23 13:49:07 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:07 volumio volumio[2156]: info: CoreCommandRouter::serviceStop May 23 13:49:07 volumio volumio[2156]: info: [1748000947546] ControllerWebradio::stop May 23 13:49:07 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand stop May 23 13:49:07 volumio volumio[2156]: info: CorePlayQueue::clearPlayQueue May 23 13:49:07 volumio volumio[2156]: info: CorePlayQueue::saveQueue May 23 13:49:07 volumio volumio[2156]: info: CoreCommandRouter::volumioPushQueue May 23 13:49:07 volumio volumio[2156]: info: CoreStateMachine::addQueueItems May 23 13:49:07 volumio volumio[2156]: info: CorePlayQueue::addQueueItems May 23 13:49:07 volumio volumio[2156]: info: Preload queue cleared May 23 13:49:07 volumio volumio[2156]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s73977 May 23 13:49:07 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 23 13:49:07 volumio volumio[2156]: info: CoreCommandRouter::volumioPushQueue May 23 13:49:07 volumio volumio[2156]: info: CorePlayQueue::saveQueue May 23 13:49:07 volumio volumio[2156]: info: CoreStateMachine::updateTrackBlock May 23 13:49:07 volumio volumio[2156]: info: CorePlayQueue::getTrackBlock May 23 13:49:07 volumio volumio[2156]: info: CoreCommandRouter::volumioPlay May 23 13:49:07 volumio volumio[2156]: info: CoreStateMachine::play index 0 May 23 13:49:07 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:07 volumio volumio[2156]: info: CoreStateMachine::stop May 23 13:49:07 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:07 volumio volumio[2156]: info: CoreStateMachine::play index undefined May 23 13:49:07 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:07 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:07 volumio volumio[2156]: info: CoreStateMachine::startPlaybackTimer May 23 13:49:07 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:07 volumio volumio[2156]: info: [1748000947566] ControllerWebradio::clearAddPlayTrack May 23 13:49:07 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand stop May 23 13:49:07 volumio volumio[2156]: info: May 23 13:49:07 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:07 volumio volumio[2156]: info: sendMpdCommand stop took 26 milliseconds May 23 13:49:07 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:07 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:07 volumio volumio[2156]: info: sendMpdCommand stop took 6 milliseconds May 23 13:49:07 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand clear May 23 13:49:07 volumio volumio[2156]: info: May 23 13:49:07 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:07 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:07 volumio volumio[2156]: info: sendMpdCommand status took 16 milliseconds May 23 13:49:07 volumio volumio[2156]: info: sendMpdCommand clear took 14 milliseconds May 23 13:49:07 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:07 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:07 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand load "https://sr15.inmystream.it/stream/lattemiele?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0ODAwMDgwNSwiaXNzIjoidGlzcnYifQ.AO5kga4GMGmkj5xLOA6gsIRH1LxLJ2CjiKyZ0UAf7VU" May 23 13:49:07 volumio volumio[2156]: info: touch_display: Setting screensaver timeout to 120 seconds. May 23 13:49:07 volumio volumio[2156]: error: updateQueue error: null May 23 13:49:07 volumio volumio[2156]: info: ------------------------------ 400ms May 23 13:49:07 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 398 milliseconds May 23 13:49:07 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand add "https://sr15.inmystream.it/stream/lattemiele?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0ODAwMDgwNSwiaXNzIjoidGlzcnYifQ.AO5kga4GMGmkj5xLOA6gsIRH1LxLJ2CjiKyZ0UAf7VU" May 23 13:49:07 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:07 volumio volumio[2156]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 23 13:49:07 volumio volumio[2156]: info: ------------------------------ 419ms May 23 13:49:07 volumio volumio[2156]: info: May 23 13:49:07 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:07 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:07 volumio volumio[2156]: info: sendMpdCommand add "https://sr15.inmystream.it/stream/lattemiele?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0ODAwMDgwNSwiaXNzIjoidGlzcnYifQ.AO5kga4GMGmkj5xLOA6gsIRH1LxLJ2CjiKyZ0UAf7VU" took 3 milliseconds May 23 13:49:07 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 13:49:07 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand play May 23 13:49:07 volumio volumio[2156]: info: ------------------------------ 3ms May 23 13:49:07 volumio volumio[2156]: info: sendMpdCommand play took 1 milliseconds May 23 13:49:08 volumio volumio[2156]: info: May 23 13:49:08 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:08 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:08 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:08 volumio volumio[2156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 23 13:49:08 volumio volumio[2156]: info: FusionDsp - ---- read samplerate from file: 44100 May 23 13:49:08 volumio volumio[2156]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 13:49:08 volumio volumio[2156]: info: FusionDsp - Loudness is ON true May 23 13:49:08 volumio volumio[2156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 23 13:49:08 volumio volumio[2156]: info: FusionDsp - ---- read samplerate from file: 44100 May 23 13:49:08 volumio volumio[2156]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 13:49:08 volumio volumio[2156]: info: FusionDsp - Loudness is ON true May 23 13:49:08 volumio volumio[2156]: info: May 23 13:49:08 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:08 volumio volumio[2156]: info: sendMpdCommand status took 11 milliseconds May 23 13:49:08 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:08 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:08 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:08 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:08 volumio volumio[2156]: info: sendMpdCommand status took 2 milliseconds May 23 13:49:08 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 1 milliseconds May 23 13:49:08 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:08 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:08 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:08 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:08 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:08 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:08 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"lattemiele?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0ODAwMDgwNSwiaXNzIjoidGlzcnYifQ.AO5kga4GMGmkj5xLOA6gsIRH1LxLJ2CjiKyZ0UAf7VU","artist":null,"album":null,"uri":"https://sr15.inmystream.it/stream/lattemiele?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0ODAwMDgwNSwiaXNzIjoidGlzcnYifQ.AO5kga4GMGmkj5xLOA6gsIRH1LxLJ2CjiKyZ0UAf7VU","trackType":""} May 23 13:49:08 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:08 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:08 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus stop May 23 13:49:08 volumio volumio[2156]: info: ------------------------------ 19ms May 23 13:49:08 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 4 milliseconds May 23 13:49:08 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:08 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:08 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:08 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:08 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"lattemiele?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0ODAwMDgwNSwiaXNzIjoidGlzcnYifQ.AO5kga4GMGmkj5xLOA6gsIRH1LxLJ2CjiKyZ0UAf7VU","artist":null,"album":null,"uri":"https://sr15.inmystream.it/stream/lattemiele?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0ODAwMDgwNSwiaXNzIjoidGlzcnYifQ.AO5kga4GMGmkj5xLOA6gsIRH1LxLJ2CjiKyZ0UAf7VU","trackType":""} May 23 13:49:08 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:08 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:08 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus play May 23 13:49:08 volumio volumio[2156]: info: Received an update from plugin. extracting info from payload May 23 13:49:08 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:08 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:08 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:08 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:08 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:08 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:08 volumio volumio[2156]: info: ------------------------------ 22ms May 23 13:49:08 volumio volumio[2156]: info: touch_display: Setting screensaver timeout to 0 seconds. May 23 13:49:08 volumio volumio[2156]: info: May 23 13:49:08 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:08 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:08 volumio volumio[2156]: info: May 23 13:49:08 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:08 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:08 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:08 volumio volumio[2156]: info: ------------------------------ 5ms May 23 13:49:08 volumio volumio[2156]: info: sendMpdCommand status took 3 milliseconds May 23 13:49:08 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:08 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:08 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 1 milliseconds May 23 13:49:08 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:08 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:08 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:08 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:08 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1360,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"TIZIANO FERRO - LA DIFFERENZA TRA ME E TE ","artist":"LATTEMIELE","album":null,"uri":"https://sr15.inmystream.it/stream/lattemiele?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0ODAwMDgwNSwiaXNzIjoidGlzcnYifQ.AO5kga4GMGmkj5xLOA6gsIRH1LxLJ2CjiKyZ0UAf7VU","trackType":""} May 23 13:49:08 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:08 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:08 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus play May 23 13:49:08 volumio volumio[2156]: info: Received an update from plugin. extracting info from payload May 23 13:49:08 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:08 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:08 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:08 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:08 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:08 volumio volumio[2156]: info: ------------------------------ 16ms May 23 13:49:11 volumio volumio[2156]: info: Preload queue cleared May 23 13:49:11 volumio volumio[2156]: info: CoreCommandRouter::volumioReplaceandPlayItems May 23 13:49:11 volumio volumio[2156]: info: CoreStateMachine::ClearQueue May 23 13:49:11 volumio volumio[2156]: info: CoreStateMachine::stop May 23 13:49:11 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:11 volumio volumio[2156]: info: CoreStateMachine::stPlaybackTimer May 23 13:49:11 volumio volumio[2156]: info: CoreStateMachine::updateTrackBlock May 23 13:49:11 volumio volumio[2156]: info: CorePlayQueue::getTrackBlock May 23 13:49:11 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:11 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:11 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:11 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:11 volumio volumio[2156]: info: CoreStateMachine::serviceStop May 23 13:49:11 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:11 volumio volumio[2156]: info: CoreCommandRouter::serviceStop May 23 13:49:11 volumio volumio[2156]: info: [1748000951962] ControllerWebradio::stop May 23 13:49:11 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand stop May 23 13:49:11 volumio volumio[2156]: info: CorePlayQueue::clearPlayQueue May 23 13:49:11 volumio volumio[2156]: info: CorePlayQueue::saveQueue May 23 13:49:11 volumio volumio[2156]: info: CoreCommandRouter::volumioPushQueue May 23 13:49:11 volumio volumio[2156]: info: CoreStateMachine::addQueueItems May 23 13:49:11 volumio volumio[2156]: info: CorePlayQueue::addQueueItems May 23 13:49:11 volumio volumio[2156]: info: Preload queue cleared May 23 13:49:11 volumio volumio[2156]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s210249 May 23 13:49:11 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 23 13:49:11 volumio volumio[2156]: info: CoreCommandRouter::volumioPushQueue May 23 13:49:11 volumio volumio[2156]: info: CorePlayQueue::saveQueue May 23 13:49:11 volumio volumio[2156]: info: CoreStateMachine::updateTrackBlock May 23 13:49:11 volumio volumio[2156]: info: CorePlayQueue::getTrackBlock May 23 13:49:11 volumio volumio[2156]: info: CoreCommandRouter::volumioPlay May 23 13:49:11 volumio volumio[2156]: info: CoreStateMachine::play index 0 May 23 13:49:11 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:11 volumio volumio[2156]: info: CoreStateMachine::stop May 23 13:49:11 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:11 volumio volumio[2156]: info: CoreStateMachine::play index undefined May 23 13:49:11 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:11 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:11 volumio volumio[2156]: info: CoreStateMachine::startPlaybackTimer May 23 13:49:11 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:11 volumio volumio[2156]: info: [1748000951978] ControllerWebradio::clearAddPlayTrack May 23 13:49:11 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand stop May 23 13:49:11 volumio volumio[2156]: info: May 23 13:49:11 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:11 volumio volumio[2156]: info: sendMpdCommand stop took 22 milliseconds May 23 13:49:11 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:11 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:11 volumio volumio[2156]: info: sendMpdCommand stop took 7 milliseconds May 23 13:49:11 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand clear May 23 13:49:11 volumio volumio[2156]: info: May 23 13:49:11 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:11 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:11 volumio volumio[2156]: info: sendMpdCommand status took 12 milliseconds May 23 13:49:11 volumio volumio[2156]: info: sendMpdCommand clear took 11 milliseconds May 23 13:49:11 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:11 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:11 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand load "https://ice09.fluidstream.net/fluid0904.mp3?FLID=3" May 23 13:49:12 volumio volumio[2156]: info: touch_display: Setting screensaver timeout to 120 seconds. May 23 13:49:13 volumio volumio[2156]: error: updateQueue error: null May 23 13:49:13 volumio volumio[2156]: info: ------------------------------ 1148ms May 23 13:49:13 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 1146 milliseconds May 23 13:49:13 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand add "https://ice09.fluidstream.net/fluid0904.mp3?FLID=3" May 23 13:49:13 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:13 volumio volumio[2156]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 23 13:49:13 volumio volumio[2156]: info: ------------------------------ 1161ms May 23 13:49:13 volumio volumio[2156]: info: May 23 13:49:13 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:13 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:13 volumio volumio[2156]: info: sendMpdCommand add "https://ice09.fluidstream.net/fluid0904.mp3?FLID=3" took 1 milliseconds May 23 13:49:13 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 13:49:13 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand play May 23 13:49:13 volumio volumio[2156]: info: ------------------------------ 2ms May 23 13:49:13 volumio volumio[2156]: info: sendMpdCommand play took 2 milliseconds May 23 13:49:14 volumio volumio[2156]: info: May 23 13:49:14 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:14 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:14 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:14 volumio volumio[2156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 23 13:49:14 volumio volumio[2156]: info: FusionDsp - ---- read samplerate from file: 44100 May 23 13:49:14 volumio volumio[2156]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 13:49:14 volumio volumio[2156]: info: FusionDsp - Loudness is ON true May 23 13:49:14 volumio volumio[2156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 23 13:49:14 volumio volumio[2156]: info: FusionDsp - ---- read samplerate from file: 44100 May 23 13:49:14 volumio volumio[2156]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 13:49:14 volumio volumio[2156]: info: FusionDsp - Loudness is ON true May 23 13:49:14 volumio volumio[2156]: info: May 23 13:49:14 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:14 volumio volumio[2156]: info: sendMpdCommand status took 15 milliseconds May 23 13:49:14 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:14 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:14 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:14 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:14 volumio volumio[2156]: info: sendMpdCommand status took 2 milliseconds May 23 13:49:14 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 1 milliseconds May 23 13:49:14 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:14 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:14 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:14 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:14 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:14 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:14 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"fluid0904.mp3?FLID=3","artist":null,"album":null,"uri":"https://ice09.fluidstream.net/fluid0904.mp3?FLID=3","trackType":"mp3"} May 23 13:49:14 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:14 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:14 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus stop May 23 13:49:14 volumio volumio[2156]: info: ------------------------------ 19ms May 23 13:49:14 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 2 milliseconds May 23 13:49:14 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:14 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:14 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:14 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:14 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"fluid0904.mp3?FLID=3","artist":null,"album":null,"uri":"https://ice09.fluidstream.net/fluid0904.mp3?FLID=3","trackType":"mp3"} May 23 13:49:14 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:14 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:14 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus play May 23 13:49:14 volumio volumio[2156]: info: Received an update from plugin. extracting info from payload May 23 13:49:14 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:14 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:14 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:14 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:14 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:14 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:14 volumio volumio[2156]: info: ------------------------------ 17ms May 23 13:49:14 volumio volumio[2156]: info: touch_display: Setting screensaver timeout to 0 seconds. May 23 13:49:15 volumio volumio[2156]: info: Preload queue cleared May 23 13:49:15 volumio volumio[2156]: info: CoreCommandRouter::volumioReplaceandPlayItems May 23 13:49:15 volumio volumio[2156]: info: CoreStateMachine::ClearQueue May 23 13:49:15 volumio volumio[2156]: info: CoreStateMachine::stop May 23 13:49:15 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:15 volumio volumio[2156]: info: CoreStateMachine::stPlaybackTimer May 23 13:49:15 volumio volumio[2156]: info: CoreStateMachine::updateTrackBlock May 23 13:49:15 volumio volumio[2156]: info: CorePlayQueue::getTrackBlock May 23 13:49:15 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:15 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:15 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:15 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:15 volumio volumio[2156]: info: CoreStateMachine::serviceStop May 23 13:49:15 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:15 volumio volumio[2156]: info: CoreCommandRouter::serviceStop May 23 13:49:15 volumio volumio[2156]: info: [1748000955851] ControllerWebradio::stop May 23 13:49:15 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand stop May 23 13:49:15 volumio volumio[2156]: info: CorePlayQueue::clearPlayQueue May 23 13:49:15 volumio volumio[2156]: info: CorePlayQueue::saveQueue May 23 13:49:15 volumio volumio[2156]: info: CoreCommandRouter::volumioPushQueue May 23 13:49:15 volumio volumio[2156]: info: CoreStateMachine::addQueueItems May 23 13:49:15 volumio volumio[2156]: info: CorePlayQueue::addQueueItems May 23 13:49:15 volumio volumio[2156]: info: Preload queue cleared May 23 13:49:15 volumio volumio[2156]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s73977 May 23 13:49:15 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 23 13:49:15 volumio volumio[2156]: info: CoreCommandRouter::volumioPushQueue May 23 13:49:15 volumio volumio[2156]: info: CorePlayQueue::saveQueue May 23 13:49:15 volumio volumio[2156]: info: CoreStateMachine::updateTrackBlock May 23 13:49:15 volumio volumio[2156]: info: CorePlayQueue::getTrackBlock May 23 13:49:15 volumio volumio[2156]: info: CoreCommandRouter::volumioPlay May 23 13:49:15 volumio volumio[2156]: info: CoreStateMachine::play index 0 May 23 13:49:15 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:15 volumio volumio[2156]: info: CoreStateMachine::stop May 23 13:49:15 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:15 volumio volumio[2156]: info: CoreStateMachine::play index undefined May 23 13:49:15 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:15 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:15 volumio volumio[2156]: info: CoreStateMachine::startPlaybackTimer May 23 13:49:15 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:15 volumio volumio[2156]: info: [1748000955866] ControllerWebradio::clearAddPlayTrack May 23 13:49:15 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand stop May 23 13:49:15 volumio volumio[2156]: info: May 23 13:49:15 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:15 volumio volumio[2156]: info: sendMpdCommand stop took 20 milliseconds May 23 13:49:15 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:15 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:15 volumio volumio[2156]: info: sendMpdCommand stop took 6 milliseconds May 23 13:49:15 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand clear May 23 13:49:15 volumio volumio[2156]: info: May 23 13:49:15 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:15 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:15 volumio volumio[2156]: info: sendMpdCommand status took 2 milliseconds May 23 13:49:15 volumio volumio[2156]: info: sendMpdCommand clear took 1 milliseconds May 23 13:49:15 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:15 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:15 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand load "https://sr15.inmystream.it/stream/lattemiele?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0ODAwMDgwNSwiaXNzIjoidGlzcnYifQ.AO5kga4GMGmkj5xLOA6gsIRH1LxLJ2CjiKyZ0UAf7VU" May 23 13:49:15 volumio volumio[2156]: info: touch_display: Setting screensaver timeout to 120 seconds. May 23 13:49:16 volumio volumio[2156]: error: updateQueue error: null May 23 13:49:16 volumio volumio[2156]: info: ------------------------------ 371ms May 23 13:49:16 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 369 milliseconds May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand add "https://sr15.inmystream.it/stream/lattemiele?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0ODAwMDgwNSwiaXNzIjoidGlzcnYifQ.AO5kga4GMGmkj5xLOA6gsIRH1LxLJ2CjiKyZ0UAf7VU" May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:16 volumio volumio[2156]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 23 13:49:16 volumio volumio[2156]: info: ------------------------------ 374ms May 23 13:49:16 volumio volumio[2156]: info: May 23 13:49:16 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:16 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:16 volumio volumio[2156]: info: sendMpdCommand add "https://sr15.inmystream.it/stream/lattemiele?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0ODAwMDgwNSwiaXNzIjoidGlzcnYifQ.AO5kga4GMGmkj5xLOA6gsIRH1LxLJ2CjiKyZ0UAf7VU" took 2 milliseconds May 23 13:49:16 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand play May 23 13:49:16 volumio volumio[2156]: info: ------------------------------ 3ms May 23 13:49:16 volumio volumio[2156]: info: sendMpdCommand play took 4 milliseconds May 23 13:49:16 volumio volumio[2156]: info: May 23 13:49:16 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:16 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:16 volumio volumio[2156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 23 13:49:16 volumio volumio[2156]: info: FusionDsp - ---- read samplerate from file: 44100 May 23 13:49:16 volumio volumio[2156]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 13:49:16 volumio volumio[2156]: info: FusionDsp - Loudness is ON true May 23 13:49:16 volumio volumio[2156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 23 13:49:16 volumio volumio[2156]: info: FusionDsp - ---- read samplerate from file: 44100 May 23 13:49:16 volumio volumio[2156]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 13:49:16 volumio volumio[2156]: info: FusionDsp - Loudness is ON true May 23 13:49:16 volumio volumio[2156]: info: May 23 13:49:16 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:16 volumio volumio[2156]: info: sendMpdCommand status took 16 milliseconds May 23 13:49:16 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:16 volumio volumio[2156]: info: sendMpdCommand status took 3 milliseconds May 23 13:49:16 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 3 milliseconds May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:16 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:16 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:16 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:16 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"lattemiele?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0ODAwMDgwNSwiaXNzIjoidGlzcnYifQ.AO5kga4GMGmkj5xLOA6gsIRH1LxLJ2CjiKyZ0UAf7VU","artist":null,"album":null,"uri":"https://sr15.inmystream.it/stream/lattemiele?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0ODAwMDgwNSwiaXNzIjoidGlzcnYifQ.AO5kga4GMGmkj5xLOA6gsIRH1LxLJ2CjiKyZ0UAf7VU","trackType":""} May 23 13:49:16 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:16 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:16 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus stop May 23 13:49:16 volumio volumio[2156]: info: ------------------------------ 21ms May 23 13:49:16 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 11 milliseconds May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:16 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:16 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:16 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:16 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"lattemiele?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0ODAwMDgwNSwiaXNzIjoidGlzcnYifQ.AO5kga4GMGmkj5xLOA6gsIRH1LxLJ2CjiKyZ0UAf7VU","artist":null,"album":null,"uri":"https://sr15.inmystream.it/stream/lattemiele?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0ODAwMDgwNSwiaXNzIjoidGlzcnYifQ.AO5kga4GMGmkj5xLOA6gsIRH1LxLJ2CjiKyZ0UAf7VU","trackType":""} May 23 13:49:16 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:16 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:16 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus play May 23 13:49:16 volumio volumio[2156]: info: Received an update from plugin. extracting info from payload May 23 13:49:16 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:16 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:16 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:16 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:16 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:16 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:16 volumio volumio[2156]: info: ------------------------------ 33ms May 23 13:49:16 volumio volumio[2156]: info: touch_display: Setting screensaver timeout to 0 seconds. May 23 13:49:16 volumio volumio[2156]: info: May 23 13:49:16 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:16 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:16 volumio volumio[2156]: info: May 23 13:49:16 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:16 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:16 volumio volumio[2156]: info: ------------------------------ 3ms May 23 13:49:16 volumio volumio[2156]: info: sendMpdCommand status took 2 milliseconds May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:16 volumio volumio[2156]: info: May 23 13:49:16 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:16 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:16 volumio volumio[2156]: info: May 23 13:49:16 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:16 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 4 milliseconds May 23 13:49:16 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:16 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:16 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:16 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:16 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":206,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"lattemiele?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0ODAwMDgwNSwiaXNzIjoidGlzcnYifQ.AO5kga4GMGmkj5xLOA6gsIRH1LxLJ2CjiKyZ0UAf7VU","artist":"LATTEMIELE","album":null,"uri":"https://sr15.inmystream.it/stream/lattemiele?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0ODAwMDgwNSwiaXNzIjoidGlzcnYifQ.AO5kga4GMGmkj5xLOA6gsIRH1LxLJ2CjiKyZ0UAf7VU","trackType":""} May 23 13:49:16 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:16 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:16 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus play May 23 13:49:16 volumio volumio[2156]: info: Received an update from plugin. extracting info from payload May 23 13:49:16 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:16 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:16 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:16 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:16 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:16 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:16 volumio volumio[2156]: info: ------------------------------ 19ms May 23 13:49:16 volumio volumio[2156]: info: ------------------------------ 16ms May 23 13:49:16 volumio volumio[2156]: info: sendMpdCommand status took 14 milliseconds May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:16 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 6 milliseconds May 23 13:49:16 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:16 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:16 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:16 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:16 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1360,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"TIZIANO FERRO - LA DIFFERENZA TRA ME E TE ","artist":"LATTEMIELE","album":null,"uri":"https://sr15.inmystream.it/stream/lattemiele?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc0ODAwMDgwNSwiaXNzIjoidGlzcnYifQ.AO5kga4GMGmkj5xLOA6gsIRH1LxLJ2CjiKyZ0UAf7VU","trackType":""} May 23 13:49:16 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:16 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:16 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus play May 23 13:49:16 volumio volumio[2156]: info: Received an update from plugin. extracting info from payload May 23 13:49:16 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:16 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:16 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:16 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:16 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:16 volumio volumio[2156]: info: ------------------------------ 37ms May 23 13:49:20 volumio volumio[2156]: info: Preload queue cleared May 23 13:49:20 volumio volumio[2156]: info: CoreCommandRouter::volumioReplaceandPlayItems May 23 13:49:20 volumio volumio[2156]: info: CoreStateMachine::ClearQueue May 23 13:49:20 volumio volumio[2156]: info: CoreStateMachine::stop May 23 13:49:20 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:20 volumio volumio[2156]: info: CoreStateMachine::stPlaybackTimer May 23 13:49:20 volumio volumio[2156]: info: CoreStateMachine::updateTrackBlock May 23 13:49:20 volumio volumio[2156]: info: CorePlayQueue::getTrackBlock May 23 13:49:20 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:20 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:20 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:20 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:20 volumio volumio[2156]: info: CoreStateMachine::serviceStop May 23 13:49:20 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:20 volumio volumio[2156]: info: CoreCommandRouter::serviceStop May 23 13:49:20 volumio volumio[2156]: info: [1748000960832] ControllerWebradio::stop May 23 13:49:20 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand stop May 23 13:49:20 volumio volumio[2156]: info: CorePlayQueue::clearPlayQueue May 23 13:49:20 volumio volumio[2156]: info: CorePlayQueue::saveQueue May 23 13:49:20 volumio volumio[2156]: info: CoreCommandRouter::volumioPushQueue May 23 13:49:20 volumio volumio[2156]: info: CoreStateMachine::addQueueItems May 23 13:49:20 volumio volumio[2156]: info: CorePlayQueue::addQueueItems May 23 13:49:20 volumio volumio[2156]: info: Preload queue cleared May 23 13:49:20 volumio volumio[2156]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s295386&filter=s:popular May 23 13:49:20 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 23 13:49:20 volumio volumio[2156]: info: CoreCommandRouter::volumioPushQueue May 23 13:49:20 volumio volumio[2156]: info: CorePlayQueue::saveQueue May 23 13:49:20 volumio volumio[2156]: info: CoreStateMachine::updateTrackBlock May 23 13:49:20 volumio volumio[2156]: info: CorePlayQueue::getTrackBlock May 23 13:49:20 volumio volumio[2156]: info: CoreCommandRouter::volumioPlay May 23 13:49:20 volumio volumio[2156]: info: CoreStateMachine::play index 0 May 23 13:49:20 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:20 volumio volumio[2156]: info: CoreStateMachine::stop May 23 13:49:20 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:20 volumio volumio[2156]: info: CoreStateMachine::play index undefined May 23 13:49:20 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:20 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:20 volumio volumio[2156]: info: CoreStateMachine::startPlaybackTimer May 23 13:49:20 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:20 volumio volumio[2156]: info: [1748000960854] ControllerWebradio::clearAddPlayTrack May 23 13:49:20 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand stop May 23 13:49:20 volumio volumio[2156]: info: May 23 13:49:20 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:20 volumio volumio[2156]: info: sendMpdCommand stop took 28 milliseconds May 23 13:49:20 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:20 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:20 volumio volumio[2156]: info: sendMpdCommand stop took 8 milliseconds May 23 13:49:20 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand clear May 23 13:49:20 volumio volumio[2156]: info: May 23 13:49:20 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:20 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:20 volumio volumio[2156]: info: sendMpdCommand status took 3 milliseconds May 23 13:49:20 volumio volumio[2156]: info: sendMpdCommand clear took 2 milliseconds May 23 13:49:20 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:20 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:20 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand load "https://stream10.xdevel.com/audio1s977004-1749/stream/icecast.audio" May 23 13:49:20 volumio volumio[2156]: error: updateQueue error: null May 23 13:49:20 volumio volumio[2156]: info: ------------------------------ 15ms May 23 13:49:20 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 14 milliseconds May 23 13:49:20 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:20 volumio volumio[2156]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 23 13:49:20 volumio volumio[2156]: info: ------------------------------ 20ms May 23 13:49:20 volumio volumio[2156]: info: touch_display: Setting screensaver timeout to 120 seconds. May 23 13:49:21 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand add "https://stream10.xdevel.com/audio1s977004-1749/stream/icecast.audio" May 23 13:49:21 volumio volumio[2156]: info: sendMpdCommand add "https://stream10.xdevel.com/audio1s977004-1749/stream/icecast.audio" took 0 milliseconds May 23 13:49:21 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 13:49:21 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand play May 23 13:49:21 volumio volumio[2156]: info: May 23 13:49:21 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:21 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:21 volumio volumio[2156]: info: sendMpdCommand play took 1 milliseconds May 23 13:49:21 volumio volumio[2156]: info: ------------------------------ 1ms May 23 13:49:21 volumio volumio[2156]: info: May 23 13:49:21 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:21 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:21 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:21 volumio volumio[2156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 23 13:49:21 volumio volumio[2156]: info: FusionDsp - ---- read samplerate from file: 44100 May 23 13:49:21 volumio volumio[2156]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 13:49:21 volumio volumio[2156]: info: FusionDsp - Loudness is ON true May 23 13:49:21 volumio volumio[2156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 23 13:49:21 volumio volumio[2156]: info: FusionDsp - ---- read samplerate from file: 44100 May 23 13:49:21 volumio volumio[2156]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 13:49:21 volumio volumio[2156]: info: FusionDsp - Loudness is ON true May 23 13:49:21 volumio volumio[2156]: info: May 23 13:49:21 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:21 volumio volumio[2156]: info: sendMpdCommand status took 9 milliseconds May 23 13:49:21 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:21 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:21 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:21 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:21 volumio volumio[2156]: info: sendMpdCommand status took 4 milliseconds May 23 13:49:21 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 4 milliseconds May 23 13:49:21 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:21 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:21 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:21 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:21 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:21 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:21 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"icecast.audio","artist":null,"album":null,"uri":"https://stream10.xdevel.com/audio1s977004-1749/stream/icecast.audio","trackType":"audio"} May 23 13:49:21 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:21 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:21 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus stop May 23 13:49:21 volumio volumio[2156]: info: ------------------------------ 17ms May 23 13:49:21 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 2 milliseconds May 23 13:49:21 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:21 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:21 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:21 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:21 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"icecast.audio","artist":null,"album":null,"uri":"https://stream10.xdevel.com/audio1s977004-1749/stream/icecast.audio","trackType":"audio"} May 23 13:49:21 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:21 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:21 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus play May 23 13:49:21 volumio volumio[2156]: info: Received an update from plugin. extracting info from payload May 23 13:49:21 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:21 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:21 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:21 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:21 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:21 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:21 volumio volumio[2156]: info: ------------------------------ 18ms May 23 13:49:21 volumio volumio[2156]: info: May 23 13:49:21 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:21 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:21 volumio volumio[2156]: info: May 23 13:49:21 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:21 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:21 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:21 volumio volumio[2156]: info: ------------------------------ 3ms May 23 13:49:21 volumio volumio[2156]: info: sendMpdCommand status took 2 milliseconds May 23 13:49:21 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:21 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:21 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 3 milliseconds May 23 13:49:21 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:21 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:21 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:21 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:21 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":" ","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio1s977004-1749/stream/icecast.audio","trackType":"audio"} May 23 13:49:21 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:21 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:21 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus play May 23 13:49:21 volumio volumio[2156]: info: Received an update from plugin. extracting info from payload May 23 13:49:21 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:21 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:21 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:21 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:21 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:21 volumio volumio[2156]: info: ------------------------------ 20ms May 23 13:49:21 volumio volumio[2156]: info: touch_display: Setting screensaver timeout to 0 seconds. May 23 13:49:25 volumio volumio[2156]: info: Preload queue cleared May 23 13:49:25 volumio volumio[2156]: info: CoreCommandRouter::volumioReplaceandPlayItems May 23 13:49:25 volumio volumio[2156]: info: CoreStateMachine::ClearQueue May 23 13:49:25 volumio volumio[2156]: info: CoreStateMachine::stop May 23 13:49:25 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:25 volumio volumio[2156]: info: CoreStateMachine::stPlaybackTimer May 23 13:49:25 volumio volumio[2156]: info: CoreStateMachine::updateTrackBlock May 23 13:49:25 volumio volumio[2156]: info: CorePlayQueue::getTrackBlock May 23 13:49:25 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:25 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:25 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:25 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:25 volumio volumio[2156]: info: CoreStateMachine::serviceStop May 23 13:49:25 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:25 volumio volumio[2156]: info: CoreCommandRouter::serviceStop May 23 13:49:25 volumio volumio[2156]: info: [1748000965631] ControllerWebradio::stop May 23 13:49:25 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand stop May 23 13:49:25 volumio volumio[2156]: info: CorePlayQueue::clearPlayQueue May 23 13:49:25 volumio volumio[2156]: info: CorePlayQueue::saveQueue May 23 13:49:25 volumio volumio[2156]: info: CoreCommandRouter::volumioPushQueue May 23 13:49:25 volumio volumio[2156]: info: CoreStateMachine::addQueueItems May 23 13:49:25 volumio volumio[2156]: info: CorePlayQueue::addQueueItems May 23 13:49:25 volumio volumio[2156]: info: Preload queue cleared May 23 13:49:25 volumio volumio[2156]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s306882&filter=s:popular May 23 13:49:25 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 23 13:49:25 volumio volumio[2156]: info: May 23 13:49:25 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:25 volumio volumio[2156]: info: sendMpdCommand stop took 45 milliseconds May 23 13:49:25 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:25 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:25 volumio volumio[2156]: info: sendMpdCommand status took 19 milliseconds May 23 13:49:25 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:25 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:25 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 3 milliseconds May 23 13:49:25 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:25 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:25 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:25 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:25 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:25 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:25 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:25 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:25 volumio volumio[2156]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":" ","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio1s977004-1749/stream/icecast.audio","trackType":"audio"} May 23 13:49:25 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:25 volumio volumio[2156]: info: CoreStateMachine::syncState stateService stop May 23 13:49:25 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus stop May 23 13:49:25 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:25 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:25 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:25 volumio volumio[2156]: info: No code May 23 13:49:25 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:25 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:25 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:25 volumio volumio[2156]: info: ------------------------------ 43ms May 23 13:49:25 volumio volumio[2156]: info: touch_display: Setting screensaver timeout to 120 seconds. May 23 13:49:26 volumio volumio[2156]: info: CoreCommandRouter::volumioPushQueue May 23 13:49:26 volumio volumio[2156]: info: CorePlayQueue::saveQueue May 23 13:49:26 volumio volumio[2156]: info: CoreStateMachine::updateTrackBlock May 23 13:49:26 volumio volumio[2156]: info: CorePlayQueue::getTrackBlock May 23 13:49:26 volumio volumio[2156]: info: CoreCommandRouter::volumioPlay May 23 13:49:26 volumio volumio[2156]: info: CoreStateMachine::play index 0 May 23 13:49:26 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:26 volumio volumio[2156]: info: CoreStateMachine::stop May 23 13:49:26 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:26 volumio volumio[2156]: info: CoreStateMachine::play index undefined May 23 13:49:26 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:26 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:26 volumio volumio[2156]: info: CoreStateMachine::startPlaybackTimer May 23 13:49:26 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:26 volumio volumio[2156]: info: [1748000966029] ControllerWebradio::clearAddPlayTrack May 23 13:49:26 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand stop May 23 13:49:26 volumio volumio[2156]: info: sendMpdCommand stop took 2 milliseconds May 23 13:49:26 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand clear May 23 13:49:26 volumio volumio[2156]: info: May 23 13:49:26 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:26 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:26 volumio volumio[2156]: info: sendMpdCommand clear took 1 milliseconds May 23 13:49:26 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand load "http://stream.rockantenne.at/rockantenne-oesterreich/stream/mp3?aw_0_1st.playerid=tunein.com" May 23 13:49:26 volumio volumio[2156]: error: updateQueue error: null May 23 13:49:26 volumio volumio[2156]: info: ------------------------------ 2ms May 23 13:49:26 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand add "http://stream.rockantenne.at/rockantenne-oesterreich/stream/mp3?aw_0_1st.playerid=tunein.com" May 23 13:49:26 volumio volumio[2156]: info: May 23 13:49:26 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:26 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:26 volumio volumio[2156]: info: sendMpdCommand add "http://stream.rockantenne.at/rockantenne-oesterreich/stream/mp3?aw_0_1st.playerid=tunein.com" took 0 milliseconds May 23 13:49:26 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 13:49:26 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand play May 23 13:49:26 volumio volumio[2156]: info: ------------------------------ 4ms May 23 13:49:26 volumio volumio[2156]: info: sendMpdCommand play took 3 milliseconds May 23 13:49:27 volumio volumio[2156]: info: May 23 13:49:27 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:27 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:27 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:27 volumio volumio[2156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 23 13:49:27 volumio volumio[2156]: info: FusionDsp - ---- read samplerate from file: 44100 May 23 13:49:27 volumio volumio[2156]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 13:49:27 volumio volumio[2156]: info: FusionDsp - Loudness is ON true May 23 13:49:27 volumio volumio[2156]: info: May 23 13:49:27 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:27 volumio volumio[2156]: info: sendMpdCommand status took 7 milliseconds May 23 13:49:27 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:27 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:27 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:27 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:27 volumio volumio[2156]: info: sendMpdCommand status took 2 milliseconds May 23 13:49:27 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 1 milliseconds May 23 13:49:27 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:27 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:27 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:27 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:27 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:27 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:27 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.rockantenne.at/rockantenne-oesterreich/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} May 23 13:49:27 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:27 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:27 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus stop May 23 13:49:27 volumio volumio[2156]: info: ------------------------------ 11ms May 23 13:49:27 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 1 milliseconds May 23 13:49:27 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:27 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:27 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:27 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:27 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.rockantenne.at/rockantenne-oesterreich/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} May 23 13:49:27 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:27 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:27 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus play May 23 13:49:27 volumio volumio[2156]: info: Received an update from plugin. extracting info from payload May 23 13:49:27 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:27 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:27 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:27 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:27 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:27 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:27 volumio volumio[2156]: info: ------------------------------ 23ms May 23 13:49:27 volumio volumio[2156]: info: May 23 13:49:27 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:27 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:27 volumio volumio[2156]: info: May 23 13:49:27 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:27 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:27 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:27 volumio volumio[2156]: info: May 23 13:49:27 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:27 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:27 volumio volumio[2156]: info: May 23 13:49:27 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:27 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:27 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:27 volumio volumio[2156]: info: ------------------------------ 10ms May 23 13:49:27 volumio volumio[2156]: info: sendMpdCommand status took 9 milliseconds May 23 13:49:27 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:27 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:27 volumio volumio[2156]: info: ------------------------------ 5ms May 23 13:49:27 volumio volumio[2156]: info: sendMpdCommand status took 4 milliseconds May 23 13:49:27 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 1 milliseconds May 23 13:49:27 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:27 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:27 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:27 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:27 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:27 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:27 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1480,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Def Leppard - Let's get rocked","artist":"ROCK ANTENNE Österreich","album":null,"uri":"http://stream.rockantenne.at/rockantenne-oesterreich/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} May 23 13:49:27 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:27 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:27 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus play May 23 13:49:27 volumio volumio[2156]: info: Received an update from plugin. extracting info from payload May 23 13:49:27 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:27 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:27 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:27 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:27 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:27 volumio volumio[2156]: info: ------------------------------ 29ms May 23 13:49:27 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 29 milliseconds May 23 13:49:27 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:27 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:27 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:27 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:27 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1606,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Def Leppard - Let's get rocked","artist":"ROCK ANTENNE Österreich","album":null,"uri":"http://stream.rockantenne.at/rockantenne-oesterreich/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} May 23 13:49:27 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:27 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:27 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus play May 23 13:49:27 volumio volumio[2156]: info: Received an update from plugin. extracting info from payload May 23 13:49:27 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:27 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:27 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:27 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:27 volumio volumio[2156]: info: ------------------------------ 49ms May 23 13:49:27 volumio volumio[2156]: info: touch_display: Setting screensaver timeout to 0 seconds. May 23 13:49:29 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 13:49:29 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 13:49:29 volumio volumio[2156]: info: Discovery: Getting this device information May 23 13:49:29 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:49:29 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 13:49:30 volumio volumio[2156]: info: Preload queue cleared May 23 13:49:30 volumio volumio[2156]: info: CoreCommandRouter::volumioReplaceandPlayItems May 23 13:49:30 volumio volumio[2156]: info: CoreStateMachine::ClearQueue May 23 13:49:30 volumio volumio[2156]: info: CoreStateMachine::stop May 23 13:49:30 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:30 volumio volumio[2156]: info: CoreStateMachine::stPlaybackTimer May 23 13:49:30 volumio volumio[2156]: info: CoreStateMachine::updateTrackBlock May 23 13:49:30 volumio volumio[2156]: info: CorePlayQueue::getTrackBlock May 23 13:49:30 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:30 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:30 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:30 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:30 volumio volumio[2156]: info: CoreStateMachine::serviceStop May 23 13:49:30 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:30 volumio volumio[2156]: info: CoreCommandRouter::serviceStop May 23 13:49:30 volumio volumio[2156]: info: [1748000970998] ControllerWebradio::stop May 23 13:49:30 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand stop May 23 13:49:30 volumio volumio[2156]: info: CorePlayQueue::clearPlayQueue May 23 13:49:30 volumio volumio[2156]: info: CorePlayQueue::saveQueue May 23 13:49:30 volumio volumio[2156]: info: CoreCommandRouter::volumioPushQueue May 23 13:49:30 volumio volumio[2156]: info: CoreStateMachine::addQueueItems May 23 13:49:30 volumio volumio[2156]: info: CorePlayQueue::addQueueItems May 23 13:49:30 volumio volumio[2156]: info: Preload queue cleared May 23 13:49:31 volumio volumio[2156]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s42824 May 23 13:49:31 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 23 13:49:31 volumio volumio[2156]: info: May 23 13:49:31 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:31 volumio volumio[2156]: info: sendMpdCommand stop took 26 milliseconds May 23 13:49:31 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:31 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:31 volumio volumio[2156]: info: sendMpdCommand status took 7 milliseconds May 23 13:49:31 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:31 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:31 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 1 milliseconds May 23 13:49:31 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:31 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:31 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:31 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:31 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:31 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:31 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:31 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:31 volumio volumio[2156]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Def Leppard - Let's get rocked","artist":"ROCK ANTENNE Österreich","album":null,"uri":"http://stream.rockantenne.at/rockantenne-oesterreich/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} May 23 13:49:31 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:31 volumio volumio[2156]: info: CoreStateMachine::syncState stateService stop May 23 13:49:31 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus stop May 23 13:49:31 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:31 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:31 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:31 volumio volumio[2156]: info: No code May 23 13:49:31 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:31 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:31 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:31 volumio volumio[2156]: info: ------------------------------ 27ms May 23 13:49:31 volumio volumio[2156]: info: touch_display: Setting screensaver timeout to 120 seconds. May 23 13:49:31 volumio volumio[2156]: info: CoreCommandRouter::volumioPushQueue May 23 13:49:31 volumio volumio[2156]: info: CorePlayQueue::saveQueue May 23 13:49:31 volumio volumio[2156]: info: CoreStateMachine::updateTrackBlock May 23 13:49:31 volumio volumio[2156]: info: CorePlayQueue::getTrackBlock May 23 13:49:31 volumio volumio[2156]: info: CoreCommandRouter::volumioPlay May 23 13:49:31 volumio volumio[2156]: info: CoreStateMachine::play index 0 May 23 13:49:31 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:31 volumio volumio[2156]: info: CoreStateMachine::stop May 23 13:49:31 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:31 volumio volumio[2156]: info: CoreStateMachine::play index undefined May 23 13:49:31 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 13:49:31 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:31 volumio volumio[2156]: info: CoreStateMachine::startPlaybackTimer May 23 13:49:31 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:31 volumio volumio[2156]: info: [1748000971358] ControllerWebradio::clearAddPlayTrack May 23 13:49:31 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand stop May 23 13:49:31 volumio volumio[2156]: info: sendMpdCommand stop took 3 milliseconds May 23 13:49:31 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand clear May 23 13:49:31 volumio volumio[2156]: info: May 23 13:49:31 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:31 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:31 volumio volumio[2156]: info: sendMpdCommand clear took 1 milliseconds May 23 13:49:31 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand load "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" May 23 13:49:31 volumio volumio[2156]: error: updateQueue error: null May 23 13:49:31 volumio volumio[2156]: info: ------------------------------ 2ms May 23 13:49:32 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand add "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" May 23 13:49:32 volumio volumio[2156]: info: sendMpdCommand add "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" took 1 milliseconds May 23 13:49:32 volumio volumio[2156]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 13:49:32 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand play May 23 13:49:32 volumio volumio[2156]: info: May 23 13:49:32 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:32 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:32 volumio volumio[2156]: info: sendMpdCommand play took 2 milliseconds May 23 13:49:32 volumio volumio[2156]: info: ------------------------------ 1ms May 23 13:49:32 volumio volumio[2156]: info: May 23 13:49:32 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:32 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:32 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:32 volumio volumio[2156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 23 13:49:32 volumio volumio[2156]: info: FusionDsp - ---- read samplerate from file: 44100 May 23 13:49:32 volumio volumio[2156]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 13:49:32 volumio volumio[2156]: info: FusionDsp - Loudness is ON true May 23 13:49:32 volumio volumio[2156]: info: May 23 13:49:32 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:32 volumio volumio[2156]: info: sendMpdCommand status took 18 milliseconds May 23 13:49:32 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:32 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:32 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:32 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:32 volumio volumio[2156]: info: sendMpdCommand status took 2 milliseconds May 23 13:49:32 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 2 milliseconds May 23 13:49:32 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:32 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:32 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:32 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:32 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:32 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:32 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} May 23 13:49:32 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:32 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:32 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus stop May 23 13:49:32 volumio volumio[2156]: info: ------------------------------ 22ms May 23 13:49:32 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 1 milliseconds May 23 13:49:32 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:32 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:32 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:32 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:32 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} May 23 13:49:32 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:32 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:32 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus play May 23 13:49:32 volumio volumio[2156]: info: Received an update from plugin. extracting info from payload May 23 13:49:32 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:32 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:32 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:32 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:32 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:32 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:32 volumio volumio[2156]: info: ------------------------------ 11ms May 23 13:49:32 volumio volumio[2156]: info: May 23 13:49:32 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:32 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:32 volumio volumio[2156]: info: May 23 13:49:32 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:32 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:32 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:32 volumio volumio[2156]: info: ------------------------------ 6ms May 23 13:49:32 volumio volumio[2156]: info: sendMpdCommand status took 2 milliseconds May 23 13:49:32 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:32 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:32 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 2 milliseconds May 23 13:49:32 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:32 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:32 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:32 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:32 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1606,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Rosa Linn - Snap","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} May 23 13:49:32 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:32 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:32 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus play May 23 13:49:32 volumio volumio[2156]: info: Received an update from plugin. extracting info from payload May 23 13:49:32 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:32 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:32 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:32 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:32 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:32 volumio volumio[2156]: info: ------------------------------ 20ms May 23 13:49:32 volumio volumio[2156]: info: touch_display: Setting screensaver timeout to 0 seconds. May 23 13:49:37 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 23 13:49:37 volumio dbus-daemon[675]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.75' (uid=0 pid=8657 comm="timedatectl show --property=NTPSynchronized --valu") May 23 13:49:37 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 23 13:49:37 volumio dbus-daemon[675]: [system] Successfully activated service 'org.freedesktop.timedate1' May 23 13:49:37 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 23 13:49:37 volumio setdatetime-helper.sh[8656]: Time is already synchronized. May 23 13:49:37 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 23 13:49:37 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 23 13:49:39 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 13:49:39 volumio volumio[2156]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 13:49:39 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 23 13:49:52 volumio volumio[2156]: info: May 23 13:49:52 volumio volumio[2156]: ---------------------------- MPD announces system playlist update May 23 13:49:52 volumio volumio[2156]: info: Ignoring MPD Status Update May 23 13:49:52 volumio volumio[2156]: info: May 23 13:49:52 volumio volumio[2156]: ---------------------------- MPD announces state update: player May 23 13:49:52 volumio volumio[2156]: info: ControllerMpd::getState May 23 13:49:52 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand status May 23 13:49:52 volumio volumio[2156]: info: ------------------------------ 1ms May 23 13:49:52 volumio volumio[2156]: info: sendMpdCommand status took 2 milliseconds May 23 13:49:52 volumio volumio[2156]: verbose: ControllerMpd::parseState May 23 13:49:52 volumio volumio[2156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 13:49:52 volumio volumio[2156]: info: sendMpdCommand playlistinfo took 0 milliseconds May 23 13:49:52 volumio volumio[2156]: verbose: ControllerMpd::parseTrackInfo May 23 13:49:52 volumio volumio[2156]: info: ControllerMpd::pushState May 23 13:49:52 volumio volumio[2156]: info: CoreCommandRouter::servicePushState May 23 13:49:52 volumio volumio[2156]: info: CorePlayQueue::getTrack 0 May 23 13:49:52 volumio volumio[2156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22356,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"R.E.M. - Losing My Religion","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} May 23 13:49:52 volumio volumio[2156]: verbose: CURRENT POSITION 0 May 23 13:49:52 volumio volumio[2156]: info: CoreStateMachine::syncState stateService play May 23 13:49:52 volumio volumio[2156]: info: CoreStateMachine::syncState currentStatus play May 23 13:49:52 volumio volumio[2156]: info: Received an update from plugin. extracting info from payload May 23 13:49:52 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:52 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 13:49:52 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:52 volumio volumio[2156]: info: CoreStateMachine::pushState May 23 13:49:52 volumio volumio[2156]: info: CoreCommandRouter::volumioPushState May 23 13:49:53 volumio volumio[2156]: info: ------------------------------ 14ms May 23 13:49:56 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 23 13:49:56 volumio volumio[2156]: info: Preload queue cleared May 23 13:49:58 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 23 13:49:59 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 13:49:59 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 13:49:59 volumio volumio[2156]: info: Discovery: Getting this device information May 23 13:49:59 volumio volumio[2156]: info: CoreCommandRouter::volumioGetState May 23 13:49:59 volumio volumio[2156]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 13:49:59 volumio volumio[2156]: Use XMLElement.setAttribute instead May 23 13:49:59 volumio volumio[2156]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 13:49:59 volumio volumio[2156]: TypeError: children[i].attr(...).value is not a function May 23 13:49:59 volumio volumio[2156]: at /volumio/app/plugins/music_service/webradio/index.js:428:46 May 23 13:49:59 volumio volumio[2156]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 23 13:49:59 volumio volumio[2156]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 13:49:59 volumio sudo[8747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-23 13:48' May 23 13:49:59 volumio sudo[8747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="26d819930af572ef184e636a18ef1127de78f391" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 21 13:47:54 UTC 2025" VOLUMIO_VERSION="0.068" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="82c7b61aa1619bd495d6c4f648975de8"