Aug 30 08:26:02 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 08:26:02 volumio-markus volumio[1195]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 30 08:26:02 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 30 08:26:02 volumio-markus volumio[1195]: info: Received Get System Version Aug 30 08:26:02 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 08:26:02 volumio-markus volumio[1195]: info: Received Get System Info Aug 30 08:26:02 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 08:26:02 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 08:26:02 volumio-markus volumio[1195]: info: Discovery: Getting this device information Aug 30 08:26:02 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioGetState Aug 30 08:26:02 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 08:26:02 volumio-markus systemd[1]: systemd-timedated.service: Deactivated successfully. Aug 30 08:26:20 volumio-markus go-librespot[1567]: time="2025-08-30T08:26:20+02:00" level=trace msg="sent dealer ping" Aug 30 08:26:21 volumio-markus go-librespot[1567]: time="2025-08-30T08:26:21+02:00" level=trace msg="received dealer pong" Aug 30 08:26:22 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPlay Aug 30 08:26:22 volumio-markus volumio[1195]: info: CoreStateMachine::play index undefined Aug 30 08:26:22 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 08:26:22 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:22 volumio-markus volumio[1195]: info: CoreStateMachine::startPlaybackTimer Aug 30 08:26:22 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:22 volumio-markus volumio[1195]: info: [1756535182421] ControllerWebradio::clearAddPlayTrack Aug 30 08:26:22 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 08:26:22 volumio-markus mpd[1441]: player: played "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" Aug 30 08:26:22 volumio-markus volumio[1195]: info: Aug 30 08:26:22 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:22 volumio-markus volumio[1195]: info: sendMpdCommand stop took 4 milliseconds Aug 30 08:26:22 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:22 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:22 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 08:26:22 volumio-markus volumio[1195]: info: Aug 30 08:26:22 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:22 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:22 volumio-markus volumio[1195]: info: sendMpdCommand status took 1 milliseconds Aug 30 08:26:22 volumio-markus volumio[1195]: info: sendMpdCommand clear took 1 milliseconds Aug 30 08:26:22 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:22 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:22 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand load "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" Aug 30 08:26:22 volumio-markus volumio[1195]: info: Aug 30 08:26:22 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:22 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:22 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:22 volumio-markus volumio[1195]: info: Aug 30 08:26:22 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:22 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:22 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:22 volumio-markus volumio[1195]: error: updateQueue error: null Aug 30 08:26:22 volumio-markus volumio[1195]: info: ------------------------------ 5ms Aug 30 08:26:22 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 30 08:26:22 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:22 volumio-markus volumio[1195]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 30 08:26:22 volumio-markus volumio[1195]: info: ------------------------------ 8ms Aug 30 08:26:22 volumio-markus mpd[1441]: exception: No such playlist Aug 30 08:26:22 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand add "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" Aug 30 08:26:22 volumio-markus volumio[1195]: info: sendMpdCommand status took 545 milliseconds Aug 30 08:26:22 volumio-markus volumio[1195]: info: sendMpdCommand status took 544 milliseconds Aug 30 08:26:22 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:22 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:22 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:22 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:22 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:22 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:22 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:26:22 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:22 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:22 volumio-markus volumio[1195]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:26:22 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:22 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:22 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:22 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:22 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:22 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:22 volumio-markus volumio[1195]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:26:22 volumio-markus volumio[1195]: info: ------------------------------ 564ms Aug 30 08:26:22 volumio-markus volumio[1195]: info: ------------------------------ 564ms Aug 30 08:26:22 volumio-markus volumio[1195]: info: Aug 30 08:26:22 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:22 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:22 volumio-markus volumio[1195]: info: Aug 30 08:26:22 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:22 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:22 volumio-markus volumio[1195]: info: Aug 30 08:26:22 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:22 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:22 volumio-markus volumio[1195]: info: sendMpdCommand add "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" took 26 milliseconds Aug 30 08:26:22 volumio-markus volumio[1195]: info: ------------------------------ 5ms Aug 30 08:26:22 volumio-markus volumio[1195]: info: ------------------------------ 4ms Aug 30 08:26:22 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 08:26:22 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand play Aug 30 08:26:23 volumio-markus volumio[1195]: info: Aug 30 08:26:23 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:23 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:23 volumio-markus volumio[1195]: info: Aug 30 08:26:23 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:23 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:23 volumio-markus volumio[1195]: info: ------------------------------ 19ms Aug 30 08:26:23 volumio-markus volumio[1195]: info: sendMpdCommand play took 17 milliseconds Aug 30 08:26:23 volumio-markus volumio[1195]: info: ------------------------------ 17ms Aug 30 08:26:23 volumio-markus volumio[1195]: info: ------------------------------ 15ms Aug 30 08:26:23 volumio-markus volumio[1195]: info: Aug 30 08:26:23 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:23 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:23 volumio-markus volumio[1195]: info: Aug 30 08:26:23 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:23 volumio-markus mpd[1441]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Aug 30 08:26:23 volumio-markus mpd[1441]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Aug 30 08:26:23 volumio-markus mpd[1441]: player: problems opening audio device while playing "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" Aug 30 08:26:23 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:23 volumio-markus volumio[1195]: info: Aug 30 08:26:23 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:23 volumio-markus volumio[1195]: info: Aug 30 08:26:23 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:23 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:26:23 volumio-markus volumio[1195]: info: sendMpdCommand status took 8 milliseconds Aug 30 08:26:23 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:23 volumio-markus volumio[1195]: info: Aug 30 08:26:23 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:23 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:26:23 volumio-markus volumio[1195]: info: sendMpdCommand status took 19 milliseconds Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:26:23 volumio-markus volumio[1195]: info: sendMpdCommand status took 19 milliseconds Aug 30 08:26:23 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 14 milliseconds Aug 30 08:26:23 volumio-markus volumio[1195]: info: sendMpdCommand status took 13 milliseconds Aug 30 08:26:23 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:23 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: ------------------------------ 54ms Aug 30 08:26:23 volumio-markus volumio[1195]: info: Aug 30 08:26:23 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:23 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:23 volumio-markus volumio[1195]: info: sendMpdCommand status took 56 milliseconds Aug 30 08:26:23 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 48 milliseconds Aug 30 08:26:23 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 47 milliseconds Aug 30 08:26:23 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 39 milliseconds Aug 30 08:26:23 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 39 milliseconds Aug 30 08:26:23 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 38 milliseconds Aug 30 08:26:23 volumio-markus volumio[1195]: info: sendMpdCommand status took 14 milliseconds Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:23 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: ------------------------------ 104ms Aug 30 08:26:23 volumio-markus volumio[1195]: info: ------------------------------ 104ms Aug 30 08:26:23 volumio-markus volumio[1195]: info: ------------------------------ 103ms Aug 30 08:26:23 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 39 milliseconds Aug 30 08:26:23 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 38 milliseconds Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:23 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:26:23 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:23 volumio-markus volumio[1195]: info: ------------------------------ 122ms Aug 30 08:26:23 volumio-markus volumio[1195]: info: ------------------------------ 80ms Aug 30 08:26:30 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioGetQueue Aug 30 08:26:30 volumio-markus volumio[1195]: info: CoreStateMachine::getQueue Aug 30 08:26:30 volumio-markus volumio[1195]: info: CorePlayQueue::getQueue Aug 30 08:26:35 volumio-markus volumio[1195]: info: Preload queue cleared Aug 30 08:26:35 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 08:26:35 volumio-markus volumio[1195]: info: CoreStateMachine::ClearQueue Aug 30 08:26:35 volumio-markus volumio[1195]: info: CoreStateMachine::stop Aug 30 08:26:35 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 08:26:35 volumio-markus volumio[1195]: info: CorePlayQueue::clearPlayQueue Aug 30 08:26:35 volumio-markus volumio[1195]: info: CorePlayQueue::saveQueue Aug 30 08:26:35 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushQueue Aug 30 08:26:35 volumio-markus volumio[1195]: info: CoreStateMachine::addQueueItems Aug 30 08:26:35 volumio-markus volumio[1195]: info: CorePlayQueue::addQueueItems Aug 30 08:26:35 volumio-markus volumio[1195]: info: Preload queue cleared Aug 30 08:26:35 volumio-markus volumio[1195]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s15033 Aug 30 08:26:35 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 08:26:35 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushQueue Aug 30 08:26:35 volumio-markus volumio[1195]: info: CorePlayQueue::saveQueue Aug 30 08:26:35 volumio-markus volumio[1195]: info: CoreStateMachine::updateTrackBlock Aug 30 08:26:35 volumio-markus volumio[1195]: info: CorePlayQueue::getTrackBlock Aug 30 08:26:35 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPlay Aug 30 08:26:35 volumio-markus volumio[1195]: info: CoreStateMachine::play index 0 Aug 30 08:26:35 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 08:26:35 volumio-markus volumio[1195]: info: CoreStateMachine::stop Aug 30 08:26:35 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 08:26:35 volumio-markus volumio[1195]: info: CoreStateMachine::play index undefined Aug 30 08:26:35 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 08:26:35 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:35 volumio-markus volumio[1195]: info: CoreStateMachine::startPlaybackTimer Aug 30 08:26:35 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:35 volumio-markus volumio[1195]: info: [1756535195783] ControllerWebradio::clearAddPlayTrack Aug 30 08:26:35 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 08:26:35 volumio-markus mpd[1441]: player: played "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" Aug 30 08:26:35 volumio-markus volumio[1195]: info: Aug 30 08:26:35 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:35 volumio-markus volumio[1195]: info: sendMpdCommand stop took 6 milliseconds Aug 30 08:26:35 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:35 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:35 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 08:26:35 volumio-markus volumio[1195]: info: Aug 30 08:26:35 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:35 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:35 volumio-markus volumio[1195]: info: sendMpdCommand status took 1 milliseconds Aug 30 08:26:35 volumio-markus volumio[1195]: info: sendMpdCommand clear took 1 milliseconds Aug 30 08:26:35 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:35 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:35 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand load "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" Aug 30 08:26:35 volumio-markus volumio[1195]: info: Aug 30 08:26:35 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:35 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:35 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:35 volumio-markus volumio[1195]: info: Aug 30 08:26:35 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:35 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:35 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:35 volumio-markus volumio[1195]: error: updateQueue error: null Aug 30 08:26:35 volumio-markus volumio[1195]: info: ------------------------------ 7ms Aug 30 08:26:35 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 30 08:26:35 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:35 volumio-markus volumio[1195]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 30 08:26:35 volumio-markus volumio[1195]: info: ------------------------------ 11ms Aug 30 08:26:36 volumio-markus mpd[1441]: exception: No such playlist Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand add "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" Aug 30 08:26:36 volumio-markus volumio[1195]: info: sendMpdCommand status took 534 milliseconds Aug 30 08:26:36 volumio-markus volumio[1195]: info: sendMpdCommand status took 533 milliseconds Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:36 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:36 volumio-markus volumio[1195]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:26:36 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:36 volumio-markus volumio[1195]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:26:36 volumio-markus volumio[1195]: info: ------------------------------ 556ms Aug 30 08:26:36 volumio-markus volumio[1195]: info: ------------------------------ 555ms Aug 30 08:26:36 volumio-markus volumio[1195]: info: Aug 30 08:26:36 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:36 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:36 volumio-markus volumio[1195]: info: Aug 30 08:26:36 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:36 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:36 volumio-markus volumio[1195]: info: Aug 30 08:26:36 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:36 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:36 volumio-markus volumio[1195]: info: sendMpdCommand add "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" took 27 milliseconds Aug 30 08:26:36 volumio-markus volumio[1195]: info: ------------------------------ 6ms Aug 30 08:26:36 volumio-markus volumio[1195]: info: ------------------------------ 5ms Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand play Aug 30 08:26:36 volumio-markus volumio[1195]: info: Aug 30 08:26:36 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:36 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:36 volumio-markus volumio[1195]: info: Aug 30 08:26:36 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:36 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:36 volumio-markus volumio[1195]: info: ------------------------------ 15ms Aug 30 08:26:36 volumio-markus volumio[1195]: info: sendMpdCommand play took 13 milliseconds Aug 30 08:26:36 volumio-markus volumio[1195]: info: ------------------------------ 13ms Aug 30 08:26:36 volumio-markus volumio[1195]: info: ------------------------------ 12ms Aug 30 08:26:36 volumio-markus mpd[1441]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Aug 30 08:26:36 volumio-markus mpd[1441]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Aug 30 08:26:36 volumio-markus volumio[1195]: info: Aug 30 08:26:36 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:36 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:36 volumio-markus mpd[1441]: player: problems opening audio device while playing "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" Aug 30 08:26:36 volumio-markus volumio[1195]: info: Aug 30 08:26:36 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:36 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:36 volumio-markus volumio[1195]: info: Aug 30 08:26:36 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:36 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:36 volumio-markus volumio[1195]: info: Aug 30 08:26:36 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:36 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:36 volumio-markus volumio[1195]: info: Aug 30 08:26:36 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:26:36 volumio-markus volumio[1195]: info: sendMpdCommand status took 15 milliseconds Aug 30 08:26:36 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:26:36 volumio-markus volumio[1195]: info: sendMpdCommand status took 15 milliseconds Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:26:36 volumio-markus volumio[1195]: info: sendMpdCommand status took 15 milliseconds Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:26:36 volumio-markus volumio[1195]: info: sendMpdCommand status took 16 milliseconds Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:36 volumio-markus volumio[1195]: info: Aug 30 08:26:36 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:36 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:36 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 31 milliseconds Aug 30 08:26:36 volumio-markus volumio[1195]: info: sendMpdCommand status took 32 milliseconds Aug 30 08:26:36 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 31 milliseconds Aug 30 08:26:36 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 30 milliseconds Aug 30 08:26:36 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 30 milliseconds Aug 30 08:26:36 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 30 08:26:36 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 30 08:26:36 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 30 08:26:36 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:36 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:26:36 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:36 volumio-markus volumio[1195]: info: ------------------------------ 98ms Aug 30 08:26:36 volumio-markus volumio[1195]: info: ------------------------------ 98ms Aug 30 08:26:36 volumio-markus volumio[1195]: info: ------------------------------ 98ms Aug 30 08:26:36 volumio-markus volumio[1195]: info: ------------------------------ 98ms Aug 30 08:26:37 volumio-markus volumio[1195]: info: sendMpdCommand status took 78 milliseconds Aug 30 08:26:37 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 73 milliseconds Aug 30 08:26:37 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:37 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:37 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:37 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:37 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:37 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:37 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:26:37 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:37 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:37 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:37 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:37 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:26:37 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:37 volumio-markus volumio[1195]: info: ------------------------------ 134ms Aug 30 08:26:37 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 18 milliseconds Aug 30 08:26:37 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:37 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:37 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:37 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:37 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:26:37 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:37 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:37 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:37 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:37 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:37 volumio-markus volumio[1195]: info: ------------------------------ 114ms Aug 30 08:26:39 volumio-markus volumio[1195]: info: Preload queue cleared Aug 30 08:26:39 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 08:26:39 volumio-markus volumio[1195]: info: CoreStateMachine::ClearQueue Aug 30 08:26:39 volumio-markus volumio[1195]: info: CoreStateMachine::stop Aug 30 08:26:39 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 08:26:39 volumio-markus volumio[1195]: info: CorePlayQueue::clearPlayQueue Aug 30 08:26:39 volumio-markus volumio[1195]: info: CorePlayQueue::saveQueue Aug 30 08:26:39 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushQueue Aug 30 08:26:39 volumio-markus volumio[1195]: info: CoreStateMachine::addQueueItems Aug 30 08:26:39 volumio-markus volumio[1195]: info: CorePlayQueue::addQueueItems Aug 30 08:26:39 volumio-markus volumio[1195]: info: Preload queue cleared Aug 30 08:26:39 volumio-markus volumio[1195]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s15024 Aug 30 08:26:39 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 08:26:39 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushQueue Aug 30 08:26:39 volumio-markus volumio[1195]: info: CorePlayQueue::saveQueue Aug 30 08:26:39 volumio-markus volumio[1195]: info: CoreStateMachine::updateTrackBlock Aug 30 08:26:39 volumio-markus volumio[1195]: info: CorePlayQueue::getTrackBlock Aug 30 08:26:39 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPlay Aug 30 08:26:39 volumio-markus volumio[1195]: info: CoreStateMachine::play index 0 Aug 30 08:26:39 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 08:26:39 volumio-markus volumio[1195]: info: CoreStateMachine::stop Aug 30 08:26:39 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 08:26:39 volumio-markus volumio[1195]: info: CoreStateMachine::play index undefined Aug 30 08:26:39 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 08:26:39 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:39 volumio-markus volumio[1195]: info: CoreStateMachine::startPlaybackTimer Aug 30 08:26:39 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:39 volumio-markus volumio[1195]: info: [1756535199393] ControllerWebradio::clearAddPlayTrack Aug 30 08:26:39 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 08:26:39 volumio-markus mpd[1441]: player: played "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" Aug 30 08:26:39 volumio-markus volumio[1195]: info: Aug 30 08:26:39 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:39 volumio-markus volumio[1195]: info: sendMpdCommand stop took 4 milliseconds Aug 30 08:26:39 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:39 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:39 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 08:26:39 volumio-markus volumio[1195]: info: Aug 30 08:26:39 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:39 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:39 volumio-markus volumio[1195]: info: sendMpdCommand status took 1 milliseconds Aug 30 08:26:39 volumio-markus volumio[1195]: info: sendMpdCommand clear took 2 milliseconds Aug 30 08:26:39 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:39 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:39 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" Aug 30 08:26:39 volumio-markus volumio[1195]: info: Aug 30 08:26:39 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:39 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:39 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:39 volumio-markus volumio[1195]: info: Aug 30 08:26:39 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:39 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:39 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:39 volumio-markus volumio[1195]: error: updateQueue error: null Aug 30 08:26:39 volumio-markus volumio[1195]: info: ------------------------------ 5ms Aug 30 08:26:39 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 30 08:26:39 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:39 volumio-markus volumio[1195]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 30 08:26:39 volumio-markus volumio[1195]: info: ------------------------------ 8ms Aug 30 08:26:40 volumio-markus mpd[1441]: exception: No such playlist Aug 30 08:26:40 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" Aug 30 08:26:40 volumio-markus volumio[1195]: info: sendMpdCommand status took 653 milliseconds Aug 30 08:26:40 volumio-markus volumio[1195]: info: sendMpdCommand status took 653 milliseconds Aug 30 08:26:40 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:40 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:40 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:40 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:40 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:40 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:40 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:26:40 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:40 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:40 volumio-markus volumio[1195]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:26:40 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:40 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:40 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:40 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:40 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:40 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:40 volumio-markus volumio[1195]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:26:40 volumio-markus volumio[1195]: info: ------------------------------ 676ms Aug 30 08:26:40 volumio-markus volumio[1195]: info: ------------------------------ 676ms Aug 30 08:26:40 volumio-markus volumio[1195]: info: Aug 30 08:26:40 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:40 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:40 volumio-markus volumio[1195]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" took 24 milliseconds Aug 30 08:26:40 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 08:26:40 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand play Aug 30 08:26:40 volumio-markus volumio[1195]: info: Aug 30 08:26:40 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:40 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:40 volumio-markus volumio[1195]: info: Aug 30 08:26:40 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:40 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:40 volumio-markus volumio[1195]: info: ------------------------------ 18ms Aug 30 08:26:40 volumio-markus volumio[1195]: info: sendMpdCommand play took 17 milliseconds Aug 30 08:26:40 volumio-markus volumio[1195]: info: ------------------------------ 17ms Aug 30 08:26:40 volumio-markus volumio[1195]: info: ------------------------------ 16ms Aug 30 08:26:40 volumio-markus volumio[1195]: info: Aug 30 08:26:40 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:40 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:40 volumio-markus volumio[1195]: info: Aug 30 08:26:40 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:40 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:40 volumio-markus volumio[1195]: info: ------------------------------ 4ms Aug 30 08:26:40 volumio-markus volumio[1195]: info: ------------------------------ 4ms Aug 30 08:26:41 volumio-markus volumio[1195]: info: Aug 30 08:26:41 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:41 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:41 volumio-markus mpd[1441]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Aug 30 08:26:41 volumio-markus mpd[1441]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Aug 30 08:26:41 volumio-markus mpd[1441]: player: problems opening audio device while playing "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" Aug 30 08:26:41 volumio-markus volumio[1195]: info: Aug 30 08:26:41 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:41 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:41 volumio-markus volumio[1195]: info: Aug 30 08:26:41 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:41 volumio-markus volumio[1195]: info: Aug 30 08:26:41 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:41 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:26:41 volumio-markus volumio[1195]: info: sendMpdCommand status took 7 milliseconds Aug 30 08:26:41 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:41 volumio-markus volumio[1195]: info: Aug 30 08:26:41 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:41 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:41 volumio-markus volumio[1195]: info: Aug 30 08:26:41 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:41 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:26:41 volumio-markus volumio[1195]: info: sendMpdCommand status took 14 milliseconds Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:26:41 volumio-markus volumio[1195]: info: sendMpdCommand status took 13 milliseconds Aug 30 08:26:41 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 8 milliseconds Aug 30 08:26:41 volumio-markus volumio[1195]: info: sendMpdCommand status took 9 milliseconds Aug 30 08:26:41 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 30 08:26:41 volumio-markus volumio[1195]: info: sendMpdCommand status took 8 milliseconds Aug 30 08:26:41 volumio-markus volumio[1195]: info: sendMpdCommand status took 7 milliseconds Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:41 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""} Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: ------------------------------ 36ms Aug 30 08:26:41 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 33 milliseconds Aug 30 08:26:41 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 34 milliseconds Aug 30 08:26:41 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 29 milliseconds Aug 30 08:26:41 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 28 milliseconds Aug 30 08:26:41 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 29 milliseconds Aug 30 08:26:41 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 28 milliseconds Aug 30 08:26:41 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 29 milliseconds Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:41 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""} Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""} Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""} Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""} Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""} Aug 30 08:26:41 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:41 volumio-markus volumio[1195]: info: ------------------------------ 99ms Aug 30 08:26:41 volumio-markus volumio[1195]: info: ------------------------------ 99ms Aug 30 08:26:41 volumio-markus volumio[1195]: info: ------------------------------ 98ms Aug 30 08:26:41 volumio-markus volumio[1195]: info: ------------------------------ 92ms Aug 30 08:26:41 volumio-markus volumio[1195]: info: ------------------------------ 92ms Aug 30 08:26:43 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPlay Aug 30 08:26:43 volumio-markus volumio[1195]: info: CoreStateMachine::play index undefined Aug 30 08:26:43 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 08:26:43 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:43 volumio-markus volumio[1195]: info: CoreStateMachine::startPlaybackTimer Aug 30 08:26:43 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:43 volumio-markus volumio[1195]: info: [1756535203173] ControllerWebradio::clearAddPlayTrack Aug 30 08:26:43 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 08:26:43 volumio-markus mpd[1441]: player: played "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" Aug 30 08:26:43 volumio-markus volumio[1195]: info: Aug 30 08:26:43 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:43 volumio-markus volumio[1195]: info: sendMpdCommand stop took 8 milliseconds Aug 30 08:26:43 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:43 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:43 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 08:26:43 volumio-markus volumio[1195]: info: Aug 30 08:26:43 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:43 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:43 volumio-markus volumio[1195]: info: sendMpdCommand status took 2 milliseconds Aug 30 08:26:43 volumio-markus volumio[1195]: info: sendMpdCommand clear took 1 milliseconds Aug 30 08:26:43 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:43 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:43 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" Aug 30 08:26:43 volumio-markus volumio[1195]: info: Aug 30 08:26:43 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:43 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:43 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:43 volumio-markus volumio[1195]: info: Aug 30 08:26:43 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:43 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:43 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:43 volumio-markus volumio[1195]: error: updateQueue error: null Aug 30 08:26:43 volumio-markus volumio[1195]: info: ------------------------------ 7ms Aug 30 08:26:43 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 30 08:26:43 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:43 volumio-markus volumio[1195]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 30 08:26:43 volumio-markus volumio[1195]: info: ------------------------------ 9ms Aug 30 08:26:44 volumio-markus mpd[1441]: exception: No such playlist Aug 30 08:26:44 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" Aug 30 08:26:44 volumio-markus volumio[1195]: info: Aug 30 08:26:44 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:44 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:44 volumio-markus volumio[1195]: info: sendMpdCommand status took 872 milliseconds Aug 30 08:26:44 volumio-markus volumio[1195]: info: sendMpdCommand status took 870 milliseconds Aug 30 08:26:44 volumio-markus volumio[1195]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" took 2 milliseconds Aug 30 08:26:44 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:44 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 08:26:44 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand play Aug 30 08:26:44 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:44 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:44 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 30 08:26:44 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService stop Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:44 volumio-markus volumio[1195]: info: No code Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:44 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:44 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:44 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 30 08:26:44 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService stop Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:44 volumio-markus volumio[1195]: info: No code Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:44 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:44 volumio-markus volumio[1195]: info: ------------------------------ 924ms Aug 30 08:26:44 volumio-markus volumio[1195]: info: ------------------------------ 924ms Aug 30 08:26:44 volumio-markus volumio[1195]: info: Aug 30 08:26:44 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:44 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:44 volumio-markus volumio[1195]: info: Aug 30 08:26:44 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:44 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:44 volumio-markus volumio[1195]: info: ------------------------------ 55ms Aug 30 08:26:44 volumio-markus volumio[1195]: info: sendMpdCommand play took 54 milliseconds Aug 30 08:26:44 volumio-markus volumio[1195]: info: ------------------------------ 3ms Aug 30 08:26:44 volumio-markus volumio[1195]: info: ------------------------------ 2ms Aug 30 08:26:44 volumio-markus volumio[1195]: info: Aug 30 08:26:44 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:44 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:44 volumio-markus volumio[1195]: info: Aug 30 08:26:44 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:44 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:44 volumio-markus volumio[1195]: info: ------------------------------ 19ms Aug 30 08:26:44 volumio-markus volumio[1195]: info: ------------------------------ 18ms Aug 30 08:26:45 volumio-markus volumio[1195]: info: Aug 30 08:26:45 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:45 volumio-markus mpd[1441]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Aug 30 08:26:45 volumio-markus mpd[1441]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Aug 30 08:26:45 volumio-markus mpd[1441]: player: problems opening audio device while playing "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" Aug 30 08:26:45 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:45 volumio-markus volumio[1195]: info: Aug 30 08:26:45 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:45 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:45 volumio-markus volumio[1195]: info: Aug 30 08:26:45 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:45 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:45 volumio-markus volumio[1195]: info: Aug 30 08:26:45 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:45 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:45 volumio-markus volumio[1195]: info: Aug 30 08:26:45 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:45 volumio-markus volumio[1195]: info: Aug 30 08:26:45 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:45 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand status took 9 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand status took 10 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand status took 9 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand status took 29 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand status took 29 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 26 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand status took 25 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 24 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 23 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:45 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""} Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""} Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""} Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: ------------------------------ 69ms Aug 30 08:26:45 volumio-markus volumio[1195]: info: ------------------------------ 68ms Aug 30 08:26:45 volumio-markus volumio[1195]: info: ------------------------------ 67ms Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 52 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 51 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 43 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 42 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 42 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:45 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""} Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""} Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""} Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: ------------------------------ 108ms Aug 30 08:26:45 volumio-markus volumio[1195]: info: ------------------------------ 108ms Aug 30 08:26:45 volumio-markus volumio[1195]: info: ------------------------------ 108ms Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPlay Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::play index undefined Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 08:26:45 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::startPlaybackTimer Aug 30 08:26:45 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:45 volumio-markus volumio[1195]: info: [1756535205365] ControllerWebradio::clearAddPlayTrack Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 08:26:45 volumio-markus mpd[1441]: player: played "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" Aug 30 08:26:45 volumio-markus volumio[1195]: info: Aug 30 08:26:45 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand stop took 7 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 08:26:45 volumio-markus volumio[1195]: info: Aug 30 08:26:45 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:45 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:45 volumio-markus volumio[1195]: info: Aug 30 08:26:45 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:45 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand status took 1 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand clear took 1 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" Aug 30 08:26:45 volumio-markus volumio[1195]: info: Aug 30 08:26:45 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:45 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:45 volumio-markus volumio[1195]: info: Aug 30 08:26:45 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:45 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:45 volumio-markus volumio[1195]: error: updateQueue error: null Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand status took 3 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: info: ------------------------------ 3ms Aug 30 08:26:45 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:45 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:45 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:26:45 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:45 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:45 volumio-markus volumio[1195]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:26:45 volumio-markus volumio[1195]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 30 08:26:45 volumio-markus volumio[1195]: info: ------------------------------ 15ms Aug 30 08:26:45 volumio-markus volumio[1195]: info: ------------------------------ 16ms Aug 30 08:26:46 volumio-markus mpd[1441]: exception: No such playlist Aug 30 08:26:46 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" Aug 30 08:26:46 volumio-markus volumio[1195]: error: updateQueue error: null Aug 30 08:26:46 volumio-markus volumio[1195]: info: Aug 30 08:26:46 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:46 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:46 volumio-markus volumio[1195]: info: sendMpdCommand status took 661 milliseconds Aug 30 08:26:46 volumio-markus volumio[1195]: info: ------------------------------ 661ms Aug 30 08:26:46 volumio-markus volumio[1195]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" took 1 milliseconds Aug 30 08:26:46 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:46 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 08:26:46 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand play Aug 30 08:26:46 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:46 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:46 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:46 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:26:46 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:46 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:46 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 30 08:26:46 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:46 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService stop Aug 30 08:26:46 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:46 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:46 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:46 volumio-markus volumio[1195]: info: No code Aug 30 08:26:46 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:46 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:46 volumio-markus volumio[1195]: info: ------------------------------ 689ms Aug 30 08:26:46 volumio-markus volumio[1195]: info: Aug 30 08:26:46 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:46 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:46 volumio-markus volumio[1195]: info: ------------------------------ 29ms Aug 30 08:26:46 volumio-markus volumio[1195]: info: sendMpdCommand play took 28 milliseconds Aug 30 08:26:46 volumio-markus volumio[1195]: info: ------------------------------ 2ms Aug 30 08:26:46 volumio-markus volumio[1195]: info: Aug 30 08:26:46 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:46 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:46 volumio-markus volumio[1195]: info: Aug 30 08:26:46 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:26:46 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:26:46 volumio-markus volumio[1195]: info: ------------------------------ 12ms Aug 30 08:26:46 volumio-markus volumio[1195]: info: ------------------------------ 12ms Aug 30 08:26:47 volumio-markus volumio[1195]: info: Aug 30 08:26:47 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:47 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:47 volumio-markus volumio[1195]: info: Aug 30 08:26:47 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:47 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:47 volumio-markus mpd[1441]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Aug 30 08:26:47 volumio-markus mpd[1441]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Aug 30 08:26:47 volumio-markus mpd[1441]: player: problems opening audio device while playing "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" Aug 30 08:26:47 volumio-markus volumio[1195]: info: Aug 30 08:26:47 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:47 volumio-markus volumio[1195]: info: Aug 30 08:26:47 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:47 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:26:47 volumio-markus volumio[1195]: info: sendMpdCommand status took 9 milliseconds Aug 30 08:26:47 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:47 volumio-markus volumio[1195]: info: Aug 30 08:26:47 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:47 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:47 volumio-markus volumio[1195]: info: Aug 30 08:26:47 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:26:47 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:26:47 volumio-markus volumio[1195]: info: sendMpdCommand status took 16 milliseconds Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:26:47 volumio-markus volumio[1195]: info: sendMpdCommand status took 16 milliseconds Aug 30 08:26:47 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 10 milliseconds Aug 30 08:26:47 volumio-markus volumio[1195]: info: sendMpdCommand status took 10 milliseconds Aug 30 08:26:47 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 30 08:26:47 volumio-markus volumio[1195]: info: sendMpdCommand status took 8 milliseconds Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:47 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""} Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: ------------------------------ 38ms Aug 30 08:26:47 volumio-markus volumio[1195]: info: sendMpdCommand status took 33 milliseconds Aug 30 08:26:47 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 27 milliseconds Aug 30 08:26:47 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 26 milliseconds Aug 30 08:26:47 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 21 milliseconds Aug 30 08:26:47 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 22 milliseconds Aug 30 08:26:47 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 21 milliseconds Aug 30 08:26:47 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 21 milliseconds Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:47 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""} Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""} Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""} Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""} Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: ------------------------------ 89ms Aug 30 08:26:47 volumio-markus volumio[1195]: info: ------------------------------ 89ms Aug 30 08:26:47 volumio-markus volumio[1195]: info: ------------------------------ 89ms Aug 30 08:26:47 volumio-markus volumio[1195]: info: ------------------------------ 82ms Aug 30 08:26:47 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 47 milliseconds Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:26:47 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""} Aug 30 08:26:47 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:26:47 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:26:47 volumio-markus volumio[1195]: info: ------------------------------ 97ms Aug 30 08:26:51 volumio-markus go-librespot[1567]: time="2025-08-30T08:26:51+02:00" level=trace msg="sent dealer ping" Aug 30 08:26:51 volumio-markus go-librespot[1567]: time="2025-08-30T08:26:51+02:00" level=trace msg="received dealer pong" Aug 30 08:26:56 volumio-markus volumio[1195]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 08:26:56 volumio-markus volumio[1195]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 08:26:56 volumio-markus volumio[1195]: info: Retrieving Cloud Streaming UI Aug 30 08:26:56 volumio-markus volumio[1195]: info: Getting Tidal Cloud Configuration Aug 30 08:26:56 volumio-markus volumio[1195]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 08:26:56 volumio-markus volumio[1195]: info: Getting Qobuz Cloud Configuration Aug 30 08:26:56 volumio-markus volumio[1195]: info: Asking plugin for UI Config Aug 30 08:26:56 volumio-markus volumio[1195]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 08:26:56 volumio-markus volumio[1195]: info: Getting Spotify Cloud Configuration Aug 30 08:26:56 volumio-markus volumio[1195]: info: Asking plugin for UI Config Aug 30 08:26:56 volumio-markus volumio[1195]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 08:26:56 volumio-markus volumio[1195]: info: Saving Spotify Acccount Aug 30 08:26:56 volumio-markus volumio[1195]: info: Got Tidal Cloud Configuration Aug 30 08:26:56 volumio-markus volumio[1195]: info: Got it Aug 30 08:26:56 volumio-markus volumio[1195]: info: Got it Aug 30 08:26:56 volumio-markus volumio[1195]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 30 08:26:56 volumio-markus volumio[1195]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 08:26:56 volumio-markus volumio[1195]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Aug 30 08:26:56 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 30 08:26:56 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 08:26:56 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 08:26:56 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 08:26:56 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 08:26:56 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 08:26:56 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 08:26:56 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 08:26:56 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 08:26:56 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 08:26:56 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 08:26:57 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 08:26:57 volumio-markus mpd[1441]: exception: No database Aug 30 08:26:58 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 30 08:27:05 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 30 08:27:05 volumio-markus volumio[1195]: info: Preload queue cleared Aug 30 08:27:08 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 30 08:27:08 volumio-markus volumio[1195]: info: Preload queue cleared Aug 30 08:27:09 volumio-markus volumio[1195]: info: Preload queue cleared Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreStateMachine::ClearQueue Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreStateMachine::stop Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 08:27:09 volumio-markus volumio[1195]: info: CorePlayQueue::clearPlayQueue Aug 30 08:27:09 volumio-markus volumio[1195]: info: CorePlayQueue::saveQueue Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushQueue Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreStateMachine::addQueueItems Aug 30 08:27:09 volumio-markus volumio[1195]: info: CorePlayQueue::addQueueItems Aug 30 08:27:09 volumio-markus volumio[1195]: info: Preload queue cleared Aug 30 08:27:09 volumio-markus volumio[1195]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s15033 Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushQueue Aug 30 08:27:09 volumio-markus volumio[1195]: info: CorePlayQueue::saveQueue Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreStateMachine::updateTrackBlock Aug 30 08:27:09 volumio-markus volumio[1195]: info: CorePlayQueue::getTrackBlock Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPlay Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreStateMachine::play index 0 Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreStateMachine::stop Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreStateMachine::play index undefined Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 08:27:09 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreStateMachine::startPlaybackTimer Aug 30 08:27:09 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:09 volumio-markus volumio[1195]: info: [1756535229950] ControllerWebradio::clearAddPlayTrack Aug 30 08:27:09 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 08:27:09 volumio-markus mpd[1441]: player: played "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" Aug 30 08:27:09 volumio-markus volumio[1195]: info: Aug 30 08:27:09 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:27:09 volumio-markus volumio[1195]: info: sendMpdCommand stop took 12 milliseconds Aug 30 08:27:09 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:27:09 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:27:09 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 08:27:09 volumio-markus volumio[1195]: info: Aug 30 08:27:09 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:27:09 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:27:09 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:27:09 volumio-markus volumio[1195]: info: Aug 30 08:27:09 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:27:09 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:27:09 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:27:09 volumio-markus volumio[1195]: info: Aug 30 08:27:09 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:27:09 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:27:09 volumio-markus volumio[1195]: info: sendMpdCommand status took 3 milliseconds Aug 30 08:27:09 volumio-markus volumio[1195]: info: sendMpdCommand clear took 3 milliseconds Aug 30 08:27:09 volumio-markus volumio[1195]: info: sendMpdCommand status took 2 milliseconds Aug 30 08:27:09 volumio-markus volumio[1195]: info: sendMpdCommand status took 2 milliseconds Aug 30 08:27:09 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:27:09 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:27:09 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand load "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" Aug 30 08:27:09 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:27:09 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:27:09 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:27:09 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:27:09 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:09 volumio-markus volumio[1195]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:27:09 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:27:09 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:09 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:27:09 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:09 volumio-markus volumio[1195]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:27:09 volumio-markus volumio[1195]: info: ------------------------------ 25ms Aug 30 08:27:09 volumio-markus volumio[1195]: info: ------------------------------ 25ms Aug 30 08:27:09 volumio-markus volumio[1195]: info: Aug 30 08:27:09 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:27:09 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:27:09 volumio-markus volumio[1195]: info: Aug 30 08:27:09 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:27:09 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:27:09 volumio-markus volumio[1195]: error: updateQueue error: null Aug 30 08:27:09 volumio-markus volumio[1195]: info: ------------------------------ 27ms Aug 30 08:27:09 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 27 milliseconds Aug 30 08:27:09 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:27:09 volumio-markus volumio[1195]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 30 08:27:09 volumio-markus volumio[1195]: info: ------------------------------ 33ms Aug 30 08:27:10 volumio-markus mpd[1441]: exception: No such playlist Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand add "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" Aug 30 08:27:10 volumio-markus volumio[1195]: error: updateQueue error: null Aug 30 08:27:10 volumio-markus volumio[1195]: error: updateQueue error: null Aug 30 08:27:10 volumio-markus volumio[1195]: info: ------------------------------ 381ms Aug 30 08:27:10 volumio-markus volumio[1195]: info: ------------------------------ 380ms Aug 30 08:27:10 volumio-markus volumio[1195]: info: Aug 30 08:27:10 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:27:10 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:27:10 volumio-markus volumio[1195]: info: sendMpdCommand add "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" took 1 milliseconds Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand play Aug 30 08:27:10 volumio-markus volumio[1195]: info: Aug 30 08:27:10 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:27:10 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:27:10 volumio-markus volumio[1195]: info: Aug 30 08:27:10 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:27:10 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:27:10 volumio-markus volumio[1195]: info: ------------------------------ 3ms Aug 30 08:27:10 volumio-markus volumio[1195]: info: sendMpdCommand play took 2 milliseconds Aug 30 08:27:10 volumio-markus volumio[1195]: info: ------------------------------ 2ms Aug 30 08:27:10 volumio-markus volumio[1195]: info: ------------------------------ 1ms Aug 30 08:27:10 volumio-markus volumio[1195]: info: Aug 30 08:27:10 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:27:10 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:27:10 volumio-markus mpd[1441]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Aug 30 08:27:10 volumio-markus mpd[1441]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Aug 30 08:27:10 volumio-markus mpd[1441]: player: problems opening audio device while playing "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" Aug 30 08:27:10 volumio-markus volumio[1195]: info: Aug 30 08:27:10 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:27:10 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:27:10 volumio-markus volumio[1195]: info: Aug 30 08:27:10 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:27:10 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:27:10 volumio-markus volumio[1195]: info: Aug 30 08:27:10 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:27:10 volumio-markus volumio[1195]: info: sendMpdCommand status took 8 milliseconds Aug 30 08:27:10 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:27:10 volumio-markus volumio[1195]: info: sendMpdCommand status took 8 milliseconds Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:27:10 volumio-markus volumio[1195]: info: Aug 30 08:27:10 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:27:10 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:27:10 volumio-markus volumio[1195]: info: Aug 30 08:27:10 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:27:10 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:27:10 volumio-markus volumio[1195]: info: sendMpdCommand status took 24 milliseconds Aug 30 08:27:10 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 18 milliseconds Aug 30 08:27:10 volumio-markus volumio[1195]: info: sendMpdCommand status took 19 milliseconds Aug 30 08:27:10 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 18 milliseconds Aug 30 08:27:10 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 30 08:27:10 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 30 08:27:10 volumio-markus volumio[1195]: info: sendMpdCommand status took 10 milliseconds Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:27:10 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: ------------------------------ 68ms Aug 30 08:27:10 volumio-markus volumio[1195]: info: ------------------------------ 68ms Aug 30 08:27:10 volumio-markus volumio[1195]: info: sendMpdCommand status took 64 milliseconds Aug 30 08:27:10 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 56 milliseconds Aug 30 08:27:10 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 54 milliseconds Aug 30 08:27:10 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 53 milliseconds Aug 30 08:27:10 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 52 milliseconds Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:27:10 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: ------------------------------ 132ms Aug 30 08:27:10 volumio-markus volumio[1195]: info: ------------------------------ 132ms Aug 30 08:27:10 volumio-markus volumio[1195]: info: ------------------------------ 118ms Aug 30 08:27:10 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 52 milliseconds Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:27:10 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:27:10 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:27:10 volumio-markus volumio[1195]: info: ------------------------------ 130ms Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPlay Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreStateMachine::play index undefined Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 08:27:18 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreStateMachine::startPlaybackTimer Aug 30 08:27:18 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:18 volumio-markus volumio[1195]: info: [1756535238125] ControllerWebradio::clearAddPlayTrack Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 08:27:18 volumio-markus mpd[1441]: player: played "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" Aug 30 08:27:18 volumio-markus volumio[1195]: info: Aug 30 08:27:18 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand stop took 6 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 08:27:18 volumio-markus volumio[1195]: info: Aug 30 08:27:18 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:27:18 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand status took 1 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand clear took 1 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand load "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" Aug 30 08:27:18 volumio-markus volumio[1195]: info: Aug 30 08:27:18 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:27:18 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:27:18 volumio-markus volumio[1195]: info: Aug 30 08:27:18 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:27:18 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:27:18 volumio-markus volumio[1195]: error: updateQueue error: null Aug 30 08:27:18 volumio-markus volumio[1195]: info: ------------------------------ 4ms Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:27:18 volumio-markus volumio[1195]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 30 08:27:18 volumio-markus volumio[1195]: info: ------------------------------ 7ms Aug 30 08:27:18 volumio-markus mpd[1441]: exception: No such playlist Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand add "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand status took 378 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand status took 377 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:27:18 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:27:18 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:27:18 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:18 volumio-markus volumio[1195]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:27:18 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:27:18 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:27:18 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:18 volumio-markus volumio[1195]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:27:18 volumio-markus volumio[1195]: info: ------------------------------ 394ms Aug 30 08:27:18 volumio-markus volumio[1195]: info: ------------------------------ 393ms Aug 30 08:27:18 volumio-markus volumio[1195]: info: Aug 30 08:27:18 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:27:18 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:27:18 volumio-markus volumio[1195]: info: Aug 30 08:27:18 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:27:18 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:27:18 volumio-markus volumio[1195]: info: Aug 30 08:27:18 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:27:18 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand add "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" took 22 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: info: ------------------------------ 4ms Aug 30 08:27:18 volumio-markus volumio[1195]: info: ------------------------------ 4ms Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand play Aug 30 08:27:18 volumio-markus volumio[1195]: info: Aug 30 08:27:18 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:27:18 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:27:18 volumio-markus volumio[1195]: info: Aug 30 08:27:18 volumio-markus volumio[1195]: ---------------------------- MPD announces system playlist update Aug 30 08:27:18 volumio-markus volumio[1195]: info: Ignoring MPD Status Update Aug 30 08:27:18 volumio-markus volumio[1195]: info: ------------------------------ 22ms Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand play took 21 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: info: ------------------------------ 21ms Aug 30 08:27:18 volumio-markus volumio[1195]: info: ------------------------------ 21ms Aug 30 08:27:18 volumio-markus volumio[1195]: info: Aug 30 08:27:18 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:27:18 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:27:18 volumio-markus mpd[1441]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Aug 30 08:27:18 volumio-markus mpd[1441]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Aug 30 08:27:18 volumio-markus mpd[1441]: player: problems opening audio device while playing "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" Aug 30 08:27:18 volumio-markus volumio[1195]: info: Aug 30 08:27:18 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:27:18 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:27:18 volumio-markus volumio[1195]: info: Aug 30 08:27:18 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:27:18 volumio-markus volumio[1195]: info: Aug 30 08:27:18 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:27:18 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand status took 8 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:27:18 volumio-markus volumio[1195]: info: Aug 30 08:27:18 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:27:18 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand status took 18 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:27:18 volumio-markus volumio[1195]: info: Aug 30 08:27:18 volumio-markus volumio[1195]: ---------------------------- MPD announces state update: player Aug 30 08:27:18 volumio-markus volumio[1195]: info: ControllerMpd::getState Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand status took 34 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 28 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand status took 27 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 27 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand status took 26 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 18 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand status took 16 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::parseState Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 08:27:18 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:27:18 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:27:18 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:27:18 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:27:18 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:27:18 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:27:18 volumio-markus volumio[1195]: info: ------------------------------ 76ms Aug 30 08:27:18 volumio-markus volumio[1195]: info: ------------------------------ 76ms Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand clearerror took 42 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 40 milliseconds Aug 30 08:27:18 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 39 milliseconds Aug 30 08:27:19 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 39 milliseconds Aug 30 08:27:19 volumio-markus volumio[1195]: info: sendMpdCommand playlistinfo took 39 milliseconds Aug 30 08:27:19 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:27:19 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:27:19 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:27:19 volumio-markus volumio[1195]: verbose: ControllerMpd::parseTrackInfo Aug 30 08:27:19 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:27:19 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:19 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:27:19 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:27:19 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:27:19 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:19 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:27:19 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:27:19 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:27:19 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:19 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:27:19 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:27:19 volumio-markus volumio[1195]: info: ControllerMpd::pushState Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreCommandRouter::servicePushState Aug 30 08:27:19 volumio-markus volumio[1195]: info: CorePlayQueue::getTrack 0 Aug 30 08:27:19 volumio-markus volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 30 08:27:19 volumio-markus volumio[1195]: verbose: CURRENT POSITION 0 Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreStateMachine::syncState stateService pause Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreStateMachine::pushState Aug 30 08:27:19 volumio-markus volumio[1195]: info: CoreCommandRouter::volumioPushState Aug 30 08:27:19 volumio-markus volumio[1195]: info: ------------------------------ 122ms Aug 30 08:27:19 volumio-markus volumio[1195]: info: ------------------------------ 122ms Aug 30 08:27:19 volumio-markus volumio[1195]: info: ------------------------------ 113ms Aug 30 08:27:19 volumio-markus volumio[1195]: info: ------------------------------ 104ms Aug 30 08:27:21 volumio-markus go-librespot[1567]: time="2025-08-30T08:27:21+02:00" level=trace msg="sent dealer ping" Aug 30 08:27:21 volumio-markus go-librespot[1567]: time="2025-08-30T08:27:21+02:00" level=trace msg="received dealer pong" Aug 30 08:27:21 volumio-markus systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Aug 30 08:27:21 volumio-markus dbus-daemon[663]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.36' (uid=0 pid=2720 comm="timedatectl show --property=NTPSynchronized --valu") Aug 30 08:27:21 volumio-markus systemd[1]: Starting systemd-timedated.service - Time & Date Service... Aug 30 08:27:21 volumio-markus dbus-daemon[663]: [system] Successfully activated service 'org.freedesktop.timedate1' Aug 30 08:27:21 volumio-markus systemd[1]: Started systemd-timedated.service - Time & Date Service. Aug 30 08:27:21 volumio-markus setdatetime-helper.sh[2719]: Time is not synchronized. Attempting to sync... Aug 30 08:27:21 volumio-markus sudo[2728]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sat, 30 Aug 2025 06:27:21 GMT#015' Aug 30 08:27:21 volumio-markus sudo[2728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 30 08:27:21 volumio-markus setdatetime-helper.sh[2729]: Sat Aug 30 08:27:21 CEST 2025 Aug 30 08:27:21 volumio-markus setdatetime-helper.sh[2719]: Time synchronized successfully. Aug 30 08:27:21 volumio-markus systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Aug 30 08:27:21 volumio-markus sudo[2728]: pam_unix(sudo:session): session closed for user root Aug 30 08:27:21 volumio-markus systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Aug 30 08:27:21 volumio-markus systemd-journald[328]: Time jumped backwards, rotating. Aug 30 08:27:28 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 30 08:27:28 volumio-markus volumio[1195]: info: TuneIn handleBrowseUri: tunein/local Aug 30 08:27:29 volumio-markus volumio[1195]: error: Cannot read properties of undefined (reading 'indexOf') Aug 30 08:27:29 volumio-markus volumio[1195]: error: Failed to execute browseSource: Error: Cannot list category items for local: TypeError: Cannot read properties of undefined (reading 'indexOf') Aug 30 08:27:31 volumio-markus volumio[1195]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 30 08:27:31 volumio-markus volumio[1195]: info: READ Aug 30 08:27:31 volumio-markus volumio[1195]: Use XMLElement.setAttribute instead Aug 30 08:27:31 volumio-markus volumio[1195]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 08:27:31 volumio-markus volumio[1195]: TypeError: children[i].attr(...).value is not a function Aug 30 08:27:31 volumio-markus volumio[1195]: at /volumio/app/plugins/music_service/webradio/index.js:286:47 Aug 30 08:27:31 volumio-markus volumio[1195]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Aug 30 08:27:31 volumio-markus volumio[1195]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 08:27:31 volumio-markus sudo[2761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-08-30 08:26' Aug 30 08:27:31 volumio-markus sudo[2761]: 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="1da895bfdbb6eb90d965df32a25a3568df7c84ed" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 28 19:10:18 UTC 2025" VOLUMIO_VERSION="4.003" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1bd40b20372a0e1ffe6ebc6d3df45570"