Jan 11 20:30:02 sala systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7568. Jan 11 20:30:02 sala systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 11 20:30:03 sala systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 11 20:30:03 sala upmpdcli[7981]: Could not open config: /tmp/upmpdcli.conf Jan 11 20:30:03 sala systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:30:03 sala systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 11 20:30:09 sala go-librespot[23714]: time="2026-01-11T20:30:09+01:00" level=trace msg="sent dealer ping" Jan 11 20:30:09 sala go-librespot[23714]: time="2026-01-11T20:30:09+01:00" level=trace msg="received dealer pong" Jan 11 20:30:18 sala systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7569. Jan 11 20:30:18 sala systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 11 20:30:18 sala systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 11 20:30:18 sala upmpdcli[8000]: Could not open config: /tmp/upmpdcli.conf Jan 11 20:30:18 sala systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:30:18 sala systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 11 20:30:33 sala systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7570. Jan 11 20:30:33 sala systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 11 20:30:33 sala systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 11 20:30:33 sala upmpdcli[8030]: Could not open config: /tmp/upmpdcli.conf Jan 11 20:30:33 sala systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:30:33 sala systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 11 20:30:39 sala go-librespot[23714]: time="2026-01-11T20:30:39+01:00" level=trace msg="sent dealer ping" Jan 11 20:30:39 sala go-librespot[23714]: time="2026-01-11T20:30:39+01:00" level=trace msg="received dealer pong" Jan 11 20:30:48 sala systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7571. Jan 11 20:30:48 sala systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 11 20:30:48 sala systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 11 20:30:48 sala upmpdcli[8049]: Could not open config: /tmp/upmpdcli.conf Jan 11 20:30:48 sala systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:30:48 sala systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 11 20:31:02 sala nmbd[1213]: [2026/01/11 20:31:02.124529, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 11 20:31:02 sala nmbd[1213]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.28 for name WORKGROUP<1d>. Jan 11 20:31:02 sala nmbd[1213]: This response was from IP 192.168.1.4, reporting an IP address of 192.168.1.4. Jan 11 20:31:02 sala nmbd[1213]: [2026/01/11 20:31:02.125504, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 11 20:31:02 sala nmbd[1213]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.67 for name WORKGROUP<1d>. Jan 11 20:31:02 sala nmbd[1213]: This response was from IP 192.168.1.4, reporting an IP address of 192.168.1.4. Jan 11 20:31:03 sala systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7572. Jan 11 20:31:03 sala systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 11 20:31:04 sala systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 11 20:31:04 sala upmpdcli[8082]: Could not open config: /tmp/upmpdcli.conf Jan 11 20:31:04 sala systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:31:04 sala systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 11 20:31:09 sala go-librespot[23714]: time="2026-01-11T20:31:09+01:00" level=trace msg="sent dealer ping" Jan 11 20:31:09 sala go-librespot[23714]: time="2026-01-11T20:31:09+01:00" level=trace msg="received dealer pong" Jan 11 20:31:10 sala go-librespot[23714]: time="2026-01-11T20:31:10+01:00" level=trace msg="received accesspoint ping" Jan 11 20:31:10 sala go-librespot[23714]: time="2026-01-11T20:31:10+01:00" level=trace msg="received accesspoint pong ack" Jan 11 20:31:19 sala systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7573. Jan 11 20:31:19 sala systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 11 20:31:19 sala systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 11 20:31:19 sala upmpdcli[8101]: Could not open config: /tmp/upmpdcli.conf Jan 11 20:31:19 sala systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:31:19 sala systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 11 20:31:21 sala volumio[1253]: info: CoreCommandRouter::volumioToggle Jan 11 20:31:21 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:21 sala volumio[1253]: info: CoreStateMachine::play index undefined Jan 11 20:31:21 sala volumio[1253]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 20:31:21 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:21 sala volumio[1253]: info: CoreStateMachine::startPlaybackTimer Jan 11 20:31:21 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:21 sala volumio[1253]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 20:31:21 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 20:31:21 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 11 20:31:21 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand play Jan 11 20:31:21 sala volumio[1253]: info: sendMpdCommand play took 24 milliseconds Jan 11 20:31:21 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:21 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:21 sala volumio[1253]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/11842.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"Closer","title":"Closer","artist":"Nine Inch Nails","album":"The Downward Spiral","streaming":true,"disableUiControls":true,"duration":372,"seek":66523,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 20:31:21 sala volumio[1253]: verbose: CURRENT POSITION 0 Jan 11 20:31:21 sala volumio[1253]: info: CoreStateMachine::syncState stateService play Jan 11 20:31:21 sala volumio[1253]: info: CoreStateMachine::syncState currentStatus pause Jan 11 20:31:21 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:21 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:21 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:31:21 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:21 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:21 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:21 sala volumio[1253]: info: [1768159881669] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Jan 11 20:31:21 sala volumio[1253]: info: Jan 11 20:31:21 sala volumio[1253]: ---------------------------- MPD announces state update: player Jan 11 20:31:21 sala volumio[1253]: info: ControllerMpd::getState Jan 11 20:31:21 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:31:21 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:21 sala volumio[1253]: info: sendMpdCommand status took 28 milliseconds Jan 11 20:31:21 sala volumio[1253]: verbose: ControllerMpd::parseState Jan 11 20:31:21 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:31:21 sala volumio[1253]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 11 20:31:21 sala volumio[1253]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:31:21 sala volumio[1253]: info: ControllerMpd::pushState Jan 11 20:31:21 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:21 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:21 sala volumio[1253]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 11 20:31:21 sala volumio[1253]: info: ------------------------------ 39ms Jan 11 20:31:21 sala volumio[1253]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 11 20:31:22 sala volumio[1253]: info: [1768159882317] [RadioParadise] received new metadata: {"time":45,"artist":"Airbag","title":"A Day At The Beach 1","album":"A Day At The Beach","year":"1900","cover":"https://img.radioparadise.com/covers/l/26050.jpg","cover_med":"https://img.radioparadise.com/covers/m/26050.jpg","cover_small":"https://img.radioparadise.com/covers/s/26050.jpg"} Jan 11 20:31:22 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:22 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:22 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:22 sala volumio[1253]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/26050.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"A Day At The Beach 1","title":"A Day At The Beach 1","artist":"Airbag","album":"A Day At The Beach","streaming":true,"disableUiControls":true,"duration":50,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 20:31:22 sala volumio[1253]: verbose: CURRENT POSITION 0 Jan 11 20:31:22 sala volumio[1253]: info: CoreStateMachine::syncState stateService play Jan 11 20:31:22 sala volumio[1253]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:31:22 sala volumio[1253]: info: Received an update from plugin. extracting info from payload Jan 11 20:31:22 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:22 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:22 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:31:22 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:22 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:22 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:22 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:22 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:22 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:22 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:22 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:22 sala volumio[1253]: info: [1768159882328] [RadioParadise] setting new timer with duration of 50000 seconds. Jan 11 20:31:22 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:22 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:27 sala volumio[1253]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 20:31:27 sala volumio[1253]: info: CoreCommandRouter::Close All Modals sent Jan 11 20:31:32 sala volumio[1253]: info: CoreCommandRouter::volumioToggle Jan 11 20:31:32 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:32 sala volumio[1253]: info: CoreStateMachine::pause Jan 11 20:31:32 sala volumio[1253]: info: CoreStateMachine::stPlaybackTimer Jan 11 20:31:32 sala volumio[1253]: info: CoreStateMachine::servicePause Jan 11 20:31:32 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:32 sala volumio[1253]: info: CoreCommandRouter::servicePause Jan 11 20:31:32 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand pause Jan 11 20:31:32 sala volumio[1253]: info: Jan 11 20:31:32 sala volumio[1253]: ---------------------------- MPD announces state update: player Jan 11 20:31:32 sala volumio[1253]: info: sendMpdCommand pause took 3 milliseconds Jan 11 20:31:32 sala volumio[1253]: info: ControllerMpd::getState Jan 11 20:31:32 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:31:32 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:32 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:32 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:32 sala volumio[1253]: verbose: STATE SERVICE {"status":"pause","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/26050.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"A Day At The Beach 1","title":"A Day At The Beach 1","artist":"Airbag","album":"A Day At The Beach","streaming":true,"disableUiControls":true,"duration":50,"seek":10333,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 20:31:32 sala volumio[1253]: verbose: CURRENT POSITION 0 Jan 11 20:31:32 sala volumio[1253]: info: CoreStateMachine::syncState stateService pause Jan 11 20:31:32 sala volumio[1253]: info: CoreStateMachine::syncState currentStatus pause Jan 11 20:31:32 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:32 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:32 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:31:32 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:32 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:32 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:32 sala volumio[1253]: info: CoreStateMachine::stPlaybackTimer Jan 11 20:31:32 sala volumio[1253]: info: sendMpdCommand status took 10 milliseconds Jan 11 20:31:32 sala volumio[1253]: verbose: ControllerMpd::parseState Jan 11 20:31:32 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:31:32 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:32 sala volumio[1253]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 11 20:31:32 sala volumio[1253]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:31:32 sala volumio[1253]: info: ControllerMpd::pushState Jan 11 20:31:32 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:32 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:32 sala volumio[1253]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 11 20:31:32 sala volumio[1253]: info: ------------------------------ 22ms Jan 11 20:31:32 sala volumio[1253]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::volumioToggle Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::play index undefined Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::startPlaybackTimer Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 11 20:31:34 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand play Jan 11 20:31:34 sala volumio[1253]: info: Jan 11 20:31:34 sala volumio[1253]: ---------------------------- MPD announces state update: player Jan 11 20:31:34 sala volumio[1253]: info: sendMpdCommand play took 29 milliseconds Jan 11 20:31:34 sala volumio[1253]: info: ControllerMpd::getState Jan 11 20:31:34 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/26050.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"A Day At The Beach 1","title":"A Day At The Beach 1","artist":"Airbag","album":"A Day At The Beach","streaming":true,"disableUiControls":true,"duration":50,"seek":10333,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 20:31:34 sala volumio[1253]: verbose: CURRENT POSITION 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::syncState stateService play Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::syncState currentStatus pause Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: [1768159894043] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Jan 11 20:31:34 sala volumio[1253]: info: sendMpdCommand status took 21 milliseconds Jan 11 20:31:34 sala volumio[1253]: verbose: ControllerMpd::parseState Jan 11 20:31:34 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:31:34 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:34 sala volumio[1253]: info: sendMpdCommand playlistinfo took 24 milliseconds Jan 11 20:31:34 sala volumio[1253]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:31:34 sala volumio[1253]: info: ControllerMpd::pushState Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 11 20:31:34 sala volumio[1253]: info: ------------------------------ 52ms Jan 11 20:31:34 sala volumio[1253]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::volumioToggle Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::pause Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::stPlaybackTimer Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::servicePause Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::servicePause Jan 11 20:31:34 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand pause Jan 11 20:31:34 sala volumio[1253]: info: Jan 11 20:31:34 sala volumio[1253]: ---------------------------- MPD announces state update: player Jan 11 20:31:34 sala volumio[1253]: info: sendMpdCommand pause took 1 milliseconds Jan 11 20:31:34 sala volumio[1253]: info: ControllerMpd::getState Jan 11 20:31:34 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: verbose: STATE SERVICE {"status":"pause","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/26050.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"A Day At The Beach 1","title":"A Day At The Beach 1","artist":"Airbag","album":"A Day At The Beach","streaming":true,"disableUiControls":true,"duration":50,"seek":10840,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 20:31:34 sala volumio[1253]: verbose: CURRENT POSITION 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::syncState stateService pause Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::syncState currentStatus pause Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::stPlaybackTimer Jan 11 20:31:34 sala volumio[1253]: info: sendMpdCommand status took 12 milliseconds Jan 11 20:31:34 sala volumio[1253]: verbose: ControllerMpd::parseState Jan 11 20:31:34 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:31:34 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:34 sala volumio[1253]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 11 20:31:34 sala volumio[1253]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:31:34 sala volumio[1253]: info: ControllerMpd::pushState Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 11 20:31:34 sala volumio[1253]: info: ------------------------------ 24ms Jan 11 20:31:34 sala volumio[1253]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 11 20:31:34 sala systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7574. Jan 11 20:31:34 sala systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 11 20:31:34 sala systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 11 20:31:34 sala upmpdcli[8144]: Could not open config: /tmp/upmpdcli.conf Jan 11 20:31:34 sala systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:31:34 sala systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::volumioToggle Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::play index undefined Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::startPlaybackTimer Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 11 20:31:34 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand play Jan 11 20:31:34 sala volumio[1253]: info: Jan 11 20:31:34 sala volumio[1253]: ---------------------------- MPD announces state update: player Jan 11 20:31:34 sala volumio[1253]: info: sendMpdCommand play took 30 milliseconds Jan 11 20:31:34 sala volumio[1253]: info: ControllerMpd::getState Jan 11 20:31:34 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/26050.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"A Day At The Beach 1","title":"A Day At The Beach 1","artist":"Airbag","album":"A Day At The Beach","streaming":true,"disableUiControls":true,"duration":50,"seek":10840,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 20:31:34 sala volumio[1253]: verbose: CURRENT POSITION 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::syncState stateService play Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::syncState currentStatus pause Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: [1768159894617] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Jan 11 20:31:34 sala volumio[1253]: info: sendMpdCommand status took 29 milliseconds Jan 11 20:31:34 sala volumio[1253]: verbose: ControllerMpd::parseState Jan 11 20:31:34 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:31:34 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:34 sala volumio[1253]: info: sendMpdCommand playlistinfo took 30 milliseconds Jan 11 20:31:34 sala volumio[1253]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:31:34 sala volumio[1253]: info: ControllerMpd::pushState Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 11 20:31:34 sala volumio[1253]: info: ------------------------------ 63ms Jan 11 20:31:34 sala volumio[1253]: info: [1768159894686] [RadioParadise] received new metadata: {"time":33,"artist":"Airbag","title":"A Day At The Beach 1","album":"A Day At The Beach","year":"1900","cover":"https://img.radioparadise.com/covers/l/26050.jpg","cover_med":"https://img.radioparadise.com/covers/m/26050.jpg","cover_small":"https://img.radioparadise.com/covers/s/26050.jpg"} Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/26050.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"A Day At The Beach 1","title":"A Day At The Beach 1","artist":"Airbag","album":"A Day At The Beach","streaming":true,"disableUiControls":true,"duration":38,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 20:31:34 sala volumio[1253]: verbose: CURRENT POSITION 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::syncState stateService play Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:31:34 sala volumio[1253]: info: Received an update from plugin. extracting info from payload Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:34 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:34 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:34 sala volumio[1253]: info: [1768159894704] [RadioParadise] setting new timer with duration of 38000 seconds. Jan 11 20:31:34 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:34 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:34 sala volumio[1253]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 11 20:31:35 sala volumio[1253]: info: [1768159895261] [RadioParadise] received new metadata: {"time":31,"artist":"Airbag","title":"A Day At The Beach 1","album":"A Day At The Beach","year":"1900","cover":"https://img.radioparadise.com/covers/l/26050.jpg","cover_med":"https://img.radioparadise.com/covers/m/26050.jpg","cover_small":"https://img.radioparadise.com/covers/s/26050.jpg"} Jan 11 20:31:35 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:35 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:35 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:35 sala volumio[1253]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/26050.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"A Day At The Beach 1","title":"A Day At The Beach 1","artist":"Airbag","album":"A Day At The Beach","streaming":true,"disableUiControls":true,"duration":36,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 20:31:35 sala volumio[1253]: verbose: CURRENT POSITION 0 Jan 11 20:31:35 sala volumio[1253]: info: CoreStateMachine::syncState stateService play Jan 11 20:31:35 sala volumio[1253]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:31:35 sala volumio[1253]: info: Received an update from plugin. extracting info from payload Jan 11 20:31:35 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:35 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:35 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:31:35 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:35 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:35 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:35 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:35 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:35 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:35 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:35 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:35 sala volumio[1253]: info: [1768159895279] [RadioParadise] setting new timer with duration of 36000 seconds. Jan 11 20:31:35 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:35 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:36 sala volumio[1253]: info: CoreCommandRouter::volumioSeek Jan 11 20:31:36 sala volumio[1253]: info: CoreStateMachine::seek Jan 11 20:31:36 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:36 sala volumio[1253]: info: TRACKBLOCK {"service":"radio_paradise","type":"track","trackType":"Radio Paradise Rock Mix (FLAC)","radioType":"radio_paradise","albumart":"https://img.radioparadise.com/covers/l/26050.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"A Day At The Beach 1","duration":36,"artist":"Airbag","album":"A Day At The Beach","samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 20:31:36 sala volumio[1253]: info: CoreStateMachine::startPlaybackTimer Jan 11 20:31:36 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:36 sala volumio[1253]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 20:31:36 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 20:31:36 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 11 20:31:36 sala volumio[1253]: info: [1768159896904] [RadioParadise] seek to 6778 Jan 11 20:31:36 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:36 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:36 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:31:36 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:36 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:36 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:36 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:37 sala volumio[1253]: info: CoreCommandRouter::volumioRandom Jan 11 20:31:37 sala volumio[1253]: info: CoreCommandRouter::writePlayerControls Jan 11 20:31:37 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:37 sala volumio[1253]: info: CoreStateMachine::setRepeat true single false Jan 11 20:31:37 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:37 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:37 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:31:37 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:37 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:37 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:37 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:38 sala volumio[1253]: info: CoreCommandRouter::volumioSeek Jan 11 20:31:38 sala volumio[1253]: info: CoreStateMachine::seek Jan 11 20:31:38 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:38 sala volumio[1253]: info: TRACKBLOCK {"service":"radio_paradise","type":"track","trackType":"Radio Paradise Rock Mix (FLAC)","radioType":"radio_paradise","albumart":"https://img.radioparadise.com/covers/l/26050.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"A Day At The Beach 1","duration":36,"artist":"Airbag","album":"A Day At The Beach","samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 20:31:38 sala volumio[1253]: info: CoreStateMachine::startPlaybackTimer Jan 11 20:31:38 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:38 sala volumio[1253]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 20:31:38 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 20:31:38 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 11 20:31:38 sala volumio[1253]: info: [1768159898448] [RadioParadise] seek to 7780 Jan 11 20:31:38 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:38 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:38 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:31:38 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:38 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:38 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:38 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:39 sala go-librespot[23714]: time="2026-01-11T20:31:39+01:00" level=trace msg="sent dealer ping" Jan 11 20:31:39 sala volumio[1253]: info: CoreCommandRouter::volumioSeek Jan 11 20:31:39 sala volumio[1253]: info: CoreStateMachine::seek Jan 11 20:31:39 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:39 sala volumio[1253]: info: TRACKBLOCK {"service":"radio_paradise","type":"track","trackType":"Radio Paradise Rock Mix (FLAC)","radioType":"radio_paradise","albumart":"https://img.radioparadise.com/covers/l/26050.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"A Day At The Beach 1","duration":36,"artist":"Airbag","album":"A Day At The Beach","samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 20:31:39 sala volumio[1253]: info: CoreStateMachine::startPlaybackTimer Jan 11 20:31:39 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:39 sala volumio[1253]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 20:31:39 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 20:31:39 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 11 20:31:39 sala volumio[1253]: info: [1768159899330] [RadioParadise] seek to 20531 Jan 11 20:31:39 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:39 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:39 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:31:39 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:39 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:39 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:39 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:39 sala go-librespot[23714]: time="2026-01-11T20:31:39+01:00" level=trace msg="received dealer pong" Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::Close All Modals sent Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::volumioNext Jan 11 20:31:40 sala volumio[1253]: info: CoreStateMachine::next Jan 11 20:31:40 sala volumio[1253]: info: CoreStateMachine::stop Jan 11 20:31:40 sala volumio[1253]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 20:31:40 sala volumio[1253]: info: CoreStateMachine::stPlaybackTimer Jan 11 20:31:40 sala volumio[1253]: info: CoreStateMachine::updateTrackBlock Jan 11 20:31:40 sala volumio[1253]: info: CorePlayQueue::getTrackBlock Jan 11 20:31:40 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:40 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:40 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:40 sala volumio[1253]: info: CoreStateMachine::serviceStop Jan 11 20:31:40 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::serviceStop Jan 11 20:31:40 sala volumio[1253]: info: ControllerMpd::stop Jan 11 20:31:40 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::Close All Modals sent Jan 11 20:31:40 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:40 sala volumio[1253]: info: Jan 11 20:31:40 sala volumio[1253]: ---------------------------- MPD announces state update: player Jan 11 20:31:40 sala volumio[1253]: info: sendMpdCommand stop took 33 milliseconds Jan 11 20:31:40 sala volumio[1253]: info: ControllerMpd::getState Jan 11 20:31:40 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:40 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:40 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:40 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:40 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:40 sala volumio[1253]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/26050.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"A Day At The Beach 1","title":"A Day At The Beach 1","artist":"Airbag","album":"A Day At The Beach","streaming":true,"disableUiControls":true,"duration":36,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 20:31:40 sala volumio[1253]: verbose: CURRENT POSITION 0 Jan 11 20:31:40 sala volumio[1253]: info: CoreStateMachine::syncState stateService stop Jan 11 20:31:40 sala volumio[1253]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:31:40 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:40 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:40 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:40 sala volumio[1253]: info: No code Jan 11 20:31:40 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:40 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:40 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:40 sala volumio[1253]: info: CoreStateMachine::play index undefined Jan 11 20:31:40 sala volumio[1253]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 20:31:40 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:40 sala volumio[1253]: info: CoreStateMachine::startPlaybackTimer Jan 11 20:31:40 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 11 20:31:40 sala volumio[1253]: info: [RadioParadise] Matched station: Radio Paradise Rock Mix (FLAC), chan=2 Jan 11 20:31:40 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 20:31:40 sala volumio[1253]: info: CoreStateMachine::updateTrackBlock Jan 11 20:31:40 sala volumio[1253]: info: CorePlayQueue::getTrackBlock Jan 11 20:31:40 sala volumio[1253]: info: sendMpdCommand status took 43 milliseconds Jan 11 20:31:40 sala volumio[1253]: info: sendMpdCommand stop took 4 milliseconds Jan 11 20:31:40 sala volumio[1253]: verbose: ControllerMpd::parseState Jan 11 20:31:40 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:31:40 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 20:31:40 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:40 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:40 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:40 sala volumio[1253]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 11 20:31:40 sala volumio[1253]: info: Jan 11 20:31:40 sala volumio[1253]: ---------------------------- MPD announces system playlist update Jan 11 20:31:40 sala volumio[1253]: info: Ignoring MPD Status Update Jan 11 20:31:40 sala volumio[1253]: info: sendMpdCommand playlistinfo took 18 milliseconds Jan 11 20:31:40 sala volumio[1253]: info: sendMpdCommand clear took 18 milliseconds Jan 11 20:31:40 sala volumio[1253]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:31:40 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 11 20:31:40 sala volumio[1253]: info: ControllerMpd::pushState Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:40 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:40 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:40 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:40 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:40 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:40 sala volumio[1253]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 11 20:31:40 sala volumio[1253]: info: ------------------------------ 75ms Jan 11 20:31:40 sala volumio[1253]: error: updateQueue error: null Jan 11 20:31:40 sala volumio[1253]: info: ------------------------------ 15ms Jan 11 20:31:40 sala volumio[1253]: info: sendMpdCommand consume 1 took 13 milliseconds Jan 11 20:31:40 sala volumio[1253]: info: [1768159900904] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flacm Jan 11 20:31:40 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flacm" Jan 11 20:31:40 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:40 sala volumio[1253]: info: Jan 11 20:31:40 sala volumio[1253]: ---------------------------- MPD announces system playlist update Jan 11 20:31:40 sala volumio[1253]: info: Ignoring MPD Status Update Jan 11 20:31:40 sala volumio[1253]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flacm" took 6 milliseconds Jan 11 20:31:40 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand play Jan 11 20:31:40 sala volumio[1253]: info: ------------------------------ 7ms Jan 11 20:31:40 sala volumio[1253]: info: sendMpdCommand play took 6 milliseconds Jan 11 20:31:40 sala volumio[1253]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=2 Jan 11 20:31:40 sala volumio[1253]: info: [1768159900922] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Jan 11 20:31:41 sala volumio[1253]: info: [1768159901531] [RadioParadise] received new metadata: {"time":25,"artist":"Airbag","title":"A Day At The Beach 1","album":"A Day At The Beach","year":"1900","cover":"https://img.radioparadise.com/covers/l/26050.jpg","cover_med":"https://img.radioparadise.com/covers/m/26050.jpg","cover_small":"https://img.radioparadise.com/covers/s/26050.jpg"} Jan 11 20:31:41 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:41 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:41 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:41 sala volumio[1253]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/26050.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"A Day At The Beach 1","title":"A Day At The Beach 1","artist":"Airbag","album":"A Day At The Beach","streaming":true,"disableUiControls":true,"duration":30,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 20:31:41 sala volumio[1253]: verbose: CURRENT POSITION 0 Jan 11 20:31:41 sala volumio[1253]: info: CoreStateMachine::syncState stateService play Jan 11 20:31:41 sala volumio[1253]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:31:41 sala volumio[1253]: info: [1768159901533] [RadioParadise] setting new timer with duration of 30000 seconds. Jan 11 20:31:41 sala volumio[1253]: info: Jan 11 20:31:41 sala volumio[1253]: ---------------------------- MPD announces state update: player Jan 11 20:31:41 sala volumio[1253]: info: ControllerMpd::getState Jan 11 20:31:41 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:31:41 sala volumio[1253]: info: Jan 11 20:31:41 sala volumio[1253]: ---------------------------- MPD announces state update: player Jan 11 20:31:41 sala volumio[1253]: info: sendMpdCommand status took 28 milliseconds Jan 11 20:31:41 sala volumio[1253]: info: ControllerMpd::getState Jan 11 20:31:41 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:31:41 sala volumio[1253]: verbose: ControllerMpd::parseState Jan 11 20:31:41 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:31:41 sala volumio[1253]: info: sendMpdCommand status took 2 milliseconds Jan 11 20:31:41 sala volumio[1253]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 11 20:31:41 sala volumio[1253]: verbose: ControllerMpd::parseState Jan 11 20:31:41 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:31:41 sala volumio[1253]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:31:41 sala volumio[1253]: info: ControllerMpd::pushState Jan 11 20:31:41 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:41 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:41 sala volumio[1253]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 11 20:31:41 sala volumio[1253]: info: ------------------------------ 36ms Jan 11 20:31:41 sala volumio[1253]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 20:31:41 sala volumio[1253]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:31:41 sala volumio[1253]: info: ControllerMpd::pushState Jan 11 20:31:41 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:41 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:41 sala volumio[1253]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 11 20:31:41 sala volumio[1253]: info: ------------------------------ 11ms Jan 11 20:31:41 sala volumio[1253]: info: Jan 11 20:31:41 sala volumio[1253]: ---------------------------- MPD announces system playlist update Jan 11 20:31:41 sala volumio[1253]: info: Ignoring MPD Status Update Jan 11 20:31:41 sala volumio[1253]: info: Jan 11 20:31:41 sala volumio[1253]: ---------------------------- MPD announces state update: player Jan 11 20:31:41 sala volumio[1253]: info: ControllerMpd::getState Jan 11 20:31:41 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:31:41 sala volumio[1253]: info: ------------------------------ 7ms Jan 11 20:31:41 sala volumio[1253]: info: sendMpdCommand status took 5 milliseconds Jan 11 20:31:41 sala volumio[1253]: verbose: ControllerMpd::parseState Jan 11 20:31:41 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:31:41 sala volumio[1253]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 11 20:31:41 sala volumio[1253]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:31:41 sala volumio[1253]: info: ControllerMpd::pushState Jan 11 20:31:41 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:41 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:41 sala volumio[1253]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 11 20:31:41 sala volumio[1253]: info: ------------------------------ 10ms Jan 11 20:31:45 sala volumio[1253]: info: Jan 11 20:31:45 sala volumio[1253]: ---------------------------- MPD announces system playlist update Jan 11 20:31:45 sala volumio[1253]: info: Ignoring MPD Status Update Jan 11 20:31:45 sala volumio[1253]: info: Jan 11 20:31:45 sala volumio[1253]: ---------------------------- MPD announces state update: player Jan 11 20:31:45 sala volumio[1253]: info: ControllerMpd::getState Jan 11 20:31:45 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:31:45 sala volumio[1253]: info: ------------------------------ 2ms Jan 11 20:31:45 sala volumio[1253]: info: sendMpdCommand status took 1 milliseconds Jan 11 20:31:45 sala volumio[1253]: verbose: ControllerMpd::parseState Jan 11 20:31:45 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:31:45 sala volumio[1253]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 11 20:31:45 sala volumio[1253]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:31:45 sala volumio[1253]: info: ControllerMpd::pushState Jan 11 20:31:45 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:45 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:45 sala volumio[1253]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 11 20:31:45 sala volumio[1253]: info: ------------------------------ 5ms Jan 11 20:31:47 sala volumio[1253]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 20:31:47 sala volumio[1253]: info: CoreCommandRouter::Close All Modals sent Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::volumioNext Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::next Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::stop Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::stPlaybackTimer Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::updateTrackBlock Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrackBlock Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::serviceStop Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::serviceStop Jan 11 20:31:49 sala volumio[1253]: info: ControllerMpd::stop Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 20:31:49 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:49 sala volumio[1253]: info: Jan 11 20:31:49 sala volumio[1253]: ---------------------------- MPD announces state update: player Jan 11 20:31:49 sala volumio[1253]: info: sendMpdCommand stop took 24 milliseconds Jan 11 20:31:49 sala volumio[1253]: info: ControllerMpd::getState Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/26050.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"A Day At The Beach 1","title":"A Day At The Beach 1","artist":"Airbag","album":"A Day At The Beach","streaming":true,"disableUiControls":true,"duration":30,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 20:31:49 sala volumio[1253]: verbose: CURRENT POSITION 0 Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::syncState stateService stop Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: info: No code Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::play index undefined Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::startPlaybackTimer Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 11 20:31:49 sala volumio[1253]: info: [RadioParadise] Matched station: Radio Paradise Rock Mix (FLAC), chan=2 Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::updateTrackBlock Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrackBlock Jan 11 20:31:49 sala volumio[1253]: info: sendMpdCommand status took 29 milliseconds Jan 11 20:31:49 sala volumio[1253]: info: sendMpdCommand stop took 2 milliseconds Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::parseState Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 20:31:49 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:49 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:49 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:49 sala volumio[1253]: info: Jan 11 20:31:49 sala volumio[1253]: ---------------------------- MPD announces system playlist update Jan 11 20:31:49 sala volumio[1253]: info: Ignoring MPD Status Update Jan 11 20:31:49 sala volumio[1253]: info: sendMpdCommand playlistinfo took 24 milliseconds Jan 11 20:31:49 sala volumio[1253]: info: sendMpdCommand clear took 25 milliseconds Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 11 20:31:49 sala volumio[1253]: info: ControllerMpd::pushState Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 11 20:31:49 sala volumio[1253]: info: ------------------------------ 72ms Jan 11 20:31:49 sala volumio[1253]: error: updateQueue error: null Jan 11 20:31:49 sala volumio[1253]: info: ------------------------------ 20ms Jan 11 20:31:49 sala volumio[1253]: info: sendMpdCommand consume 1 took 14 milliseconds Jan 11 20:31:49 sala volumio[1253]: info: [1768159909268] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flacm Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flacm" Jan 11 20:31:49 sala volumio[1253]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 20:31:49 sala volumio[1253]: info: Jan 11 20:31:49 sala volumio[1253]: ---------------------------- MPD announces system playlist update Jan 11 20:31:49 sala volumio[1253]: info: Ignoring MPD Status Update Jan 11 20:31:49 sala volumio[1253]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flacm" took 14 milliseconds Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand play Jan 11 20:31:49 sala volumio[1253]: info: ------------------------------ 11ms Jan 11 20:31:49 sala volumio[1253]: info: sendMpdCommand play took 5 milliseconds Jan 11 20:31:49 sala volumio[1253]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=2 Jan 11 20:31:49 sala volumio[1253]: info: [1768159909296] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Jan 11 20:31:49 sala systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7575. Jan 11 20:31:49 sala systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 11 20:31:49 sala systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 11 20:31:49 sala upmpdcli[8169]: Could not open config: /tmp/upmpdcli.conf Jan 11 20:31:49 sala systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:31:49 sala systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 11 20:31:49 sala volumio[1253]: info: Jan 11 20:31:49 sala volumio[1253]: ---------------------------- MPD announces state update: player Jan 11 20:31:49 sala volumio[1253]: info: ControllerMpd::getState Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:31:49 sala volumio[1253]: info: [1768159909910] [RadioParadise] received new metadata: {"time":18,"artist":"Airbag","title":"A Day At The Beach 1","album":"A Day At The Beach","year":"1900","cover":"https://img.radioparadise.com/covers/l/26050.jpg","cover_med":"https://img.radioparadise.com/covers/m/26050.jpg","cover_small":"https://img.radioparadise.com/covers/s/26050.jpg"} Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/26050.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"A Day At The Beach 1","title":"A Day At The Beach 1","artist":"Airbag","album":"A Day At The Beach","streaming":true,"disableUiControls":true,"duration":23,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 20:31:49 sala volumio[1253]: verbose: CURRENT POSITION 0 Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::syncState stateService play Jan 11 20:31:49 sala volumio[1253]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:31:49 sala volumio[1253]: info: [1768159909911] [RadioParadise] setting new timer with duration of 23000 seconds. Jan 11 20:31:49 sala volumio[1253]: info: Jan 11 20:31:49 sala volumio[1253]: ---------------------------- MPD announces state update: player Jan 11 20:31:49 sala volumio[1253]: info: sendMpdCommand status took 28 milliseconds Jan 11 20:31:49 sala volumio[1253]: info: ControllerMpd::getState Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::parseState Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:31:49 sala volumio[1253]: info: sendMpdCommand status took 1 milliseconds Jan 11 20:31:49 sala volumio[1253]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::parseState Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:31:49 sala volumio[1253]: info: ControllerMpd::pushState Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 11 20:31:49 sala volumio[1253]: info: ------------------------------ 33ms Jan 11 20:31:49 sala volumio[1253]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:31:49 sala volumio[1253]: info: ControllerMpd::pushState Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 11 20:31:49 sala volumio[1253]: info: ------------------------------ 12ms Jan 11 20:31:49 sala volumio[1253]: info: Jan 11 20:31:49 sala volumio[1253]: ---------------------------- MPD announces system playlist update Jan 11 20:31:49 sala volumio[1253]: info: Ignoring MPD Status Update Jan 11 20:31:49 sala volumio[1253]: info: Jan 11 20:31:49 sala volumio[1253]: ---------------------------- MPD announces state update: player Jan 11 20:31:49 sala volumio[1253]: info: ControllerMpd::getState Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:31:49 sala volumio[1253]: info: ------------------------------ 6ms Jan 11 20:31:49 sala volumio[1253]: info: sendMpdCommand status took 6 milliseconds Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::parseState Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:31:49 sala volumio[1253]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 11 20:31:49 sala volumio[1253]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:31:49 sala volumio[1253]: info: ControllerMpd::pushState Jan 11 20:31:49 sala volumio[1253]: info: CoreCommandRouter::servicePushState Jan 11 20:31:49 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:49 sala volumio[1253]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 11 20:31:49 sala volumio[1253]: info: ------------------------------ 9ms Jan 11 20:31:50 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Jan 11 20:31:51 sala volumio[1253]: info: Preload queue cleared Jan 11 20:31:51 sala volumio[1253]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 20:31:51 sala volumio[1253]: info: CoreStateMachine::ClearQueue Jan 11 20:31:51 sala volumio[1253]: info: CoreStateMachine::stop Jan 11 20:31:51 sala volumio[1253]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 20:31:51 sala volumio[1253]: info: CoreStateMachine::stPlaybackTimer Jan 11 20:31:51 sala volumio[1253]: info: CoreStateMachine::updateTrackBlock Jan 11 20:31:51 sala volumio[1253]: info: CorePlayQueue::getTrackBlock Jan 11 20:31:51 sala volumio[1253]: info: CoreStateMachine::pushState Jan 11 20:31:51 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:51 sala volumio[1253]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:31:51 sala volumio[1253]: info: CoreCommandRouter::volumioPushState Jan 11 20:31:51 sala volumio[1253]: info: CoreCommandRouter::volumioGetState Jan 11 20:31:51 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:51 sala volumio[1253]: info: CoreStateMachine::serviceStop Jan 11 20:31:51 sala volumio[1253]: info: CorePlayQueue::getTrack 0 Jan 11 20:31:51 sala volumio[1253]: info: CoreCommandRouter::serviceStop Jan 11 20:31:51 sala volumio[1253]: info: ControllerMpd::stop Jan 11 20:31:51 sala volumio[1253]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 20:31:51 sala volumio[1253]: info: CorePlayQueue::clearPlayQueue Jan 11 20:31:51 sala volumio[1253]: info: CorePlayQueue::saveQueue Jan 11 20:31:51 sala volumio[1253]: info: CoreCommandRouter::volumioPushQueue Jan 11 20:31:51 sala volumio[1253]: info: CoreStateMachine::addQueueItems Jan 11 20:31:51 sala volumio[1253]: info: CorePlayQueue::addQueueItems Jan 11 20:31:51 sala volumio[1253]: info: Preload queue cleared Jan 11 20:31:51 sala volumio[1253]: info: Adding Item to queue: https://stream.radioparadise.com/rock-flacm Jan 11 20:31:51 sala volumio[1253]: info: Exploding uri https://stream.radioparadise.com/rock-flacm in service radio_paradise Jan 11 20:31:51 sala volumio[1253]: error: [RadioParadise] explodeUri: No station found for uri "https://stream.radioparadise.com/rock-flacm" Jan 11 20:31:51 sala volumio[1253]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 20:31:51 sala volumio[1253]: TypeError: Cannot read properties of undefined (reading 'then') Jan 11 20:31:51 sala volumio[1253]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) Jan 11 20:31:51 sala volumio[1253]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Jan 11 20:31:51 sala volumio[1253]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Jan 11 20:31:51 sala volumio[1253]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Jan 11 20:31:51 sala volumio[1253]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:251:25) Jan 11 20:31:51 sala volumio[1253]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:511:25) Jan 11 20:31:51 sala volumio[1253]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Jan 11 20:31:51 sala volumio[1253]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Jan 11 20:31:51 sala volumio[1253]: at Socket.emit (node:events:514:28) Jan 11 20:31:51 sala volumio[1253]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jan 11 20:31:51 sala volumio[1253]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 20:31:51 sala sudo[8198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-11 20:30' Jan 11 20:31:51 sala sudo[8198]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"