Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: Preload queue cleared Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::ClearQueue Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stop Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stPlaybackTimer Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::updateTrackBlock Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrackBlock Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::serviceStop Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::serviceStop Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::stop Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::clearPlayQueue Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::saveQueue Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushQueue Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::addQueueItems Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::addQueueItems Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: Preload queue cleared Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: Adding Item to queue: webmer/5 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: Using cached record of: webmer/5 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushQueue Oct 31 18:39:02 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::saveQueue Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::updateTrackBlock Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrackBlock Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPlay Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::play index 0 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stop Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::play index undefined Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::startPlaybackTimer Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand stop took 25 milliseconds Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand stop took 9 milliseconds Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://motherearth.streamserver24.com/api/station/motherearth_klassik/art/8c286a354680a6c6d731d121-1696976953.jpg","uri":"https://motherearth.streamserver24.com/listen/motherearth_klassik/motherearth.klassik.aac","name":"Gorg Ramifski Leipzig Pro Arte Symphony Orchestra - Tschaikowski Symphony No 6 B Minor 'Pathetique'","title":"Tschaikowski Symphony No 6 B Minor 'Pathetique'","artist":"Gorg Ramifski Leipzig Pro Arte Symphony Orchestra","album":"Symphony No 6 B Minor 'Pathetique'","streaming":true,"disableUiControls":true,"duration":2042,"seek":0,"bitdepth":"24 bit","channels":2} Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: CURRENT POSITION 0 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState stateService stop Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState currentStatus stop Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: No code Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand clear Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 38 milliseconds Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand clear took 28 milliseconds Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: error: updateQueue error: null Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 6ms Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand consume 1 took 3 milliseconds Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: [1761932342230] [MotherEarth] set to consume mode, adding url: https://motherearth.streamserver24.com/listen/motherearth_klassik/motherearth.klassik.aac Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand add "https://motherearth.streamserver24.com/listen/motherearth_klassik/motherearth.klassik.aac" Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 47ms Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand add "https://motherearth.streamserver24.com/listen/motherearth_klassik/motherearth.klassik.aac" took 16 milliseconds Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand play Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 8ms Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand play took 8 milliseconds Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:39:02 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 6 milliseconds Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 78 milliseconds Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 76 milliseconds Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 89ms Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 6ms Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 3 milliseconds Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 88ms Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 8ms Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://motherearth.streamserver24.com/api/station/motherearth_klassik/art/8c286a354680a6c6d731d121-1696976953.jpg","uri":"https://motherearth.streamserver24.com/listen/motherearth_klassik/motherearth.klassik.aac","name":"Gorg Ramifski Leipzig Pro Arte Symphony Orchestra - Tschaikowski Symphony No 6 B Minor 'Pathetique'","title":"Tschaikowski Symphony No 6 B Minor 'Pathetique'","artist":"Gorg Ramifski Leipzig Pro Arte Symphony Orchestra","album":"Symphony No 6 B Minor 'Pathetique'","streaming":true,"disableUiControls":true,"duration":2042,"seek":0,"bitdepth":"24 bit","channels":2} Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: CURRENT POSITION 0 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState stateService play Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState currentStatus stop Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: [1761932342453] [MotherEarth] setting new timer with duration of 2042000 seconds. Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 1 Oct 31 18:39:05 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPlay Oct 31 18:39:05 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::play index undefined Oct 31 18:39:05 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:39:05 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:07 volumio4-rpi3-rasptouch volumio[1425]: info: VolumeController::SetAlsaVolume72 Oct 31 18:39:07 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:07 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:07 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:39:07 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:07 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioGetQueue Oct 31 18:39:07 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::getQueue Oct 31 18:39:07 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getQueue Oct 31 18:39:07 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Oct 31 18:39:07 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: SPOTIFY VOLUME 82 Oct 31 18:39:07 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: VOLUMIO VOLUME 72 Oct 31 18:39:07 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 31 18:39:07 volumio4-rpi3-rasptouch volumio[1425]: info: Setting Spotify Volume from Volumio: 72 Oct 31 18:39:07 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:39:07 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Oct 31 18:39:07 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:39:08 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: SETTING SPOTIFY VOLUME 72 Oct 31 18:39:08 volumio4-rpi3-rasptouch volumio[1425]: info: Sending Spotify command with payload to local API: /player/volume Oct 31 18:39:08 volumio4-rpi3-rasptouch go-librespot[1838]: time="2025-10-31T18:39:08+01:00" level=debug msg="update volume requested to 47185/65535" Oct 31 18:39:08 volumio4-rpi3-rasptouch go-librespot[1838]: time="2025-10-31T18:39:08+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 31 18:39:08 volumio4-rpi3-rasptouch go-librespot[1838]: time="2025-10-31T18:39:08+01:00" level=trace msg="emitting websocket event: volume" Oct 31 18:39:08 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: received: {"type":"volume","data":{"value":72,"max":100}} Oct 31 18:39:08 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED SPOTIFY VOLUME 72 Oct 31 18:39:09 volumio4-rpi3-rasptouch volumio[1425]: info: VolumeController::SetAlsaVolume80 Oct 31 18:39:09 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:09 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:09 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:39:09 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:09 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Oct 31 18:39:09 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: SPOTIFY VOLUME 72 Oct 31 18:39:09 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: VOLUMIO VOLUME 80 Oct 31 18:39:09 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 31 18:39:09 volumio4-rpi3-rasptouch volumio[1425]: info: Setting Spotify Volume from Volumio: 80 Oct 31 18:39:09 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:39:09 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Oct 31 18:39:09 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:39:10 volumio4-rpi3-rasptouch volumio[1425]: info: VolumeController::SetAlsaVolume79 Oct 31 18:39:10 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:10 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:10 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:39:10 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:10 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Oct 31 18:39:10 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: SPOTIFY VOLUME 80 Oct 31 18:39:10 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: VOLUMIO VOLUME 79 Oct 31 18:39:10 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 31 18:39:10 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:39:10 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Oct 31 18:39:10 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:39:11 volumio4-rpi3-rasptouch volumio[1425]: info: VolumeController::SetAlsaVolume87 Oct 31 18:39:11 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:11 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:11 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:39:11 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:11 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:39:11 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: SPOTIFY VOLUME 80 Oct 31 18:39:11 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: VOLUMIO VOLUME 87 Oct 31 18:39:11 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 31 18:39:11 volumio4-rpi3-rasptouch volumio[1425]: info: Setting Spotify Volume from Volumio: 87 Oct 31 18:39:11 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:39:11 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Oct 31 18:39:11 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:39:12 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: SETTING SPOTIFY VOLUME 87 Oct 31 18:39:12 volumio4-rpi3-rasptouch volumio[1425]: info: Sending Spotify command with payload to local API: /player/volume Oct 31 18:39:12 volumio4-rpi3-rasptouch go-librespot[1838]: time="2025-10-31T18:39:12+01:00" level=debug msg="update volume requested to 57015/65535" Oct 31 18:39:12 volumio4-rpi3-rasptouch go-librespot[1838]: time="2025-10-31T18:39:12+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 31 18:39:12 volumio4-rpi3-rasptouch go-librespot[1838]: time="2025-10-31T18:39:12+01:00" level=trace msg="emitting websocket event: volume" Oct 31 18:39:12 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: received: {"type":"volume","data":{"value":87,"max":100}} Oct 31 18:39:12 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED SPOTIFY VOLUME 87 Oct 31 18:39:15 volumio4-rpi3-rasptouch go-librespot[1838]: time="2025-10-31T18:39:15+01:00" level=trace msg="sent dealer ping" Oct 31 18:39:15 volumio4-rpi3-rasptouch go-librespot[1838]: time="2025-10-31T18:39:15+01:00" level=trace msg="received dealer pong" Oct 31 18:39:15 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 31 18:39:15 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 31 18:39:32 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 18:39:34 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 31 18:39:34 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 31 18:39:44 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Oct 31 18:39:44 volumio4-rpi3-rasptouch volumio[1425]: info: Preload queue cleared Oct 31 18:39:45 volumio4-rpi3-rasptouch go-librespot[1838]: time="2025-10-31T18:39:45+01:00" level=trace msg="sent dealer ping" Oct 31 18:39:45 volumio4-rpi3-rasptouch go-librespot[1838]: time="2025-10-31T18:39:45+01:00" level=trace msg="received dealer pong" Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: Preload queue cleared Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::ClearQueue Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stop Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stPlaybackTimer Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::updateTrackBlock Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrackBlock Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::serviceStop Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::serviceStop Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::stop Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::clearPlayQueue Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::saveQueue Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushQueue Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::addQueueItems Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::addQueueItems Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: Preload queue cleared Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: Adding Item to queue: webrp/12 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: Exploding uri webrp/12 in service radio_paradise Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushQueue Oct 31 18:39:49 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::saveQueue Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::updateTrackBlock Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrackBlock Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPlay Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::play index 0 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stop Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::play index undefined Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::startPlaybackTimer Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand stop took 30 milliseconds Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand stop took 12 milliseconds Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received motherearthradio Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand clear Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 21 milliseconds Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand clear took 13 milliseconds Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/serenity" Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: error: updateQueue error: null Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 20ms Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand add "http://stream.radioparadise.com/serenity" took 19 milliseconds Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand play Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 49ms Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 15ms Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand play took 7 milliseconds Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: touch_display: Setting screensaver timeout to 360 seconds. Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:39:49 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 26 milliseconds Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 14 milliseconds Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"serenity","artist":null,"album":null,"uri":"http://stream.radioparadise.com/serenity","trackType":""} Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: CURRENT POSITION 0 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState stateService play Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState currentStatus stop Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 47ms Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 7ms Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Serenity","artist":"Radio Paradise: Serenity (64k aac)","album":null,"uri":"http://stream.radioparadise.com/serenity","trackType":""} Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: CURRENT POSITION 0 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState stateService play Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState currentStatus play Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: Received an update from plugin. extracting info from payload Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 55ms Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 31 milliseconds Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 17 milliseconds Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Serenity","artist":"Radio Paradise: Serenity (64k aac)","album":null,"uri":"http://stream.radioparadise.com/serenity","trackType":""} Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: verbose: CURRENT POSITION 0 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState stateService play Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState currentStatus play Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: Received an update from plugin. extracting info from payload Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 69ms Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:39:49 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: Preload queue cleared Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::ClearQueue Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stop Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stPlaybackTimer Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::updateTrackBlock Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrackBlock Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::serviceStop Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::serviceStop Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::stop Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::clearPlayQueue Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::saveQueue Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushQueue Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::addQueueItems Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::addQueueItems Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: Preload queue cleared Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: Adding Item to queue: webrp/9 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: Exploding uri webrp/9 in service radio_paradise Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushQueue Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::saveQueue Oct 31 18:39:53 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::updateTrackBlock Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrackBlock Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPlay Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::play index 0 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stop Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::play index undefined Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::startPlaybackTimer Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: [RadioParadise] Matched station: Radio Paradise Radio 2050 (FLAC), chan=9 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand stop took 38 milliseconds Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand stop took 16 milliseconds Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/B00000DR6A.jpg","uri":"http://stream.radioparadise.com/global-flacm","name":"Peligro","title":"Peligro","artist":"Mano Negra","album":"Puta’s Fever","streaming":true,"disableUiControls":true,"duration":159,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: CURRENT POSITION 0 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState stateService stop Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState currentStatus stop Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: No code Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand clear Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 49 milliseconds Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand clear took 36 milliseconds Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: error: updateQueue error: null Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 26ms Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand consume 1 took 21 milliseconds Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: [1761932393593] [RadioParadise] set to consume mode, adding url: http://stream.radioparadise.com/radio2050-flacm Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/radio2050-flacm" Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 75ms Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand add "http://stream.radioparadise.com/radio2050-flacm" took 3 milliseconds Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand play Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 8ms Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand play took 15 milliseconds Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=9 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: [1761932393619] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=9 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: touch_display: Setting screensaver timeout to 360 seconds. Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:39:53 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 34 milliseconds Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 26 milliseconds Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 65ms Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 18ms Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 10 milliseconds Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 48ms Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 31 18:39:53 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 35ms Oct 31 18:39:54 volumio4-rpi3-rasptouch volumio[1425]: info: [1761932394142] [RadioParadise] received new metadata: {"time":5,"artist":"","title":null,"album":null,"year":null,"cover":"https://img.radioparadise.com/covers/l/.jpg","cover_med":"https://img.radioparadise.com/covers/m/.jpg","cover_small":"https://img.radioparadise.com/covers/s/.jpg"} Oct 31 18:39:54 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:54 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:39:54 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:54 volumio4-rpi3-rasptouch volumio[1425]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/.jpg","uri":"http://stream.radioparadise.com/radio2050-flacm","name":null,"title":null,"artist":"","album":null,"streaming":true,"disableUiControls":true,"duration":10,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Oct 31 18:39:54 volumio4-rpi3-rasptouch volumio[1425]: verbose: CURRENT POSITION 0 Oct 31 18:39:54 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState stateService play Oct 31 18:39:54 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState currentStatus stop Oct 31 18:39:54 volumio4-rpi3-rasptouch volumio[1425]: info: [1761932394145] [RadioParadise] setting new timer with duration of 10000 seconds. Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: Preload queue cleared Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::ClearQueue Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stop Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stPlaybackTimer Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::updateTrackBlock Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrackBlock Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::serviceStop Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::serviceStop Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::stop Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::clearPlayQueue Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::saveQueue Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushQueue Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::addQueueItems Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::addQueueItems Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: Preload queue cleared Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: Adding Item to queue: webrp/4 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: Using cached record of: webrp/4 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushQueue Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::saveQueue Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::updateTrackBlock Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrackBlock Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPlay Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::play index 0 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stop Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::play index undefined Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::startPlaybackTimer Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 18:39:58 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand stop took 36 milliseconds Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand stop took 16 milliseconds Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/.jpg","uri":"http://stream.radioparadise.com/radio2050-flacm","name":null,"title":null,"artist":"","album":null,"streaming":true,"disableUiControls":true,"duration":10,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: CURRENT POSITION 0 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState stateService stop Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState currentStatus stop Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: No code Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand clear Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 27 milliseconds Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand clear took 19 milliseconds Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-320" Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: error: updateQueue error: null Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 5ms Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-320" took 5 milliseconds Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand play Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 42ms Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 8ms Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand play took 8 milliseconds Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 28 milliseconds Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 4 milliseconds Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:39:58 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 6ms Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"319 Kbps","isStreaming":false,"title":"Folk Bitch Trio - Moth Song","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: CURRENT POSITION 0 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState stateService play Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState currentStatus stop Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: STATE SERVICE {"status":"play","position":0,"seek":779,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"319 Kbps","isStreaming":false,"title":"Folk Bitch Trio - Moth Song","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: verbose: CURRENT POSITION 0 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState stateService play Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState currentStatus play Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: Received an update from plugin. extracting info from payload Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 54ms Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 40ms Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:39:58 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:39:59 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 18:39:59 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::Close All Modals sent Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: Preload queue cleared Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::ClearQueue Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stop Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stPlaybackTimer Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::updateTrackBlock Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrackBlock Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::serviceStop Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::serviceStop Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::stop Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::clearPlayQueue Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::saveQueue Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushQueue Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::addQueueItems Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::addQueueItems Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: Preload queue cleared Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: Adding Item to queue: webrp/3 Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: Using cached record of: webrp/3 Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushQueue Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::saveQueue Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::updateTrackBlock Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrackBlock Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPlay Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::play index 0 Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stop Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::play index undefined Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::startPlaybackTimer Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: [RadioParadise] Matched station: Radio Paradise Global Mix (FLAC), chan=3 Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 18:40:01 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand stop took 36 milliseconds Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand stop took 18 milliseconds Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/.jpg","uri":"http://stream.radioparadise.com/radio2050-flacm","name":null,"title":null,"artist":"","album":null,"streaming":true,"disableUiControls":true,"duration":10,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: verbose: CURRENT POSITION 0 Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState stateService stop Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState currentStatus stop Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: No code Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:40:01 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand clear Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 33 milliseconds Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand clear took 23 milliseconds Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: error: updateQueue error: null Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 25ms Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand consume 1 took 25 milliseconds Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: [1761932402041] [RadioParadise] set to consume mode, adding url: http://stream.radioparadise.com/global-flacm Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/global-flacm" Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 61ms Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand add "http://stream.radioparadise.com/global-flacm" took 2 milliseconds Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand play Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 13ms Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand play took 5 milliseconds Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=3 Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: [1761932402058] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=3 Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: touch_display: Setting screensaver timeout to 360 seconds. Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:40:02 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 47 milliseconds Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 4ms Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 5 milliseconds Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 68ms Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 22ms Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: [1761932402563] [RadioParadise] received new metadata: {"time":25,"artist":"Mano Negra","title":"Peligro","album":"Puta’s Fever","year":"1989","cover":"https://img.radioparadise.com/covers/l/B00000DR6A.jpg","cover_med":"https://img.radioparadise.com/covers/m/B00000DR6A.jpg","cover_small":"https://img.radioparadise.com/covers/s/B00000DR6A.jpg"} Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/B00000DR6A.jpg","uri":"http://stream.radioparadise.com/global-flacm","name":"Peligro","title":"Peligro","artist":"Mano Negra","album":"Puta’s Fever","streaming":true,"disableUiControls":true,"duration":30,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: verbose: CURRENT POSITION 0 Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState stateService play Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState currentStatus stop Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: [1761932402566] [RadioParadise] setting new timer with duration of 30000 seconds. Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:02 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 1 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 2ms Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 2 milliseconds Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 4ms Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: Preload queue cleared Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::ClearQueue Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stop Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stPlaybackTimer Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::updateTrackBlock Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrackBlock Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::serviceStop Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::serviceStop Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::stop Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::clearPlayQueue Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::saveQueue Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushQueue Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::addQueueItems Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::addQueueItems Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: Preload queue cleared Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: Adding Item to queue: webrp/1 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: Using cached record of: webrp/1 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushQueue Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::saveQueue Oct 31 18:40:04 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::updateTrackBlock Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrackBlock Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPlay Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::play index 0 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stop Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::play index undefined Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::startPlaybackTimer Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: [RadioParadise] Matched station: Radio Paradise Mellow Mix (FLAC), chan=1 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand stop took 30 milliseconds Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand stop took 10 milliseconds Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/B00000DR6A.jpg","uri":"http://stream.radioparadise.com/global-flacm","name":"Peligro","title":"Peligro","artist":"Mano Negra","album":"Puta’s Fever","streaming":true,"disableUiControls":true,"duration":30,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: verbose: CURRENT POSITION 0 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState stateService stop Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState currentStatus stop Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: No code Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand clear Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 32 milliseconds Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand clear took 22 milliseconds Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: error: updateQueue error: null Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 4ms Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand consume 1 took 3 milliseconds Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: [1761932404473] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flacm Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flacm" Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 39ms Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flacm" took 4 milliseconds Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand play Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 7ms Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand play took 5 milliseconds Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=1 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: [1761932404487] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: [1761932404647] [RadioParadise] received new metadata: {"time":75,"artist":"The Beatles","title":"Hey Jude","album":"Hey Jude / Revolution","year":"1968","cover":"https://img.radioparadise.com/covers/l/24539.jpg","cover_med":"https://img.radioparadise.com/covers/m/24539.jpg","cover_small":"https://img.radioparadise.com/covers/s/24539.jpg"} Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/24539.jpg","uri":"https://stream.radioparadise.com/mellow-flacm","name":"Hey Jude","title":"Hey Jude","artist":"The Beatles","album":"Hey Jude / Revolution","streaming":true,"disableUiControls":true,"duration":80,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: verbose: CURRENT POSITION 0 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState stateService play Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState currentStatus stop Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: [1761932404648] [RadioParadise] setting new timer with duration of 80000 seconds. Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:04 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 1 Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 24 milliseconds Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:40:05 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 2 milliseconds Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 31ms Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 8ms Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 4ms Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 3 milliseconds Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 31 18:40:05 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 7ms Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: Preload queue cleared Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::ClearQueue Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stop Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stPlaybackTimer Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::updateTrackBlock Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrackBlock Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::serviceStop Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::serviceStop Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::stop Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::clearPlayQueue Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::saveQueue Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushQueue Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::addQueueItems Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::addQueueItems Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: Preload queue cleared Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: Adding Item to queue: webrp/1 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: Using cached record of: webrp/1 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushQueue Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::saveQueue Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::updateTrackBlock Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrackBlock Oct 31 18:40:06 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPlay Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::play index 0 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::stop Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::play index undefined Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::startPlaybackTimer Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: [RadioParadise] Matched station: Radio Paradise Mellow Mix (FLAC), chan=1 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand stop took 28 milliseconds Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand stop took 12 milliseconds Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/24539.jpg","uri":"https://stream.radioparadise.com/mellow-flacm","name":"Hey Jude","title":"Hey Jude","artist":"The Beatles","album":"Hey Jude / Revolution","streaming":true,"disableUiControls":true,"duration":80,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: CURRENT POSITION 0 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState stateService stop Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState currentStatus stop Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: No code Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand clear Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 35 milliseconds Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand clear took 25 milliseconds Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: error: updateQueue error: null Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 13ms Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand consume 1 took 7 milliseconds Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: [1761932406350] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flacm Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flacm" Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 46ms Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flacm" took 2 milliseconds Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand play Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 6ms Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand play took 5 milliseconds Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=1 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: [1761932406362] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 24 milliseconds Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:40:06 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 3 milliseconds Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 30ms Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 7ms Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: [1761932406522] [RadioParadise] received new metadata: {"time":73,"artist":"The Beatles","title":"Hey Jude","album":"Hey Jude / Revolution","year":"1968","cover":"https://img.radioparadise.com/covers/l/24539.jpg","cover_med":"https://img.radioparadise.com/covers/m/24539.jpg","cover_small":"https://img.radioparadise.com/covers/s/24539.jpg"} Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/24539.jpg","uri":"https://stream.radioparadise.com/mellow-flacm","name":"Hey Jude","title":"Hey Jude","artist":"The Beatles","album":"Hey Jude / Revolution","streaming":true,"disableUiControls":true,"duration":78,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: CURRENT POSITION 0 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState stateService play Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::syncState currentStatus stop Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: [1761932406523] [RadioParadise] setting new timer with duration of 78000 seconds. Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 1 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 3ms Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 1 milliseconds Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 31 18:40:06 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 6ms Oct 31 18:40:08 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 18:40:08 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::Close All Modals sent Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::Close All Modals sent Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces system playlist update Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: info: Ignoring MPD Status Update Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: info: Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: ---------------------------- MPD announces state update: player Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::getState Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 3ms Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand status took 2 milliseconds Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseState Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: info: ControllerMpd::pushState Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::servicePushState Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 31 18:40:10 volumio4-rpi3-rasptouch volumio[1425]: info: ------------------------------ 6ms Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::Close All Modals sent Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: info: VolumeController::SetAlsaVolumemute Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: Unable to parse: Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: Simple mixer control 'SoftMaster',0 Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: Capabilities: volume Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: Playback channels: Front Left - Front Right Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: Capture channels: Front Left - Front Right Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: Limits: 0 - 99 Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: Front Left: 86 [87%] Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: Front Right: 86 [87%] Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 31 18:40:12 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:40:12 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Oct 31 18:40:12 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioGetQueue Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::getQueue Oct 31 18:40:12 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getQueue Oct 31 18:40:13 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Oct 31 18:40:13 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: SPOTIFY VOLUME 87 Oct 31 18:40:13 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: VOLUMIO VOLUME 0 Oct 31 18:40:13 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 31 18:40:13 volumio4-rpi3-rasptouch volumio[1425]: info: Setting Spotify Volume from Volumio: 0 Oct 31 18:40:13 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:40:13 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Oct 31 18:40:13 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:40:14 volumio4-rpi3-rasptouch volumio[1425]: info: VolumeController::SetAlsaVolumeunmute Oct 31 18:40:14 volumio4-rpi3-rasptouch volumio[1425]: info: CoreStateMachine::pushState Oct 31 18:40:14 volumio4-rpi3-rasptouch volumio[1425]: info: CorePlayQueue::getTrack 0 Oct 31 18:40:14 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:40:14 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::volumioPushState Oct 31 18:40:14 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 31 18:40:14 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: SPOTIFY VOLUME 0 Oct 31 18:40:14 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: VOLUMIO VOLUME 87 Oct 31 18:40:14 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 31 18:40:14 volumio4-rpi3-rasptouch volumio[1425]: info: Setting Spotify Volume from Volumio: 87 Oct 31 18:40:14 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:40:14 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Oct 31 18:40:14 volumio4-rpi3-rasptouch kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 18:40:15 volumio4-rpi3-rasptouch go-librespot[1838]: time="2025-10-31T18:40:15+01:00" level=trace msg="sent dealer ping" Oct 31 18:40:15 volumio4-rpi3-rasptouch go-librespot[1838]: time="2025-10-31T18:40:15+01:00" level=trace msg="received dealer pong" Oct 31 18:40:15 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: SETTING SPOTIFY VOLUME 87 Oct 31 18:40:15 volumio4-rpi3-rasptouch volumio[1425]: info: Sending Spotify command with payload to local API: /player/volume Oct 31 18:40:15 volumio4-rpi3-rasptouch go-librespot[1838]: time="2025-10-31T18:40:15+01:00" level=debug msg="update volume requested to 57015/65535" Oct 31 18:40:16 volumio4-rpi3-rasptouch go-librespot[1838]: time="2025-10-31T18:40:16+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 31 18:40:16 volumio4-rpi3-rasptouch go-librespot[1838]: time="2025-10-31T18:40:16+01:00" level=trace msg="emitting websocket event: volume" Oct 31 18:40:16 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: received: {"type":"volume","data":{"value":87,"max":100}} Oct 31 18:40:16 volumio4-rpi3-rasptouch volumio[1425]: SPOTIFY: RECEIVED SPOTIFY VOLUME 87 Oct 31 18:40:45 volumio4-rpi3-rasptouch go-librespot[1838]: time="2025-10-31T18:40:45+01:00" level=trace msg="sent dealer ping" Oct 31 18:40:45 volumio4-rpi3-rasptouch go-librespot[1838]: time="2025-10-31T18:40:45+01:00" level=trace msg="received dealer pong" Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preload queue cleared Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: https://stream.radioparadise.com/mellow-flacm Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth_klassik/motherearth.klassik.aac Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: http://stream.radioparadise.com/global-flacm Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth_jazz/motherearth.jazz.mp4 Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth_instrumental/motherearth.instrumental Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Ftrack%2Feyes-on-me@artistUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com@albumUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Falbum%2Ffantasy-worlds Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Ftrack%2Fchocobo-variations@artistUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com@albumUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Falbum%2Ffantasy-worlds Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Ftrack%2Fnobuo-uematsu@artistUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com@albumUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Falbum%2Ffantasy-worlds Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Ftrack%2Ffantasy-over-hauyns-theme@artistUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com@albumUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Falbum%2Ffantasy-worlds Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Ftrack%2Fknight-of-goddess@artistUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com@albumUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Falbum%2Ffantasy-worlds Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Ftrack%2Fimprovisation-over-dust-to-dust@artistUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com@albumUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Falbum%2Ffantasy-worlds Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Ftrack%2Fmasashi-hamauzu@artistUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com@albumUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Falbum%2Ffantasy-worlds Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Ftrack%2Fsomnus@artistUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com@albumUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Falbum%2Ffantasy-worlds Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Ftrack%2Fyoko-shimomura@artistUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com@albumUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Falbum%2Ffantasy-worlds Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Ftrack%2Fkugane@artistUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com@albumUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Falbum%2Ffantasy-worlds Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Ftrack%2Fmasayoshi-soken@artistUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com@albumUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Falbum%2Ffantasy-worlds Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Ftrack%2Fgiza-plains@artistUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com@albumUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Falbum%2Ffantasy-worlds Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Ftrack%2Feruyt-village@artistUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com@albumUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Falbum%2Ffantasy-worlds Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Ftrack%2Fhitoshi-sakimoto@artistUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com@albumUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Falbum%2Ffantasy-worlds Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Ftrack%2Ftheme-of-love@artistUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com@albumUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Falbum%2Ffantasy-worlds Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Ftrack%2Frun@artistUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com@albumUrl=https%3A%2F%2Fbenyaminnuss.bandcamp.com%2Falbum%2Ffantasy-worlds Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Ftrack%2Fsi-dolce-il-tormento@artistUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com@albumUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Falbum%2Fmare-nostrum-ii Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Ftrack%2Fle-livre-dun-p-re-sarde@artistUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com@albumUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Falbum%2Fmare-nostrum-ii Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Ftrack%2Flekl-t@artistUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com@albumUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Falbum%2Fmare-nostrum-ii Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Ftrack%2Flili@artistUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com@albumUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Falbum%2Fmare-nostrum-ii Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Ftrack%2Fgnosienne@artistUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com@albumUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Falbum%2Fmare-nostrum-ii Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Ftrack%2Ffarv-l@artistUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com@albumUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Falbum%2Fmare-nostrum-ii Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Ftrack%2Fe-varie-notti-tre-vie-notai@artistUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com@albumUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Falbum%2Fmare-nostrum-ii Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Ftrack%2Fgiselle@artistUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com@albumUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Falbum%2Fmare-nostrum-ii Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Ftrack%2Fkristallen-den-fina@artistUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com@albumUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Falbum%2Fmare-nostrum-ii Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Ftrack%2Faurore@artistUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com@albumUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Falbum%2Fmare-nostrum-ii Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Ftrack%2Fblue-silence@artistUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com@albumUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Falbum%2Fmare-nostrum-ii Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Ftrack%2Fapnea@artistUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com@albumUrl=https%3A%2F%2Fmarenostrumtrio.bandcamp.com%2Falbum%2Fmare-nostrum-ii Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fmichaelwollny.bandcamp.com%2Ftrack%2Fsomewhere-around-barstow@artistUrl=https%3A%2F%2Fmichaelwollny.bandcamp.com@albumUrl=https%3A%2F%2Fmichaelwollny.bandcamp.com%2Falbum%2Fxxxx Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fprivat1.bandcamp.com%2Ftrack%2Fnique-tout@artistUrl=https%3A%2F%2Fprivat1.bandcamp.com@albumUrl=https%3A%2F%2Fprivat1.bandcamp.com%2Falbum%2Fcit-des-r-ves Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Ftrack%2Finvitation@artistUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com@albumUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Falbum%2Fthe-copenhagen-concert Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Ftrack%2Fthe-night-gone-by@artistUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com@albumUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Falbum%2Fthe-copenhagen-concert Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Ftrack%2Fabacus@artistUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com@albumUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Falbum%2Fthe-copenhagen-concert Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Ftrack%2Fit-speaks-for-itself@artistUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com@albumUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Falbum%2Fthe-music-of-enrico-pieranunzi Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Ftrack%2Fdistance-from-departure@artistUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com@albumUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Falbum%2Fthe-music-of-enrico-pieranunzi Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Ftrack%2Fcoralie@artistUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com@albumUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Falbum%2Fthe-music-of-enrico-pieranunzi Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Ftrack%2Fwith-my-heart-in-a-song@artistUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com@albumUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Falbum%2Fthe-music-of-enrico-pieranunzi Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Ftrack%2Fnewsbreak@artistUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com@albumUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Falbum%2Fthe-music-of-enrico-pieranunzi Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Ftrack%2Ffellinis-waltz@artistUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com@albumUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Falbum%2Fthe-music-of-enrico-pieranunzi Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Ftrack%2Fwithin-the-house-of-night@artistUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com@albumUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Falbum%2Fthe-music-of-enrico-pieranunzi Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Ftrack%2Fpersona@artistUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com@albumUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Falbum%2Fthe-music-of-enrico-pieranunzi Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth_jazz/motherearth.jazz Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Ftrack%2Fdecember@artistUrl=https%3A%2F%2Fiirorantala.bandcamp.com@albumUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Falbum%2Fmy-finnish-calendar Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Ftrack%2Fnovember@artistUrl=https%3A%2F%2Fiirorantala.bandcamp.com@albumUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Falbum%2Fmy-finnish-calendar Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Ftrack%2Foctober@artistUrl=https%3A%2F%2Fiirorantala.bandcamp.com@albumUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Falbum%2Fmy-finnish-calendar Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Ftrack%2Fseptember@artistUrl=https%3A%2F%2Fiirorantala.bandcamp.com@albumUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Falbum%2Fmy-finnish-calendar Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Ftrack%2Faugust@artistUrl=https%3A%2F%2Fiirorantala.bandcamp.com@albumUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Falbum%2Fmy-finnish-calendar Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Ftrack%2Fjuly@artistUrl=https%3A%2F%2Fiirorantala.bandcamp.com@albumUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Falbum%2Fmy-finnish-calendar Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Ftrack%2Fjune@artistUrl=https%3A%2F%2Fiirorantala.bandcamp.com@albumUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Falbum%2Fmy-finnish-calendar Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Ftrack%2Fmay@artistUrl=https%3A%2F%2Fiirorantala.bandcamp.com@albumUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Falbum%2Fmy-finnish-calendar Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Ftrack%2Fapril@artistUrl=https%3A%2F%2Fiirorantala.bandcamp.com@albumUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Falbum%2Fmy-finnish-calendar Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Ftrack%2Fmarch@artistUrl=https%3A%2F%2Fiirorantala.bandcamp.com@albumUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Falbum%2Fmy-finnish-calendar Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Ftrack%2Ffebruary@artistUrl=https%3A%2F%2Fiirorantala.bandcamp.com@albumUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Falbum%2Fmy-finnish-calendar Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Ftrack%2Fjanuary@artistUrl=https%3A%2F%2Fiirorantala.bandcamp.com@albumUrl=https%3A%2F%2Fiirorantala.bandcamp.com%2Falbum%2Fmy-finnish-calendar Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Ftrack%2Fwalksong-take-1-alternative@artistUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com@albumUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Falbum%2Fpiano-chronicles-vol-2 Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Ftrack%2Fcandy-2@artistUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com@albumUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Falbum%2Fpiano-chronicles-vol-1 Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Ftrack%2Fibojas-theme-2@artistUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com@albumUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Falbum%2Fibojas-theme-take-3 Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Ftrack%2Fgo-go-go-go-take-1-2@artistUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com@albumUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Falbum%2Fpiano-chronicles-vol-2 Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Ftrack%2Fnever-take-4-2@artistUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com@albumUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Falbum%2Fpiano-chronicles-vol-2 Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Ftrack%2Fssshhhh-take-5@artistUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com@albumUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Falbum%2Fpiano-chronicles-vol-2 Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Ftrack%2Fin-the-fridge-3@artistUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com@albumUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Falbum%2Fpiano-chronicles-vol-2 Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Ftrack%2Fthe-magic-invention-from-t-d-p-r-c-take-4@artistUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com@albumUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Falbum%2Fpiano-chronicles-vol-2 Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Ftrack%2Fwood-wood-you-like-to-disco@artistUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com@albumUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Falbum%2Fpiano-chronicles-vol-2 Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Ftrack%2Fibojas-theme@artistUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com@albumUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Falbum%2Fpiano-chronicles-vol-2 Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Ftrack%2Fboogie-2@artistUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com@albumUrl=https%3A%2F%2Fhessismoremusic.bandcamp.com%2Falbum%2Fpiano-chronicles-vol-2 Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fstevereich.bandcamp.com%2Ftrack%2Freich-richter-opening@artistUrl=https%3A%2F%2Fstevereich.bandcamp.com@albumUrl=https%3A%2F%2Fstevereich.bandcamp.com%2Falbum%2Freich-richter Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Ftrack%2Fwild-beauty@artistUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com@albumUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Falbum%2Fwild-beauty-sonata-suite-for-the-brussels-jazz-orchestra Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth_klassik/motherearth.klassik Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fphilipcatherine.bandcamp.com%2Ftrack%2Fpendulum@artistUrl=https%3A%2F%2Fphilipcatherine.bandcamp.com@albumUrl=https%3A%2F%2Fphilipcatherine.bandcamp.com%2Falbum%2Fthe-string-project-live-in-brussels Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fphilipcatherine.bandcamp.com%2Ftrack%2Fhomecomings@artistUrl=https%3A%2F%2Fphilipcatherine.bandcamp.com@albumUrl=https%3A%2F%2Fphilipcatherine.bandcamp.com%2Falbum%2Fthe-string-project-live-in-brussels Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fphilipcatherine.bandcamp.com%2Ftrack%2Fisabelle@artistUrl=https%3A%2F%2Fphilipcatherine.bandcamp.com@albumUrl=https%3A%2F%2Fphilipcatherine.bandcamp.com%2Falbum%2Fthe-string-project-live-in-brussels Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fphilipcatherine.bandcamp.com%2Ftrack%2Fphilip-a-paris@artistUrl=https%3A%2F%2Fphilipcatherine.bandcamp.com@albumUrl=https%3A%2F%2Fphilipcatherine.bandcamp.com%2Falbum%2Fthe-string-project-live-in-brussels Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Ftrack%2Fmr-iron-stone-wood-and-i@artistUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com@albumUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Falbum%2Fcountermove Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Ftrack%2Fthe-countermove@artistUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com@albumUrl=https%3A%2F%2Fbrusselsjazzorchestra.bandcamp.com%2Falbum%2Fcountermove Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Ftrack%2Ffree-visions-4@artistUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com@albumUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Falbum%2Fnew-visions Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Ftrack%2Falt-kan-ske@artistUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com@albumUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Falbum%2Fnew-visions Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Ftrack%2Ffree-visions-3@artistUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com@albumUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Falbum%2Fnew-visions Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Ftrack%2Ffree-visions-2@artistUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com@albumUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Falbum%2Fnew-visions Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Ftrack%2Fyou-know@artistUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com@albumUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Falbum%2Fnew-visions Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Ftrack%2Fanne-blomster-sang@artistUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com@albumUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Falbum%2Fnew-visions Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Ftrack%2Fnight-waltz@artistUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com@albumUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Falbum%2Fnew-visions Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Ftrack%2Ffree-visions-1@artistUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com@albumUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Falbum%2Fnew-visions Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Ftrack%2Fpannonica-3@artistUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com@albumUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Falbum%2Fthe-copenhagen-concert Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Ftrack%2Feverything-i-love-2@artistUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com@albumUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Falbum%2Fthe-copenhagen-concert Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Ftrack%2Fmedley-body-soul-if-i-should-lose-you@artistUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com@albumUrl=https%3A%2F%2Fstoryvillerecords.bandcamp.com%2Falbum%2Fthe-copenhagen-concert Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fenzozirilli.bandcamp.com%2Ftrack%2Fthe-night-gone-by-e-pieranunzi-2@artistUrl=https%3A%2F%2Fenzozirilli.bandcamp.com@albumUrl=https%3A%2F%2Fenzozirilli.bandcamp.com%2Falbum%2Fenrico-pieranunzi-4t-live-in-hamburg-2003-feat-ares-tavolazzi-b-enzo-zirilli-d-rosario-giuliani-a-sax Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fenzozirilli.bandcamp.com%2Ftrack%2Foriginal-untitled-e-pieranunzi@artistUrl=https%3A%2F%2Fenzozirilli.bandcamp.com@albumUrl=https%3A%2F%2Fenzozirilli.bandcamp.com%2Falbum%2Fenrico-pieranunzi-4t-live-in-hamburg-2003-feat-ares-tavolazzi-b-enzo-zirilli-d-rosario-giuliani-a-sax Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fenzozirilli.bandcamp.com%2Ftrack%2Flost-found-e-pieranunzi-2@artistUrl=https%3A%2F%2Fenzozirilli.bandcamp.com@albumUrl=https%3A%2F%2Fenzozirilli.bandcamp.com%2Falbum%2Fenrico-pieranunzi-4t-live-in-hamburg-2003-feat-ares-tavolazzi-b-enzo-zirilli-d-rosario-giuliani-a-sax Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fdieterilg.bandcamp.com%2Ftrack%2Fmond-und-schein@artistUrl=https%3A%2F%2Fdieterilg.bandcamp.com@albumUrl=https%3A%2F%2Fdieterilg.bandcamp.com%2Falbum%2Fmein-beethoven Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fdieterilg.bandcamp.com%2Ftrack%2Fsoldiers-dream@artistUrl=https%3A%2F%2Fdieterilg.bandcamp.com@albumUrl=https%3A%2F%2Fdieterilg.bandcamp.com%2Falbum%2Fmein-beethoven Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fdieterilg.bandcamp.com%2Ftrack%2Fallegro@artistUrl=https%3A%2F%2Fdieterilg.bandcamp.com@albumUrl=https%3A%2F%2Fdieterilg.bandcamp.com%2Falbum%2Fmein-beethoven Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fdieterilg.bandcamp.com%2Ftrack%2Fadagio@artistUrl=https%3A%2F%2Fdieterilg.bandcamp.com@albumUrl=https%3A%2F%2Fdieterilg.bandcamp.com%2Falbum%2Fmein-beethoven Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fdieterilg.bandcamp.com%2Ftrack%2F109@artistUrl=https%3A%2F%2Fdieterilg.bandcamp.com@albumUrl=https%3A%2F%2Fdieterilg.bandcamp.com%2Falbum%2Fmein-beethoven Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fdieterilg.bandcamp.com%2Ftrack%2Fhammerlos@artistUrl=https%3A%2F%2Fdieterilg.bandcamp.com@albumUrl=https%3A%2F%2Fdieterilg.bandcamp.com%2Falbum%2Fmein-beethoven Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Preloading song: bandcamp/track@trackUrl=https%3A%2F%2Fdieterilg.bandcamp.com%2Ftrack%2Fsturm@artistUrl=https%3A%2F%2Fdieterilg.bandcamp.com@albumUrl=https%3A%2F%2Fdieterilg.bandcamp.com%2Falbum%2Fmein-beethoven Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: info: Exploding uri https://stream.radioparadise.com/mellow-flacm in service radio_paradise Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: error: [RadioParadise] explodeUri: No station found for uri "https://stream.radioparadise.com/mellow-flacm" Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: TypeError: Cannot read properties of undefined (reading 'then') Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: at listOnTimeout (node:internal/timers:573:17) Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: at process.processTimers (node:internal/timers:514:7) Oct 31 18:40:50 volumio4-rpi3-rasptouch volumio[1425]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 18:40:52 volumio4-rpi3-rasptouch sudo[24741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-31 18:39' Oct 31 18:40:52 volumio4-rpi3-rasptouch sudo[24741]: 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="c22b72a8acb34a699a626adb0a09a6b88ee7233a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="40400d6a6fa57cf20e36499e510876b3654e9c4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Oct 4 16:40:08 UTC 2025" VOLUMIO_VERSION="4.061" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="7f8ace80645f85765fa10c72d1769d04"